vm-test-run-nixos-test-niks3
checks.x86_64-linux.nixos-test-niks3-lix
· build #185
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8builder: starting vm9server: starting vm10builder # Disk image does not exist, creating the virtualisation disk image...11builder: QEMU running (pid 47)12server # Disk image does not exist, creating the virtualisation disk image...13server: QEMU running (pid 48)14server # Formatting '/build/vm-state-server/tmp.RXL5O9XWde', fmt=raw size=107374182415builder # Formatting '/build/vm-state-builder/tmp.gFlfxOGCD3', fmt=raw size=107374182416server # mke2fs 1.47.4 (6-Mar-2025)17builder # mke2fs 1.47.4 (6-Mar-2025)18server # Discarding device blocks: 0/262144 done19builder # Discarding device blocks: 0/262144 done20(finished: start all VMs, in 0.45 seconds)21server # Creating filesystem with 262144 4k blocks and 65536 inodes22server # Filesystem UUID: 4b0c22a8-7562-4956-ad7a-557d65704f9723server # Superblock backups stored on blocks:24builder # Creating filesystem with 262144 4k blocks and 65536 inodes25server: waiting for unit postgresql.service26builder # Filesystem UUID: b449b44d-1399-4e13-8b5f-30124f59106d27builder # Superblock backups stored on blocks:28server: waiting for the VM to finish booting29builder # 32768, 98304, 163840, 22937630server # 32768, 98304, 163840, 22937631builder # 32server # 33builder # Allocating group tables: 0/8 done34server # Allocating group tables: 0/8 done35builder # Writing inode tables: 0/8 done36server # Writing inode tables: 0/8 done37builder # Creating journal (8192 blocks): done38server # Creating journal (8192 blocks): done39builder # Writing superblocks and filesystem accounting information: 0/8 done40server # Writing superblocks and filesystem accounting information: 0/8 done41builder # 42server # 43builder # Virtualisation disk image created.44server # Virtualisation disk image created.45builder # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)46server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)47builder # 48builder # 49builder # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0050server # 51server # 52server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0053builder # Press Ctrl-B to configure iPXE (PCI 00:02.0)...54builder # 55builder # 56builder # 57builder # 58builder # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0059server # Press Ctrl-B to configure iPXE (PCI 00:02.0)...60server # 61server # 62server # 63server # 64server # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0065builder # Press Ctrl-B to configure iPXE (PCI 00:08.0)...66builder # 67builder # 68server # Press Ctrl-B to configure iPXE (PCI 00:08.0)...69server # 70server # 71builder # Booting from ROM...72builder # Probing EDD (edd=off to disable)... ok73server # Booting from ROM...74builder # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 202675builder # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/5h2chz07g5ynmnnz485l72mlqsfz71ix-nixos-system-builder-test/init regInfo=/nix/store/mjr6jj7vn5cy75z0vw0da01hrdzjyapf-closure-info/registration console=ttyS0,115200n8 console=tty076builder # [ 0.000000] BIOS-provided physical RAM map:77builder # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable78builder # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved79builder # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved80builder # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable81builder # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved82builder # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved83builder # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved84builder # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved85builder # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved86builder # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved87builder # [ 0.000000] NX (Execute Disable) protection: active88server # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 202689builder # [ 0.000000] APIC: Static calls initialized90builder # [ 0.000000] SMBIOS 2.8 present.91builder # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201492builder # [ 0.000000] DMI: Memory slots populated: 1/193builder # [ 0.000000] Hypervisor detected: KVM94builder # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000095server # [ 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/rlvrl3y8sqjv506lqpf227b08jmkcx9l-nixos-system-server-test/init regInfo=/nix/store/bdvp2irlyfdvl0135hdz7bvs5b2l10i8-closure-info/registration console=ttyS0,115200n8 console=tty096builder # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0097server # [ 0.000000] BIOS-provided physical RAM map:98builder # [ 0.000001] kvm-clock: using sched offset of 547082265 cycles99server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable100builder # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns101server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved102builder # [ 0.000005] tsc: Detected 2400.012 MHz processor103server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved104builder # [ 0.000813] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000105server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable106builder # [ 0.000840] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs107server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved108builder # [ 0.000843] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT109server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved110builder # [ 0.002741] found SMP MP-table at [mem 0x000f5450-0x000f545f]111builder # [ 0.002754] Using GB pages for direct mapping112server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved113builder # [ 0.002843] RAMDISK: [mem 0x3e36b000-0x3ffcffff]114server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved115builder # [ 0.002851] ACPI: Early table checksum verification disabled116builder # [ 0.002854] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )117server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved118server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved119builder # [ 0.002858] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)120server # [ 0.000000] NX (Execute Disable) protection: active121server # [ 0.000000] APIC: Static calls initialized122builder # [ 0.002862] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)123server # [ 0.000000] SMBIOS 2.8 present.124builder # [ 0.002870] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)125builder # [ 0.002872] ACPI: FACS 0x000000003FFE0000 000040126server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014127server # [ 0.000000] DMI: Memory slots populated: 1/1128builder # [ 0.002873] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)129server # [ 0.000000] Hypervisor detected: KVM130server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000131builder # [ 0.002875] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)132server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00133server # [ 0.000000] kvm-clock: using sched offset of 555706892 cycles134builder # [ 0.002876] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)135builder # [ 0.002878] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)136server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns137server # [ 0.000005] tsc: Detected 2400.012 MHz processor138builder # [ 0.002879] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]139server # [ 0.000812] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000140builder # [ 0.002880] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]141server # [ 0.000839] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs142builder # [ 0.002881] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]143server # [ 0.000842] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT144builder # [ 0.002881] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]145server # [ 0.002739] found SMP MP-table at [mem 0x000f5450-0x000f545f]146builder # [ 0.002882] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]147server # [ 0.002752] Using GB pages for direct mapping148server # [ 0.002806] RAMDISK: [mem 0x3e369000-0x3ffcffff]149builder # [ 0.002882] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]150server # [ 0.002815] ACPI: Early table checksum verification disabled151builder # [ 0.002883] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]152server # [ 0.002818] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )153builder # [ 0.003110] No NUMA configuration found154server # [ 0.002822] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)155builder # [ 0.003111] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]156builder # [ 0.003114] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]157server # [ 0.002826] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)158builder # [ 0.005499] Zone ranges:159builder # [ 0.005500] DMA [mem 0x0000000000001000-0x0000000000ffffff]160server # [ 0.002834] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)161server # [ 0.002836] ACPI: FACS 0x000000003FFE0000 000040162builder # [ 0.005502] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]163builder # [ 0.005503] Normal empty164builder # [ 0.005504] Device empty165server # [ 0.002838] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)166builder # [ 0.005505] Movable zone start for each node167builder # [ 0.005506] Early memory node ranges168server # [ 0.002839] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)169builder # [ 0.005506] node 0: [mem 0x0000000000001000-0x000000000009efff]170server # [ 0.002841] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)171builder # [ 0.005507] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]172server # [ 0.002843] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)173builder # [ 0.005508] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]174builder # [ 0.005529] On node 0, zone DMA: 1 pages in unavailable ranges175server # [ 0.002844] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]176builder # [ 0.005806] On node 0, zone DMA: 97 pages in unavailable ranges177server # [ 0.002845] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]178builder # [ 0.024602] On node 0, zone DMA32: 40 pages in unavailable ranges179server # [ 0.002845] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]180builder # [ 0.025059] ACPI: PM-Timer IO Port: 0x608181server # [ 0.002846] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]182builder # [ 0.025070] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])183server # [ 0.002846] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]184builder # [ 0.025101] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23185server # [ 0.002847] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]186builder # [ 0.025104] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)187server # [ 0.002847] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]188builder # [ 0.025106] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)189server # [ 0.003074] No NUMA configuration found190builder # [ 0.025107] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)191server # [ 0.003075] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]192builder # [ 0.025108] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)193server # [ 0.003077] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]194server # [ 0.005456] Zone ranges:195builder # [ 0.025109] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)196server # [ 0.005457] DMA [mem 0x0000000000001000-0x0000000000ffffff]197builder # [ 0.025111] ACPI: Using ACPI (MADT) for SMP configuration information198server # [ 0.005459] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]199builder # [ 0.025112] ACPI: HPET id: 0x8086a201 base: 0xfed00000200server # [ 0.005460] Normal empty201server # [ 0.005461] Device empty202builder # [ 0.025116] TSC deadline timer available203server # [ 0.005462] Movable zone start for each node204builder # [ 0.025120] CPU topo: Max. logical packages: 1205server # [ 0.005462] Early memory node ranges206builder # [ 0.025121] CPU topo: Max. logical dies: 1207server # [ 0.005463] node 0: [mem 0x0000000000001000-0x000000000009efff]208builder # [ 0.025122] CPU topo: Max. dies per package: 1209builder # [ 0.025125] CPU topo: Max. threads per core: 1210server # [ 0.005464] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]211builder # [ 0.025126] CPU topo: Num. cores per package: 1212server # [ 0.005465] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]213builder # [ 0.025126] CPU topo: Num. threads per package: 1214server # [ 0.005486] On node 0, zone DMA: 1 pages in unavailable ranges215builder # [ 0.025126] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs216server # [ 0.005763] On node 0, zone DMA: 97 pages in unavailable ranges217builder # [ 0.025145] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()218server # [ 0.024543] On node 0, zone DMA32: 40 pages in unavailable ranges219server # [ 0.025011] ACPI: PM-Timer IO Port: 0x608220builder # [ 0.025178] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]221server # [ 0.025026] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])222builder # [ 0.025180] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]223server # [ 0.025061] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23224builder # [ 0.025181] [mem 0x40000000-0xafffffff] available for PCI devices225server # [ 0.025064] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)226builder # [ 0.025183] Booting paravirtualized kernel on KVM227server # [ 0.025066] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)228builder # [ 0.025186] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns229server # [ 0.025067] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)230server # [ 0.025068] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)231builder # [ 0.029669] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1232server # [ 0.025069] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)233builder # [ 0.031969] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152234builder # [ 0.032013] kvm-guest: PV spinlocks disabled, single CPU235server # [ 0.025072] ACPI: Using ACPI (MADT) for SMP configuration information236server # [ 0.025073] ACPI: HPET id: 0x8086a201 base: 0xfed00000237server # [ 0.025077] TSC deadline timer available238server # [ 0.025081] CPU topo: Max. logical packages: 1239server # [ 0.025082] CPU topo: Max. logical dies: 1240server # [ 0.025082] CPU topo: Max. dies per package: 1241server # [ 0.025086] CPU topo: Max. threads per core: 1242builder # [ 0.032015] 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/5h2chz07g5ynmnnz485l72mlqsfz71ix-nixos-system-builder-test/init regInfo=/nix/store/mjr6jj7vn5cy75z0vw0da01hrdzjyapf-closure-info/registration console=ttyS0,115200n8 console=tty0243server # [ 0.025086] CPU topo: Num. cores per package: 1244server # [ 0.025087] CPU topo: Num. threads per package: 1245server # [ 0.025087] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs246builder # [ 0.032115] Unknown kernel command line parameters "regInfo=/nix/store/mjr6jj7vn5cy75z0vw0da01hrdzjyapf-closure-info/registration", will be passed to user space.247builder # [ 0.032129] random: crng init done248server # [ 0.025112] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()249builder # [ 0.032130] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes250server # [ 0.025155] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]251server # [ 0.025156] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]252builder # [ 0.033522] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)253server # [ 0.025158] [mem 0x40000000-0xafffffff] available for PCI devices254builder # [ 0.033535] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)255server # [ 0.025160] Booting paravirtualized kernel on KVM256builder # [ 0.033568] Fallback order for Node 0: 0257builder # [ 0.033571] Built 1 zonelists, mobility grouping on. Total pages: 262006258server # [ 0.025164] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns259builder # [ 0.033572] Policy zone: DMA32260server # [ 0.029625] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1261builder # [ 0.036167] mem auto-init: stack:all(zero), heap alloc:on, heap free:off262server # [ 0.031926] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152263builder # [ 0.038948] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1264builder # [ 0.041319] allocated 2097152 bytes of page_ext265server # [ 0.031980] kvm-guest: PV spinlocks disabled, single CPU266builder # [ 0.051770] ftrace: allocating 48732 entries in 192 pages267builder # [ 0.051772] ftrace: allocated 192 pages with 2 groups268builder # [ 0.052659] Dynamic Preempt: lazy269builder # [ 0.052829] rcu: Preemptible hierarchical RCU implementation.270builder # [ 0.052830] rcu: RCU event tracing is enabled.271server # [ 0.031982] 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/rlvrl3y8sqjv506lqpf227b08jmkcx9l-nixos-system-server-test/init regInfo=/nix/store/bdvp2irlyfdvl0135hdz7bvs5b2l10i8-closure-info/registration console=ttyS0,115200n8 console=tty0272builder # [ 0.052830] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.273builder # [ 0.052832] Trampoline variant of Tasks RCU enabled.274builder # [ 0.052833] Rude variant of Tasks RCU enabled.275server # [ 0.032081] Unknown kernel command line parameters "regInfo=/nix/store/bdvp2irlyfdvl0135hdz7bvs5b2l10i8-closure-info/registration", will be passed to user space.276builder # [ 0.052833] Tracing variant of Tasks RCU enabled.277server # [ 0.032095] random: crng init done278builder # [ 0.052834] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.279server # [ 0.032096] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes280builder # [ 0.052834] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1281server # [ 0.033493] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)282builder # [ 0.052852] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.283server # [ 0.033507] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)284server # [ 0.033539] Fallback order for Node 0: 0285builder # [ 0.052854] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.286server # [ 0.033542] Built 1 zonelists, mobility grouping on. Total pages: 262006287server # [ 0.033543] Policy zone: DMA32288builder # [ 0.052855] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.289server # [ 0.036143] mem auto-init: stack:all(zero), heap alloc:on, heap free:off290builder # [ 0.057311] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16291server # [ 0.038957] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1292builder # [ 0.057588] rcu: srcu_init: Setting srcu_struct sizes based on contention.293server # [ 0.041340] allocated 2097152 bytes of page_ext294server # [ 0.051778] ftrace: allocating 48732 entries in 192 pages295builder # [ 0.057595] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns296server # [ 0.051780] ftrace: allocated 192 pages with 2 groups297server # [ 0.052698] Dynamic Preempt: lazy298server # [ 0.052868] rcu: Preemptible hierarchical RCU implementation.299builder # [ 0.057709] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)300server # [ 0.052869] rcu: RCU event tracing is enabled.301builder # [ 0.061253] Console: colour VGA+ 80x25302builder # [ 0.061256] printk: legacy console [tty0] enabled303server # [ 0.052870] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.304builder # [ 0.090235] printk: legacy console [ttyS0] enabled305server # [ 0.052871] Trampoline variant of Tasks RCU enabled.306server # [ 0.052872] Rude variant of Tasks RCU enabled.307builder # [ 0.188719] ACPI: Core revision 20250807308server # [ 0.052872] Tracing variant of Tasks RCU enabled.309server # [ 0.052873] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.310builder # [ 0.189599] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns311server # [ 0.052874] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1312builder # [ 0.191127] APIC: Switch to symmetric I/O mode setup313server # [ 0.052896] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.314builder # [ 0.192097] x2apic enabled315server # [ 0.052898] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.316builder # [ 0.192889] APIC: Switched APIC routing to: physical x2apic317server # [ 0.052899] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.318server # [ 0.057336] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16319builder # [ 0.194753] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1320server # [ 0.057626] rcu: srcu_init: Setting srcu_struct sizes based on contention.321server # [ 0.057637] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns322builder # [ 0.195750] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns323server # [ 0.057755] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)324builder # [ 0.197405] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)325server # [ 0.061318] Console: colour VGA+ 80x25326server # [ 0.061321] printk: legacy console [tty0] enabled327builder # [ 0.198490] x86/cpu: User Mode Instruction Prevention (UMIP) activated328server # [ 0.090405] printk: legacy console [ttyS0] enabled329server # [ 0.187125] ACPI: Core revision 20250807330builder # [ 0.200406] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127331builder # [ 0.201361] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0332server # [ 0.187952] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns333server # [ 0.189437] APIC: Switch to symmetric I/O mode setup334builder # [ 0.202197] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto335server # [ 0.190364] x2apic enabled336server # [ 0.191085] APIC: Switched APIC routing to: physical x2apic337builder # [ 0.203401] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl338builder # [ 0.205401] Transient Scheduler Attacks: Vulnerable: No microcode339server # [ 0.192832] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1340builder # [ 0.206401] Spectre V2 : Mitigation: Enhanced / Automatic IBRS341builder # [ 0.207406] Speculative Return Stack Overflow: Mitigation: Safe RET342server # [ 0.193771] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns343server # [ 0.195336] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)344server # [ 0.197422] x86/cpu: User Mode Instruction Prevention (UMIP) activated345server # [ 0.198485] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127346builder # [ 0.208389] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization347server # [ 0.199332] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0348builder # [ 0.209409] Spectre V2 : Enabling IBPB for BPF349builder # [ 0.210142] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier350server # [ 0.200337] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto351builder # [ 0.211402] active return thunk: srso_alias_return_thunk352server # [ 0.201333] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl353builder # [ 0.212415] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'354server # [ 0.203333] Transient Scheduler Attacks: Vulnerable: No microcode355builder # [ 0.213401] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'356server # [ 0.204332] Spectre V2 : Mitigation: Enhanced / Automatic IBRS357server # [ 0.205332] Speculative Return Stack Overflow: Mitigation: Safe RET358builder # [ 0.214401] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'359builder # [ 0.215401] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'360builder # [ 0.216401] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'361builder # [ 0.217401] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'362server # [ 0.206332] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization363builder # [ 0.218401] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'364server # [ 0.208340] Spectre V2 : Enabling IBPB for BPF365builder # [ 0.219401] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256366server # [ 0.209839] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier367builder # [ 0.221168] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64368server # [ 0.210333] active return thunk: srso_alias_return_thunk369builder # [ 0.222169] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512370server # [ 0.211959] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'371builder # [ 0.223191] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024372server # [ 0.212332] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'373builder # [ 0.224168] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8374server # [ 0.214056] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'375server # [ 0.215056] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'376builder # [ 0.225128] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.377server # [ 0.215332] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'378server # [ 0.216332] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'379server # [ 0.217332] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'380server # [ 0.218332] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256381server # [ 0.220033] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64382server # [ 0.221037] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512383server # [ 0.221332] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024384server # [ 0.222332] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8385server # [ 0.223332] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.386builder # [ 0.261096] Freeing SMP alternatives memory: 44K387builder # [ 0.261405] pid_max: default: 32768 minimum: 301388builder # [ 0.262510] LSM: initializing lsm=capability,landlock,yama,bpf,ima389builder # [ 0.263503] landlock: Up and running.390builder # [ 0.264402] Yama: becoming mindful.391builder # [ 0.265548] LSM support for eBPF active392builder # [ 0.266317] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)393builder # [ 0.267423] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)394server # [ 0.258717] Freeing SMP alternatives memory: 44K395server # [ 0.259337] pid_max: default: 32768 minimum: 301396builder # [ 0.270046] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)397server # [ 0.260395] LSM: initializing lsm=capability,landlock,yama,bpf,ima398server # [ 0.261480] landlock: Up and running.399server # [ 0.262123] Yama: becoming mindful.400builder # [ 0.270964] Performance Events: Fam17h+ core perfctr, AMD PMU driver.401builder # [ 0.271406] ... version: 2402server # [ 0.262976] LSM support for eBPF active403builder # [ 0.272137] ... bit width: 48404server # [ 0.263445] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)405builder # [ 0.272404] ... generic counters: 6406builder # [ 0.273108] ... generic bitmap: 000000000000003f407server # [ 0.264353] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)408builder # [ 0.273408] ... fixed-purpose counters: 0409builder # [ 0.274116] ... fixed-purpose bitmap: 0000000000000000410builder # [ 0.274403] ... value mask: 0000ffffffffffff411server # [ 0.267460] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)412builder # [ 0.275350] ... max period: 00007fffffffffff413builder # [ 0.276118] ... global_ctrl mask: 000000000000003f414server # [ 0.268900] Performance Events: Fam17h+ core perfctr, AMD PMU driver.415builder # [ 0.276515] signal: max sigframe size: 3376416server # [ 0.269341] ... version: 2417server # [ 0.270053] ... bit width: 48418builder # [ 0.277337] rcu: Hierarchical SRCU implementation.419server # [ 0.270335] ... generic counters: 6420builder # [ 0.278014] rcu: Max phase no-delay instances is 400.421server # [ 0.271043] ... generic bitmap: 000000000000003f422server # [ 0.271335] ... fixed-purpose counters: 0423server # [ 0.272069] ... fixed-purpose bitmap: 0000000000000000424server # [ 0.272335] ... value mask: 0000ffffffffffff425server # [ 0.273264] ... max period: 00007fffffffffff426server # [ 0.274045] ... global_ctrl mask: 000000000000003f427builder # [ 0.283070] smp: Bringing up secondary CPUs ...428server # [ 0.274439] signal: max sigframe size: 3376429builder # [ 0.283418] smp: Brought up 1 node, 1 CPU430server # [ 0.275209] rcu: Hierarchical SRCU implementation.431builder # [ 0.284132] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)432server # [ 0.275912] rcu: Max phase no-delay instances is 400.433builder # [ 0.284619] Memory: 941048K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 99600K reserved, 0K cma-reserved)434builder # [ 0.285656] devtmpfs: initialized435builder # [ 0.286477] x86/mm: Memory block size: 128MB436server # [ 0.280906] smp: Bringing up secondary CPUs ...437server # [ 0.281349] smp: Brought up 1 node, 1 CPU438builder # [ 0.288248] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)439server # [ 0.282020] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)440builder # [ 0.289389] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).441builder # [ 0.290506] pinctrl core: initialized pinctrl subsystem442server # [ 0.282441] Memory: 941040K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 99608K reserved, 0K cma-reserved)443builder # [ 0.291684] PM: RTC time: 08:16:52, date: 2026-09-08444server # [ 0.283616] devtmpfs: initialized445server # [ 0.284438] x86/mm: Memory block size: 128MB446server # [ 0.286146] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)447builder # [ 0.295160] NET: Registered PF_NETLINK/PF_ROUTE protocol family448server # [ 0.287274] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).449builder # [ 0.295779] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations450server # [ 0.288435] pinctrl core: initialized pinctrl subsystem451builder # [ 0.296423] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations452server # [ 0.289542] PM: RTC time: 08:16:52, date: 2026-09-08453builder # [ 0.297559] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations454builder # [ 0.298413] audit: initializing netlink subsys (disabled)455builder # [ 0.299622] thermal_sys: Registered thermal governor 'fair_share'456server # [ 0.293039] NET: Registered PF_NETLINK/PF_ROUTE protocol family457builder # [ 0.299625] thermal_sys: Registered thermal governor 'bang_bang'458server # [ 0.293694] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations459builder # [ 0.300405] thermal_sys: Registered thermal governor 'step_wise'460builder # [ 0.301398] thermal_sys: Registered thermal governor 'user_space'461server # [ 0.294352] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations462builder # [ 0.301407] audit: type=2000 audit(1788855413.611:1): state=initialized audit_enabled=0 res=1463server # [ 0.295477] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations464builder # [ 0.304405] thermal_sys: Registered thermal governor 'power_allocator'465server # [ 0.296344] audit: initializing netlink subsys (disabled)466builder # [ 0.304429] cpuidle: using governor menu467server # [ 0.297462] thermal_sys: Registered thermal governor 'fair_share'468builder # [ 0.306657] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5469server # [ 0.297464] thermal_sys: Registered thermal governor 'bang_bang'470server # [ 0.298335] thermal_sys: Registered thermal governor 'step_wise'471builder # [ 0.307645] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]472server # [ 0.299276] thermal_sys: Registered thermal governor 'user_space'473builder # [ 0.308406] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry474server # [ 0.300106] audit: type=2000 audit(1788855413.623:1): state=initialized audit_enabled=0 res=1475builder # [ 0.309423] PCI: Using configuration type 1 for base access476server # [ 0.301337] thermal_sys: Registered thermal governor 'power_allocator'477server # [ 0.301351] cpuidle: using governor menu478builder # [ 0.310490] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.479server # [ 0.303558] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5480server # [ 0.304579] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]481server # [ 0.305337] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry482server # [ 0.306346] PCI: Using configuration type 1 for base access483builder # [ 0.315651] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages484builder # [ 0.316405] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page485server # [ 0.307448] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.486builder # [ 0.321404] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages487server # [ 0.312571] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages488builder # [ 0.322403] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page489server # [ 0.313336] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page490server # [ 0.318336] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages491server # [ 0.319335] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page492builder # [ 0.332804] ACPI: Added _OSI(Module Device)493builder # [ 0.333405] ACPI: Added _OSI(Processor Device)494builder # [ 0.334152] ACPI: Added _OSI(Processor Aggregator Device)495server # [ 0.329721] ACPI: Added _OSI(Module Device)496server # [ 0.330337] ACPI: Added _OSI(Processor Device)497server # [ 0.331097] ACPI: Added _OSI(Processor Aggregator Device)498builder # [ 0.342692] ACPI: 1 ACPI AML tables successfully acquired and loaded499builder # [ 0.346835] ACPI: Interpreter enabled500builder # [ 0.347420] ACPI: PM: (supports S0 S3 S4 S5)501builder # [ 0.348194] ACPI: Using IOAPIC for interrupt routing502server # [ 0.339950] ACPI: 1 ACPI AML tables successfully acquired and loaded503builder # [ 0.348472] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug504builder # [ 0.349404] PCI: Using E820 reservations for host bridge windows505server # [ 0.343741] ACPI: Interpreter enabled506builder # [ 0.352543] ACPI: Enabled 2 GPEs in block 00 to 3F507server # [ 0.344370] ACPI: PM: (supports S0 S3 S4 S5)508server # [ 0.345090] ACPI: Using IOAPIC for interrupt routing509server # [ 0.345376] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug510server # [ 0.346336] PCI: Using E820 reservations for host bridge windows511server # [ 0.349483] ACPI: Enabled 2 GPEs in block 00 to 3F512builder # [ 0.361193] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])513builder # [ 0.362226] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]514builder # [ 0.364291] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]515builder # [ 0.365504] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]516builder # [ 0.366764] PCI host bridge to bus 0000:00517server # [ 0.358136] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])518builder # [ 0.367407] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]519server # [ 0.359115] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]520builder # [ 0.368404] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]521builder # [ 0.369403] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]522server # [ 0.360891] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]523builder # [ 0.370403] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]524server # [ 0.361459] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]525builder # [ 0.371403] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]526server # [ 0.362703] PCI host bridge to bus 0000:00527builder # [ 0.372404] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]528server # [ 0.363339] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]529builder # [ 0.373404] pci_bus 0000:00: root bus resource [bus 00-ff]530server # [ 0.364335] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]531server # [ 0.365335] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]532builder # [ 0.374427] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint533server # [ 0.366336] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]534builder # [ 0.375840] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint535server # [ 0.367335] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]536server # [ 0.368335] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]537server # [ 0.369347] pci_bus 0000:00: root bus resource [bus 00-ff]538server # [ 0.370354] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint539server # [ 0.371768] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint540builder # [ 0.378515] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]541builder # [ 0.379416] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]542builder # [ 0.380425] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]543builder # [ 0.381721] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]544server # [ 0.374394] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]545builder # [ 0.383128] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint546server # [ 0.375412] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]547server # [ 0.376396] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]548builder # [ 0.386436] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]549server # [ 0.377639] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]550builder # [ 0.387275] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]551builder # [ 0.388173] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]552server # [ 0.379042] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint553builder # [ 0.388410] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]554builder # [ 0.390415] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint555server # [ 0.382342] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]556server # [ 0.383220] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]557server # [ 0.384114] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]558builder # [ 0.393404] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]559server # [ 0.385195] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]560builder # [ 0.394299] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]561builder # [ 0.395153] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]562server # [ 0.386923] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint563builder # [ 0.395944] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint564server # [ 0.388342] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]565server # [ 0.389260] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]566server # [ 0.390089] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]567builder # [ 0.399127] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]568builder # [ 0.399411] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]569server # [ 0.390913] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint570builder # [ 0.400414] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]571builder # [ 0.401945] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint572server # [ 0.393342] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]573builder # [ 0.403435] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]574server # [ 0.394341] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]575builder # [ 0.404303] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]576server # [ 0.395305] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]577builder # [ 0.405230] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]578server # [ 0.396761] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint579builder # [ 0.406946] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint580server # [ 0.398342] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]581server # [ 0.399220] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]582builder # [ 0.408410] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]583server # [ 0.400124] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]584builder # [ 0.409269] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]585builder # [ 0.410225] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]586server # [ 0.401722] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint587builder # [ 0.411893] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint588server # [ 0.404343] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]589server # [ 0.405233] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]590server # [ 0.406131] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]591builder # [ 0.414436] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]592builder # [ 0.415350] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]593server # [ 0.407820] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint594builder # [ 0.416174] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]595builder # [ 0.417023] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint596server # [ 0.410344] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]597server # [ 0.411342] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]598builder # [ 0.418433] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]599server # [ 0.412327] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]600builder # [ 0.419280] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]601server # [ 0.413940] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint602builder # [ 0.420156] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]603builder # [ 0.421411] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]604server # [ 0.415342] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]605server # [ 0.416214] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]606builder # [ 0.422941] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint607server # [ 0.417057] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]608builder # [ 0.424404] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]609server # [ 0.417341] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]610builder # [ 0.425408] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]611server # [ 0.418887] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint612builder # [ 0.426957] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint613server # [ 0.420328] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]614server # [ 0.421304] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]615builder # [ 0.428863] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]616server # [ 0.422674] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint617builder # [ 0.430088] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]618builder # [ 0.430451] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]619server # [ 0.424342] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]620builder # [ 0.431949] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint621server # [ 0.425213] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]622server # [ 0.426357] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]623builder # [ 0.433435] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]624builder # [ 0.434328] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]625server # [ 0.427893] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint626builder # [ 0.435169] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]627server # [ 0.429342] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]628server # [ 0.430206] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]629builder # [ 0.435986] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint630server # [ 0.431087] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]631builder # [ 0.438033] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]632server # [ 0.431912] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint633builder # [ 0.438600] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint634builder # [ 0.439986] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]635server # [ 0.432991] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]636server # [ 0.434220] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint637server # [ 0.436032] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]638builder # [ 0.440602] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint639builder # [ 0.443906] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]640server # [ 0.436531] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint641builder # [ 0.444678] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint642server # [ 0.439364] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]643builder # [ 0.446239] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]644server # [ 0.440568] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint645builder # [ 0.447472] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint646server # [ 0.442196] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]647builder # [ 0.448687] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO648server # [ 0.443324] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint649builder # [ 0.449690] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint650server # [ 0.444642] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO651builder # [ 0.452032] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]652server # [ 0.445589] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint653builder # [ 0.452410] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]654builder # [ 0.453760] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint655server # [ 0.447377] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]656server # [ 0.448343] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]657builder # [ 0.455118] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]658server # [ 0.449588] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint659server # [ 0.451079] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]660builder # [ 0.458468] ACPI: PCI: Interrupt link LNKA configured for IRQ 10661builder # [ 0.459510] ACPI: PCI: Interrupt link LNKB configured for IRQ 10662builder # [ 0.460505] ACPI: PCI: Interrupt link LNKC configured for IRQ 11663server # [ 0.454360] ACPI: PCI: Interrupt link LNKA configured for IRQ 10664builder # [ 0.461501] ACPI: PCI: Interrupt link LNKD configured for IRQ 11665server # [ 0.455444] ACPI: PCI: Interrupt link LNKB configured for IRQ 10666builder # [ 0.462509] ACPI: PCI: Interrupt link LNKE configured for IRQ 10667server # [ 0.456449] ACPI: PCI: Interrupt link LNKC configured for IRQ 11668builder # [ 0.463504] ACPI: PCI: Interrupt link LNKF configured for IRQ 10669server # [ 0.457442] ACPI: PCI: Interrupt link LNKD configured for IRQ 11670builder # [ 0.464503] ACPI: PCI: Interrupt link LNKG configured for IRQ 11671server # [ 0.458436] ACPI: PCI: Interrupt link LNKE configured for IRQ 10672builder # [ 0.465503] ACPI: PCI: Interrupt link LNKH configured for IRQ 11673server # [ 0.459434] ACPI: PCI: Interrupt link LNKF configured for IRQ 10674builder # [ 0.466439] ACPI: PCI: Interrupt link GSIA configured for IRQ 16675server # [ 0.460440] ACPI: PCI: Interrupt link LNKG configured for IRQ 11676builder # [ 0.467415] ACPI: PCI: Interrupt link GSIB configured for IRQ 17677server # [ 0.461434] ACPI: PCI: Interrupt link LNKH configured for IRQ 11678builder # [ 0.468394] ACPI: PCI: Interrupt link GSIC configured for IRQ 18679server # [ 0.462374] ACPI: PCI: Interrupt link GSIA configured for IRQ 16680builder # [ 0.469394] ACPI: PCI: Interrupt link GSID configured for IRQ 19681server # [ 0.463326] ACPI: PCI: Interrupt link GSIB configured for IRQ 17682builder # [ 0.470416] ACPI: PCI: Interrupt link GSIE configured for IRQ 20683server # [ 0.464073] ACPI: PCI: Interrupt link GSIC configured for IRQ 18684builder # [ 0.471419] ACPI: PCI: Interrupt link GSIF configured for IRQ 21685server # [ 0.464350] ACPI: PCI: Interrupt link GSID configured for IRQ 19686builder # [ 0.472416] ACPI: PCI: Interrupt link GSIG configured for IRQ 22687server # [ 0.465302] ACPI: PCI: Interrupt link GSIE configured for IRQ 20688builder # [ 0.473421] ACPI: PCI: Interrupt link GSIH configured for IRQ 23689server # [ 0.466085] ACPI: PCI: Interrupt link GSIF configured for IRQ 21690server # [ 0.466348] ACPI: PCI: Interrupt link GSIG configured for IRQ 22691builder # [ 0.475505] iommu: Default domain type: Translated692server # [ 0.467349] ACPI: PCI: Interrupt link GSIH configured for IRQ 23693builder # [ 0.476326] iommu: DMA domain TLB invalidation policy: lazy mode694builder # [ 0.477504] ACPI: bus type USB registered695server # [ 0.469364] iommu: Default domain type: Translated696builder # [ 0.478306] usbcore: registered new interface driver usbfs697server # [ 0.470196] iommu: DMA domain TLB invalidation policy: lazy mode698builder # [ 0.479145] usbcore: registered new interface driver hub699server # [ 0.471408] ACPI: bus type USB registered700builder # [ 0.479423] usbcore: registered new device driver usb701server # [ 0.472167] usbcore: registered new interface driver usbfs702server # [ 0.473052] usbcore: registered new interface driver hub703builder # [ 0.481321] NetLabel: Initializing704server # [ 0.473361] usbcore: registered new device driver usb705builder # [ 0.481847] NetLabel: domain hash size = 128706builder # [ 0.482403] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO707server # [ 0.475316] NetLabel: Initializing708builder # [ 0.483479] NetLabel: unlabeled traffic allowed by default709server # [ 0.475806] NetLabel: domain hash size = 128710builder # [ 0.484414] PCI: Using ACPI for IRQ routing711server # [ 0.476335] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO712server # [ 0.477323] NetLabel: unlabeled traffic allowed by default713server # [ 0.478087] PCI: Using ACPI for IRQ routing714builder # [ 0.528999] pci 0000:00:01.0: vgaarb: setting as boot VGA device715builder # [ 0.529400] pci 0000:00:01.0: vgaarb: bridge control possible716server # [ 0.521178] pci 0000:00:01.0: vgaarb: setting as boot VGA device717server # [ 0.521331] pci 0000:00:01.0: vgaarb: bridge control possible718builder # [ 0.529400] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none719builder # [ 0.529408] vgaarb: loaded720server # [ 0.521331] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none721server # [ 0.521340] vgaarb: loaded722builder # [ 0.530165] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0723builder # [ 0.530405] hpet0: 3 comparators, 64-bit 100.000000 MHz counter724server # [ 0.522107] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0725server # [ 0.522336] hpet0: 3 comparators, 64-bit 100.000000 MHz counter726server # [ 0.527426] clocksource: Switched to clocksource kvm-clock727builder # [ 0.536493] clocksource: Switched to clocksource kvm-clock728builder # [ 0.540124] VFS: Disk quotas dquot_6.6.0729server # [ 0.531123] VFS: Disk quotas dquot_6.6.0730server # [ 0.531841] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)731builder # [ 0.540867] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)732server # [ 0.533197] pnp: PnP ACPI init733builder # [ 0.542273] pnp: PnP ACPI init734server # [ 0.534008] ACPI: IRQ 4 override to edge(!), high(!)735builder # [ 0.543116] ACPI: IRQ 4 override to edge(!), high(!)736server # [ 0.534991] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved737builder # [ 0.544120] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved738server # [ 0.536521] pnp: PnP ACPI: found 6 devices739builder # [ 0.545713] pnp: PnP ACPI: found 6 devices740server # [ 0.544160] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns741builder # [ 0.553359] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns742server # [ 0.545574] clocksource: Switched to clocksource acpi_pm743builder # [ 0.554856] clocksource: Switched to clocksource acpi_pm744server # [ 0.546562] NET: Registered PF_INET protocol family745builder # [ 0.555828] NET: Registered PF_INET protocol family746server # [ 0.547610] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)747builder # [ 0.556899] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)748server # [ 0.564624] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)749builder # [ 0.574326] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)750server # [ 0.566209] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)751builder # [ 0.575907] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)752server # [ 0.567531] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)753builder # [ 0.577249] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)754server # [ 0.568881] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)755builder # [ 0.578577] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)756server # [ 0.570119] TCP: Hash tables configured (established 8192 bind 8192)757builder # [ 0.579851] TCP: Hash tables configured (established 8192 bind 8192)758server # [ 0.571239] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)759builder # [ 0.580994] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)760server # [ 0.572528] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)761builder # [ 0.582333] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)762server # [ 0.573648] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)763builder # [ 0.583473] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)764server # [ 0.575175] NET: Registered PF_UNIX/PF_LOCAL protocol family765builder # [ 0.584742] NET: Registered PF_UNIX/PF_LOCAL protocol family766server # [ 0.576161] NET: Registered PF_XDP protocol family767builder # [ 0.585741] NET: Registered PF_XDP protocol family768server # [ 0.576995] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]769builder # [ 0.586618] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]770server # [ 0.577982] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]771builder # [ 0.587644] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]772server # [ 0.578991] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]773builder # [ 0.588668] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]774server # [ 0.580091] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]775builder # [ 0.589826] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]776server # [ 0.581190] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]777builder # [ 0.590988] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]778server # [ 0.582292] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]779builder # [ 0.592125] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]780server # [ 0.584061] ACPI: \_SB_.GSIA: Enabled at IRQ 16781builder # [ 0.593950] ACPI: \_SB_.GSIA: Enabled at IRQ 16782server # [ 0.586194] ACPI: \_SB_.GSIB: Enabled at IRQ 17783builder # [ 0.596072] ACPI: \_SB_.GSIB: Enabled at IRQ 17784server # [ 0.588294] ACPI: \_SB_.GSIC: Enabled at IRQ 18785builder # [ 0.598329] ACPI: \_SB_.GSIC: Enabled at IRQ 18786server # [ 0.590334] ACPI: \_SB_.GSID: Enabled at IRQ 19787builder # [ 0.600436] ACPI: \_SB_.GSID: Enabled at IRQ 19788server # [ 0.592193] PCI: CLS 0 bytes, default 64789builder # [ 0.602250] PCI: CLS 0 bytes, default 64790server # [ 0.593151] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns791builder # [ 0.603161] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns792server # [ 0.594880] Trying to unpack rootfs image as initramfs...793builder # [ 0.604976] Trying to unpack rootfs image as initramfs...794server # [ 0.638828] Initialise system trusted keyrings795builder # [ 0.648745] Initialise system trusted keyrings796builder # [ 0.649717] workingset: timestamp_bits=40 max_order=18 bucket_order=0797server # [ 0.641766] workingset: timestamp_bits=40 max_order=18 bucket_order=0798server # [ 0.663790] Key type asymmetric registered799builder # [ 0.673136] Key type asymmetric registered800server # [ 0.664517] Asymmetric key parser 'x509' registered801server # [ 0.665404] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)802builder # [ 0.675870] Asymmetric key parser 'x509' registered803builder # [ 0.676754] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)804server # [ 0.670922] io scheduler mq-deadline registered805builder # [ 0.679970] io scheduler mq-deadline registered806server # [ 0.671648] io scheduler kyber registered807builder # [ 0.680727] io scheduler kyber registered808builder # [ 0.683407] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled809server # [ 0.675344] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled810server # [ 0.676650] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A811builder # [ 0.686835] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A812server # [ 0.680498] Linux agpgart interface v0.103813server # [ 0.681296] ACPI: bus type drm_connector registered814builder # [ 0.690555] Linux agpgart interface v0.103815builder # [ 0.691377] ACPI: bus type drm_connector registered816server # [ 0.684221] usbcore: registered new interface driver usbserial_generic817server # [ 0.685322] usbserial: USB Serial support registered for generic818builder # [ 0.694264] usbcore: registered new interface driver usbserial_generic819builder # [ 0.695399] usbserial: USB Serial support registered for generic820server # [ 0.688730] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.821server # [ 0.688730] Please enable it if your BIOS has the CPPC option.822builder # [ 0.698856] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.823builder # [ 0.698856] Please enable it if your BIOS has the CPPC option.824server # [ 0.691004] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled825builder # [ 0.701211] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled826server # [ 0.694845] drop_monitor: Initializing network drop monitor service827server # [ 0.696074] NET: Registered PF_INET6 protocol family828builder # [ 0.704962] drop_monitor: Initializing network drop monitor service829builder # [ 0.706144] NET: Registered PF_INET6 protocol family830server # [ 0.699203] Segment Routing with IPv6831server # [ 0.701742] In-situ OAM (IOAM) with IPv6832builder # [ 0.711319] Segment Routing with IPv6833server # [ 0.702844] IPI shorthand broadcast: enabled834builder # [ 0.712062] In-situ OAM (IOAM) with IPv6835builder # [ 0.715130] IPI shorthand broadcast: enabled836server # [ 0.711246] sched_clock: Marking stable (577014450, 133694431)->(783065695, -72356814)837builder # [ 0.723522] sched_clock: Marking stable (587014362, 135804811)->(799164894, -76345721)838server # [ 0.716938] registered taskstats version 1839builder # [ 0.727005] registered taskstats version 1840server # [ 0.717936] Loading compiled-in X.509 certificates841builder # [ 0.727955] Loading compiled-in X.509 certificates842server # [ 0.736728] Demotion targets for Node 0: null843server # [ 0.737630] Key type .fscrypt registered844builder # [ 0.746838] Demotion targets for Node 0: null845server # [ 0.738348] Key type fscrypt-provisioning registered846builder # [ 0.747709] Key type .fscrypt registered847builder # [ 0.748401] Key type fscrypt-provisioning registered848server # [ 0.739336] ima: No TPM chip found, activating TPM-bypass!849builder # [ 0.749349] ima: No TPM chip found, activating TPM-bypass!850server # [ 0.743739] ima: Allocated hash algorithm: sha1851server # [ 0.744558] ima: No architecture policies found852builder # [ 0.753854] ima: Allocated hash algorithm: sha1853server # [ 0.745515] PM: Magic number: 10:186:270854builder # [ 0.754626] ima: No architecture policies found855builder # [ 0.755564] PM: Magic number: 10:186:270856server # [ 0.749231] RAS: Correctable Errors collector initialized.857builder # [ 0.759284] RAS: Correctable Errors collector initialized.858server # [ 0.758056] clk: Disabling unused clocks859builder # [ 0.768041] clk: Disabling unused clocks860builder # [ 0.768697] PM: genpd: Disabling unused power domains861server # [ 0.760729] PM: genpd: Disabling unused power domains862server # [ 0.931993] Freeing initrd memory: 29084K863builder # [ 0.941262] Freeing initrd memory: 29076K864server # [ 0.935226] Freeing unused decrypted memory: 2028K865builder # [ 0.944483] Freeing unused decrypted memory: 2028K866server # [ 0.937912] Freeing unused kernel image (initmem) memory: 3644K867builder # [ 0.947177] Freeing unused kernel image (initmem) memory: 3644K868server # [ 0.939008] Write protecting the kernel read-only data: 32768k869builder # [ 0.948247] Write protecting the kernel read-only data: 32768k870server # [ 0.940898] Freeing unused kernel image (text/rodata gap) memory: 1216K871builder # [ 0.950195] Freeing unused kernel image (text/rodata gap) memory: 1216K872server # [ 0.942442] Freeing unused kernel image (rodata/data gap) memory: 752K873builder # [ 0.951765] Freeing unused kernel image (rodata/data gap) memory: 752K874server # [ 0.993397] x86/mm: Checked W+X mappings: passed, no W+X pages found.875builder # [ 1.002789] x86/mm: Checked W+X mappings: passed, no W+X pages found.876server # [ 0.994465] Run /init as init process877builder # [ 1.003924] Run /init as init process878server # [ 1.005006] systemd[1]: Inserted module 'autofs4'879builder # [ 1.014256] systemd[1]: Inserted module 'autofs4'880builder # [ 1.030621] fuse: init (API version 7.45)881server # [ 1.021556] fuse: init (API version 7.45)882server # [ 1.027374] ACPI: \_SB_.GSIG: Enabled at IRQ 22883builder # [ 1.036431] ACPI: \_SB_.GSIG: Enabled at IRQ 22884server # [ 1.029874] ACPI: \_SB_.GSIH: Enabled at IRQ 23885builder # [ 1.038966] ACPI: \_SB_.GSIH: Enabled at IRQ 23886server # [ 1.032815] ACPI: \_SB_.GSIE: Enabled at IRQ 20887builder # [ 1.041967] ACPI: \_SB_.GSIE: Enabled at IRQ 20888server # [ 1.035050] ACPI: \_SB_.GSIF: Enabled at IRQ 21889builder # [ 1.044436] ACPI: \_SB_.GSIF: Enabled at IRQ 21890builder # [ 1.070919] systemd[1]: Successfully made /usr/ read-only.891server # [ 1.063193] systemd[1]: Successfully made /usr/ read-only.892builder # [ 1.407719] 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)893server # [ 1.400088] 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)894builder # [ 1.419721] systemd[1]: Detected virtualization kvm.895server # [ 1.412030] systemd[1]: Detected virtualization kvm.896builder # [ 1.421944] systemd[1]: Detected architecture x86-64.897server # [ 1.414129] systemd[1]: Detected architecture x86-64.898builder # [ 1.424072] systemd[1]: Running in initrd.899server # [ 1.416248] systemd[1]: Running in initrd.900builder # [ 1.426604] systemd[1]: Initializing machine ID from random generator.901server # [ 1.418903] systemd[1]: Initializing machine ID from random generator.902builder # [ 1.429545] systemd[1]: Hostname set to <builder>.903server # [ 1.421807] systemd[1]: Hostname set to <server>.904builder # [ 1.649996] systemd[1]: bpf-restrict-fs: LSM BPF program attached905server # [ 1.647900] systemd[1]: bpf-restrict-fs: LSM BPF program attached906builder # [ 1.704383] systemd[1]: Queued start job for default target Initrd Default Target.907builder # [ 1.708173] systemd[1]: Created slice Slice /system/modprobe.908builder # [ 1.709400] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.909builder # [ 1.710928] systemd[1]: Expecting device /dev/disk/by-label/nixos...910builder # [ 1.712093] systemd[1]: Reached target Path Units.911builder # [ 1.713004] systemd[1]: Reached target Slice Units.912server # [ 1.703484] systemd[1]: Queued start job for default target Initrd Default Target.913builder # [ 1.713879] systemd[1]: Reached target Swaps.914builder # [ 1.714662] systemd[1]: Reached target Timer Units.915builder # [ 1.715640] systemd[1]: Listening on D-Bus System Message Bus Socket.916builder # [ 1.716893] systemd[1]: Listening on Journal Socket (/dev/log).917server # [ 1.708092] systemd[1]: Created slice Slice /system/modprobe.918builder # [ 1.718076] systemd[1]: Listening on Journal Sockets.919server # [ 1.709297] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.920builder # [ 1.719106] systemd[1]: Listening on udev Control Socket.921server # [ 1.710730] systemd[1]: Expecting device /dev/disk/by-label/nixos...922builder # [ 1.720149] systemd[1]: Listening on udev Kernel Socket.923server # [ 1.711866] systemd[1]: Reached target Path Units.924builder # [ 1.721137] systemd[1]: Reached target Socket Units.925server # [ 1.712747] systemd[1]: Reached target Slice Units.926server # [ 1.713653] systemd[1]: Reached target Swaps.927server # [ 1.714479] systemd[1]: Reached target Timer Units.928builder # [ 1.723908] systemd[1]: Starting Create List of Static Device Nodes...929server # [ 1.715482] systemd[1]: Listening on D-Bus System Message Bus Socket.930server # [ 1.716771] systemd[1]: Listening on Journal Socket (/dev/log).931builder # [ 1.726901] systemd[1]: Starting Load Kernel Module 9pnet_virtio...932server # [ 1.718060] systemd[1]: Listening on Journal Sockets.933server # [ 1.719081] systemd[1]: Listening on udev Control Socket.934server # [ 1.720115] systemd[1]: Listening on udev Kernel Socket.935server # [ 1.721083] systemd[1]: Reached target Socket Units.936server # [ 1.722906] systemd[1]: Starting Create List of Static Device Nodes...937builder # [ 1.733911] systemd[1]: Starting Load Kernel Module configfs...938server # [ 1.726808] systemd[1]: Starting Load Kernel Module 9pnet_virtio...939server # [ 1.735795] systemd[1]: Starting Load Kernel Module configfs...940builder # [ 1.750490] systemd[1]: Starting Journal Service...941server # [ 1.750925] systemd[1]: Starting Journal Service...942builder # [ 1.764907] systemd[1]: Starting Load Kernel Modules...943server # [ 1.763824] systemd[1]: Starting Load Kernel Modules...944builder # [ 1.769920] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os945builder # [ 1.786287] netfs: FS-Cache loaded946builder # [ 1.791198] 9pnet: Installing 9P2000 support947server # [ 1.770805] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os948server # [ 1.787021] netfs: FS-Cache loaded949server # [ 1.791935] 9pnet: Installing 9P2000 support950builder # [ 1.803098] systemd[1]: Starting Coldplug All udev Devices...951server # [ 1.805812] systemd[1]: Starting Coldplug All udev Devices...952builder # [ 1.818211] systemd[1]: Finished Create List of Static Device Nodes.953builder # [ 1.823659] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.954server # [ 1.820049] systemd[1]: Finished Create List of Static Device Nodes.955builder # [ 1.832229] systemd[1]: Finished Load Kernel Module 9pnet_virtio.956server # [ 1.826838] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.957builder # [ 1.837286] systemd[1]: modprobe@configfs.service: Deactivated successfully.958builder # [ 1.838570] systemd-journald[67]: Collecting audit messages is disabled.959server # [ 1.833116] systemd[1]: Finished Load Kernel Module 9pnet_virtio.960builder # [ 1.846223] systemd[1]: Finished Load Kernel Module configfs.961server # [ 1.838856] systemd-journald[67]: Collecting audit messages is disabled.962server # [ 1.840509] systemd[1]: modprobe@configfs.service: Deactivated successfully.963builder # [ 1.851351] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config964builder # [ 1.853856] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.965server # [ 1.848085] systemd[1]: Finished Load Kernel Module configfs.966server # [ 1.852225] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config967builder # [ 1.862933] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...968server # [ 1.854439] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.969builder # [ 1.864337] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev970server # [ 1.861493] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev971server # [ 1.865800] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...972builder # [ 1.894450] systemd[1]: Finished Load Kernel Modules.973server # [ 1.892248] systemd[1]: Finished Load Kernel Modules.974builder # [ 1.902901] systemd[1]: Starting Apply Kernel Variables...975server # [ 1.898821] systemd[1]: Starting Apply Kernel Variables...976builder # [ 1.912029] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.977server # [ 1.911330] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.978builder # [ 1.923039] systemd[1]: Starting Create Static Device Nodes in /dev...979server # [ 1.920916] systemd[1]: Starting Create Static Device Nodes in /dev...980builder # [ 1.943695] systemd[1]: Finished Apply Kernel Variables.981server # [ 1.942799] systemd[1]: Finished Apply Kernel Variables.982builder # [ 1.819338] systemd-modules-load[68]: Inserted module 'dm_mod'983builder # [ 1.956359] systemd[1]: Started Journal Service.984builder # [ 1.822235] systemd-modules-load[68]: Inserted module 'virtio_balloon'985server # [ 1.952263] systemd[1]: Started Journal Service.986builder # [ 1.826312] systemd-modules-load[68]: Inserted module 'virtio_gpu'987builder # [ 1.827355] systemd[1]: Finished Create Static Device Nodes in /dev.988builder # [ 1.828473] systemd[1]: Reached target Preparation for Local File Systems.989server # [ 1.821233] systemd-modules-load[68]: Inserted module 'dm_mod'990builder # [ 1.830744] systemd[1]: Reached target Local File Systems.991server # [ 1.823705] systemd-modules-load[68]: Inserted module 'virtio_balloon'992server # [ 1.826305] systemd-modules-load[68]: Inserted module 'virtio_gpu'993builder # [ 1.834236] systemd[1]: Starting Create System Files and Directories...994server # [ 1.829308] systemd[1]: Finished Create Static Device Nodes in /dev.995server # [ 1.830412] systemd[1]: Reached target Preparation for Local File Systems.996server # [ 1.831525] systemd[1]: Reached target Local File Systems.997builder # [ 1.839986] systemd[1]: Starting Rule-based Manager for Device Events and Files...998server # [ 1.835908] systemd[1]: Starting Create System Files and Directories...999server # [ 1.841144] systemd[1]: Starting Rule-based Manager for Device Events and Files...1000builder # [ 1.873246] systemd[1]: Finished Create System Files and Directories.1001server # [ 1.874164] systemd[1]: Finished Create System Files and Directories.1002builder # [ 1.896775] systemd-udevd[76]: Using default interface naming scheme 'v261'.1003server # [ 1.895329] systemd-udevd[78]: Using default interface naming scheme 'v261'.1004builder # [ 1.921574] systemd[1]: Started Rule-based Manager for Device Events and Files.1005server # [ 1.918882] systemd[1]: Started Rule-based Manager for Device Events and Files.1006builder # [ 1.968094] systemd[1]: Finished Coldplug All udev Devices.1007builder # [ 1.968913] systemd[1]: Reached target System Initialization.1008builder # [ 1.969785] systemd[1]: Reached target Basic System.1009server # [ 1.966646] systemd[1]: Finished Coldplug All udev Devices.1010server # [ 1.967754] systemd[1]: Reached target System Initialization.1011server # [ 1.969347] systemd[1]: Reached target Basic System.1012builder # [ 2.289432] virtio_blk virtio5: 1/0/0 default/read/poll queues1013builder # [ 2.292605] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121014server # [ 2.291371] virtio_blk virtio5: 1/0/0 default/read/poll queues1015server # [ 2.294988] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121016builder # [ 2.315703] serio: i8042 KBD port at 0x60,0x64 irq 11017builder # [ 2.316414] serio: i8042 AUX port at 0x60,0x64 irq 121018builder # [ 2.319301] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1019server # [ 2.313444] serio: i8042 KBD port at 0x60,0x64 irq 11020server # [ 2.316981] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1021server # [ 2.323393] ehci-pci 0000:00:1d.7: EHCI Host Controller1022server # [ 2.324325] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11023server # [ 2.326130] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001024builder # [ 2.338599] uhci_hcd 0000:00:1d.0: UHCI Host Controller1025server # [ 2.331030] serio: i8042 AUX port at 0x60,0x64 irq 121026builder # [ 2.344302] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 11027server # [ 2.335737] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001028server # [ 2.336601] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181029server # [ 2.338160] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11030server # [ 2.339260] usb usb1: Product: EHCI Host Controller1031server # [ 2.339993] usb usb1: Manufacturer: Linux 6.18.49 ehci_hcd1032server # [ 2.340682] usb usb1: SerialNumber: 0000:00:1d.71033builder # [ 2.350945] uhci_hcd 0000:00:1d.0: detected 2 ports1034builder # [ 2.351693] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c2001035server # [ 2.343267] hub 1-0:1.0: USB hub found1036server # [ 2.344729] hub 1-0:1.0: 6 ports detected1037builder # [ 2.355950] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181038server # [ 2.347746] uhci_hcd 0000:00:1d.0: UHCI Host Controller1039builder # [ 2.357076] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11040server # [ 2.348491] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21041builder # [ 2.364008] usb usb1: Product: UHCI Host Controller1042builder # [ 2.364670] usb usb1: Manufacturer: Linux 6.18.49 uhci_hcd1043server # [ 2.356152] uhci_hcd 0000:00:1d.0: detected 2 ports1044builder # [ 2.367560] SCSI subsystem initialized1045server # [ 2.359364] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c2001046builder # [ 2.369322] usb usb1: SerialNumber: 0000:00:1d.01047builder # [ 2.373294] hub 1-0:1.0: USB hub found1048builder # [ 2.374981] hub 1-0:1.0: 2 ports detected1049server # [ 2.370257] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181050builder # [ 2.380130] ehci-pci 0000:00:1d.7: EHCI Host Controller1051server # [ 2.371379] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11052builder # [ 2.380870] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 21053builder # [ 2.382585] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001054builder # [ 2.388970] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001055builder # [ 2.390869] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181056builder # [ 2.391999] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11057server # [ 2.385684] usb usb2: Product: UHCI Host Controller1058builder # [ 2.395385] usb usb2: Product: EHCI Host Controller1059server # [ 2.386495] usb usb2: Manufacturer: Linux 6.18.49 uhci_hcd1060builder # [ 2.396796] usb usb2: Manufacturer: Linux 6.18.49 ehci_hcd1061server # [ 2.388130] usb usb2: SerialNumber: 0000:00:1d.01062builder # [ 2.397625] usb usb2: SerialNumber: 0000:00:1d.71063server # [ 2.389099] SCSI subsystem initialized1064builder # [ 2.399565] hub 2-0:1.0: USB hub found1065builder # [ 2.401170] hub 2-0:1.0: 6 ports detected1066server # [ 2.395243] hub 2-0:1.0: USB hub found1067server # [ 2.401512] hub 2-0:1.0: 2 ports detected1068builder # [ 2.278203] systemd[1]: Starting Virtual Console Setup...1069server # [ 2.281417] systemd[1]: Starting Virtual Console Setup...1070builder # [ 2.425166] hub 1-0:1.0: USB hub found1071builder # [ 2.427845] hub 1-0:1.0: 2 ports detected1072server # [ 2.288218] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.1073builder # [ 2.434894] uhci_hcd 0000:00:1d.1: UHCI Host Controller1074builder # [ 2.435610] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31075server # [ 2.428058] uhci_hcd 0000:00:1d.1: UHCI Host Controller1076server # [ 2.428794] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31077server # [ 2.439778] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01078builder # [ 2.451595] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01079builder # [ 2.317510] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.1080builder # [ 2.321428] systemd[1]: Finished Virtual Console Setup.1081builder # [ 2.459080] uhci_hcd 0000:00:1d.1: detected 2 ports1082server # [ 2.319262] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.1083builder # [ 2.327975] (udev-worker)[86]: Network interface NamePolicy= disabled on kernel command line.1084server # [ 2.323448] systemd[1]: Finished Virtual Console Setup.1085builder # [ 2.329662] (udev-worker)[90]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1086server # [ 2.325313] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1087builder # [ 2.334323] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1088server # [ 2.327707] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1089builder # [ 2.472612] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c2201090server # [ 2.464493] uhci_hcd 0000:00:1d.1: detected 2 ports1091server # [ 2.465312] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c2201092builder # [ 2.482858] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181093builder # [ 2.484044] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11094server # [ 2.478379] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181095server # [ 2.479634] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11096server # [ 2.483290] usb usb3: Product: UHCI Host Controller1097builder # [ 2.496916] usb usb3: Product: UHCI Host Controller1098builder # [ 2.497623] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd1099server # [ 2.488729] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd1100server # [ 2.489496] usb usb3: SerialNumber: 0000:00:1d.11101server # [ 2.492379] hub 3-0:1.0: USB hub found1102builder # [ 2.503848] usb usb3: SerialNumber: 0000:00:1d.11103builder # [ 2.505636] hub 3-0:1.0: USB hub found1104server # [ 2.498510] hub 3-0:1.0: 2 ports detected1105server # [ 2.365478] systemd[1]: Found device /dev/disk/by-label/nixos.1106server # [ 2.367158] systemd[1]: Reached target Initrd Root Device.1107builder # [ 2.511830] hub 3-0:1.0: 2 ports detected1108builder # [ 2.376759] systemd[1]: Found device /dev/disk/by-label/nixos.1109server # [ 2.370067] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1110builder # [ 2.378979] systemd[1]: Reached target Initrd Root Device.1111builder # [ 2.380600] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1112server # [ 2.509392] uhci_hcd 0000:00:1d.2: UHCI Host Controller1113server # [ 2.510257] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41114builder # [ 2.520010] uhci_hcd 0000:00:1d.2: UHCI Host Controller1115builder # [ 2.520728] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41116server # [ 2.513727] uhci_hcd 0000:00:1d.2: detected 2 ports1117server # [ 2.514503] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c2401118server # [ 2.519829] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181119builder # [ 2.532859] uhci_hcd 0000:00:1d.2: detected 2 ports1120builder # [ 2.533640] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c2401121server # [ 2.524971] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11122builder # [ 2.536080] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181123server # [ 2.530539] usb usb4: Product: UHCI Host Controller1124builder # [ 2.540861] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11125server # [ 2.532969] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd1126builder # [ 2.542867] usb usb4: Product: UHCI Host Controller1127builder # [ 2.543560] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd1128server # [ 2.536508] usb usb4: SerialNumber: 0000:00:1d.21129server # [ 2.538754] hub 4-0:1.0: USB hub found1130server # [ 2.406160] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks1131server # [ 2.541676] hub 4-0:1.0: 2 ports detected1132builder # [ 2.551894] usb usb4: SerialNumber: 0000:00:1d.21133builder # [ 2.553494] hub 4-0:1.0: USB hub found1134server # [ 2.412058] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1135builder # [ 2.418791] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks1136builder # [ 2.423515] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1137builder # [ 2.561066] hub 4-0:1.0: 2 ports detected1138server # [ 2.555571] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1139server # [ 2.556683] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1140builder # [ 2.575595] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1141server # [ 2.569171] ahci 0000:00:1f.2: flags: 64bit ncq only1142builder # [ 2.589425] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1143server # [ 2.582118] scsi host0: ahci1144server # [ 2.583739] usb 1-1: new high-speed USB device number 2 using ehci-pci1145builder # [ 2.593065] ahci 0000:00:1f.2: flags: 64bit ncq only1146server # [ 2.584955] scsi host1: ahci1147server # [ 2.587141] scsi host2: ahci1148server # [ 2.589838] scsi host3: ahci1149builder # [ 2.601917] scsi host0: ahci1150server # [ 2.592949] scsi host4: ahci1151server # [ 2.594801] scsi host5: ahci1152server # [ 2.595390] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 11153builder # [ 2.605876] scsi host1: ahci1154server # [ 2.598798] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 11155builder # [ 2.609421] scsi host2: ahci1156server # [ 2.600636] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 11157builder # [ 2.611266] scsi host3: ahci1158server # [ 2.601807] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 11159server # [ 2.602926] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 11160builder # [ 2.612943] scsi host4: ahci1161server # [ 2.604070] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 11162builder # [ 2.614483] scsi host5: ahci1163builder # [ 2.615570] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 11164builder # [ 2.617243] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 11165builder # [ 2.618459] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 11166builder # [ 2.619638] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 11167builder # [ 2.620846] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 11168builder # [ 2.622022] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 11169builder # [ 2.642910] usb 2-1: new high-speed USB device number 2 using ehci-pci1170server # [ 2.714153] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001171server # [ 2.717012] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101172server # [ 2.720355] usb 1-1: Product: QEMU USB Tablet1173server # [ 2.722159] usb 1-1: Manufacturer: QEMU1174server # [ 2.723827] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11175server # [ 2.751426] hid: raw HID events driver (C) Jiri Kosina1176builder # [ 2.771945] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001177builder # [ 2.774777] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101178builder # [ 2.778000] usb 2-1: Product: QEMU USB Tablet1179builder # [ 2.779733] usb 2-1: Manufacturer: QEMU1180builder # [ 2.781403] usb 2-1: SerialNumber: 28754-0000:00:1d.7-11181builder # [ 2.811987] hid: raw HID events driver (C) Jiri Kosina1182builder # [ 2.682198] systemd[1]: Mounting /sysroot...1183server # [ 2.683699] systemd[1]: Mounting /sysroot...1184server # [ 2.912523] ata1: SATA link down (SStatus 0 SControl 300)1185server # [ 2.920042] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1186builder # [ 2.930576] ata1: SATA link down (SStatus 0 SControl 300)1187server # [ 2.922262] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001188server # [ 2.924460] ata3.00: applying bridge limits1189server # [ 2.926374] ata4: SATA link down (SStatus 0 SControl 300)1190server # [ 2.928667] ata6: SATA link down (SStatus 0 SControl 300)1191builder # [ 2.938012] ata4: SATA link down (SStatus 0 SControl 300)1192server # [ 2.931023] ata2: SATA link down (SStatus 0 SControl 300)1193builder # [ 2.940143] ata5: SATA link down (SStatus 0 SControl 300)1194server # [ 2.933310] ata5: SATA link down (SStatus 0 SControl 300)1195builder # [ 2.942463] ata6: SATA link down (SStatus 0 SControl 300)1196server # [ 2.935488] ata3.00: configured for UDMA/1001197builder # [ 2.944733] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1198builder # [ 2.947325] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001199server # [ 2.937915] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51200builder # [ 2.949431] ata3.00: applying bridge limits1201builder # [ 2.951327] ata2: SATA link down (SStatus 0 SControl 300)1202builder # [ 2.953526] ata3.00: configured for UDMA/1001203builder # [ 2.957293] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51204server # [ 3.007490] usbcore: registered new interface driver usbhid1205builder # [ 3.018014] usbcore: registered new interface driver usbhid1206builder # [ 3.018776] usbhid: USB HID core driver1207server # [ 3.012040] usbhid: USB HID core driver1208server # [ 3.022909] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21209server # [ 3.027639] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01210builder # [ 3.047274] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input21211builder # [ 3.052702] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01212server # [ 3.048242] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1213builder # [ 3.061264] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1214server # [ 3.058810] cdrom: Uniform CD-ROM driver Revision: 3.201215builder # [ 3.068930] EXT4-fs (vda): mounted filesystem b449b44d-1399-4e13-8b5f-30124f59106d r/w with ordered data mode. Quota mode: none.1216server # [ 3.062521] EXT4-fs (vda): mounted filesystem 4b0c22a8-7562-4956-ad7a-557d65704f97 r/w with ordered data mode. Quota mode: none.1217builder # [ 2.937658] systemd[1]: Mounted /sysroot.1218builder # [ 3.075335] cdrom: Uniform CD-ROM driver Revision: 3.201219builder # [ 2.940378] systemd[1]: Reached target Initrd Root File System.1220server # [ 2.935261] systemd[1]: Mounted /sysroot.1221server # [ 2.935907] systemd[1]: Reached target Initrd Root File System.1222builder # [ 2.944093] systemd[1]: Starting Mountpoints Configured in the Real Root...1223server # [ 2.938590] systemd[1]: Starting Mountpoints Configured in the Real Root...1224server # [ 2.952764] systemd-sysroot-fstab-check[139]: /sysroot should be mounted in the initrd, will request daemon-reload.1225builder # [ 2.959712] systemd-sysroot-fstab-check[140]: /sysroot should be mounted in the initrd, will request daemon-reload.1226server # [ 2.956061] systemd[1]: Reload requested from client PID 139 ('systemd-sysroot') (unit initrd-parse-etc.service)...1227builder # [ 2.963079] systemd[1]: Reload requested from client PID 140 ('systemd-sysroot') (unit initrd-parse-etc.service)...1228server # [ 2.957470] systemd[1]: Reloading...1229builder # [ 2.964457] systemd[1]: Reloading...1230server # [ 3.039558] systemd[1]: Reloading finished in 81 ms.1231builder # [ 3.048646] systemd[1]: Reloading finished in 85 ms.1232server # [ 3.048671] systemd-sysroot-fstab-check[139]: Requesting initrd-fs.target/start/replace...1233builder # [ 3.056247] systemd-sysroot-fstab-check[140]: Requesting initrd-fs.target/start/replace...1234server # [ 3.053194] systemd-sysroot-fstab-check[139]: Requesting swap.target/start/replace...1235builder # [ 3.060231] systemd-sysroot-fstab-check[140]: Requesting swap.target/start/replace...1236server # [ 3.056221] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1237server # [ 3.057590] systemd[1]: Finished Mountpoints Configured in the Real Root.1238builder # [ 3.064697] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1239server # [ 3.058655] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1240builder # [ 3.066880] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1241builder # [ 3.067930] systemd[1]: Finished Mountpoints Configured in the Real Root.1242server # [ 3.061241] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1243builder # [ 3.069197] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1244builder # [ 3.683184] systemd[1]: Mounting /sysroot/nix/.ro-store...1245builder # [ 3.688327] systemd[1]: Mounting /sysroot/nix/.rw-store...1246server # [ 3.684936] systemd[1]: Mounting /sysroot/nix/.ro-store...1247builder # [ 3.695254] systemd[1]: Mounting /sysroot/run...1248server # [ 3.690962] systemd[1]: Mounting /sysroot/nix/.rw-store...1249server # [ 3.697308] systemd[1]: Mounting /sysroot/run...1250builder # [ 3.706187] systemd[1]: Mounting /sysroot/tmp/shared...1251server # [ 3.709316] systemd[1]: Mounting /sysroot/tmp/shared...1252builder # [ 3.718744] systemd[1]: Mounting /sysroot/tmp/xchg...1253server # [ 3.721719] systemd[1]: Mounting /sysroot/tmp/xchg...1254builder # [ 3.892677] 9p: Installing v9fs 9p2000 file system support1255builder # [ 3.760076] systemd[1]: Mounted /sysroot/nix/.rw-store.1256server # [ 3.757649] systemd[1]: Mounted /sysroot/nix/.rw-store.1257builder # [ 3.766224] systemd[1]: Starting rw-sysroot-nix-store.service...1258server # [ 3.763053] systemd[1]: Mounted /sysroot/run.1259builder # [ 3.770483] systemd[1]: Mounted /sysroot/nix/.ro-store.1260builder # [ 3.772882] systemd[1]: Mounted /sysroot/run.1261builder # [ 3.773576] systemd[1]: Mounted /sysroot/tmp/shared.1262server # [ 3.901000] 9p: Installing v9fs 9p2000 file system support1263builder # [ 3.777256] systemd[1]: Mounted /sysroot/tmp/xchg.1264server # [ 3.775713] systemd[1]: Starting rw-sysroot-nix-store.service...1265builder # [ 3.784663] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1266builder # [ 3.786170] systemd[1]: Finished rw-sysroot-nix-store.service.1267server # [ 3.780693] systemd[1]: Mounted /sysroot/nix/.ro-store.1268builder # [ 3.788253] systemd[1]: Mounting /sysroot/nix/store...1269server # [ 3.784952] systemd[1]: Mounted /sysroot/tmp/shared.1270server # [ 3.785958] systemd[1]: Mounted /sysroot/tmp/xchg.1271server # [ 3.795711] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1272server # [ 3.796967] systemd[1]: Finished rw-sysroot-nix-store.service.1273builder # [ 3.807105] systemd[1]: Mounted /sysroot/nix/store.1274builder # [ 3.808430] systemd[1]: Reached target Initrd File Systems.1275builder # [ 3.810097] systemd[1]: Starting Find NixOS closure...1276builder # [ 3.812585] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1277builder # [ 3.830331] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1278builder # [ 3.837979] systemd[1]: Finished Find NixOS closure.1279builder # [ 3.839971] systemd[1]: Reached target Initrd Default Target.1280builder # [ 3.841079] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1281builder # [ 3.854434] systemd[1]: Stopped target Initrd Default Target.1282builder # [ 3.855748] systemd[1]: Stopped target Basic System.1283builder # [ 3.857200] systemd[1]: Stopped target Initrd Root Device.1284builder # [ 3.857936] systemd[1]: Stopped target Path Units.1285builder # [ 3.858743] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1286builder # [ 3.859812] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1287builder # [ 3.861300] systemd[1]: Stopped target Slice Units.1288builder # [ 3.862318] systemd[1]: Stopped target Socket Units.1289builder # [ 3.864128] systemd[1]: Stopped target System Initialization.1290builder # [ 3.864896] systemd[1]: Stopped target Swaps.1291builder # [ 3.865570] systemd[1]: Stopped target Timer Units.1292builder # [ 3.866437] systemd[1]: dbus.socket: Deactivated successfully.1293builder # [ 3.867246] systemd[1]: Closed D-Bus System Message Bus Socket.1294builder # [ 3.867990] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1295builder # [ 3.868968] systemd[1]: Stopped Find NixOS closure.1296builder # [ 3.869651] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1297builder # [ 3.871646] systemd[1]: Starting rw-sysroot-nix-store.service...1298builder # [ 3.873183] systemd[1]: systemd-sysctl.service: Deactivated successfully.1299builder # [ 3.874332] systemd[1]: Stopped Apply Kernel Variables.1300builder # [ 3.875369] systemd[1]: systemd-modules-load.service: Deactivated successfully.1301builder # [ 3.876545] systemd[1]: Stopped Load Kernel Modules.1302builder # [ 3.877606] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1303builder # [ 3.879169] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1304builder # [ 3.880458] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1305builder # [ 3.881630] systemd[1]: Stopped Create System Files and Directories.1306builder # [ 3.883345] systemd[1]: Stopped target Local File Systems.1307builder # [ 3.884349] systemd[1]: Stopped target Preparation for Local File Systems.1308builder # [ 3.885478] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1309builder # [ 3.887113] systemd[1]: Stopped Coldplug All udev Devices.1310builder # [ 3.887846] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1311builder # [ 3.889598] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1312builder # [ 3.890599] systemd[1]: Stopped Virtual Console Setup.1313builder # [ 3.899345] systemd[1]: initrd-cleanup.service: Deactivated successfully.1314builder # [ 3.903149] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1315builder # [ 3.908078] systemd[1]: systemd-udevd.service: Deactivated successfully.1316builder # [ 3.911206] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1317builder # [ 3.912287] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1318builder # [ 3.913472] systemd[1]: Finished rw-sysroot-nix-store.service.1319builder # [ 3.914899] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1320builder # [ 3.916209] systemd[1]: Closed udev Control Socket.1321builder # [ 3.917772] systemd[1]: Starting Cleanup udev Database...1322builder # [ 3.919143] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1323builder # [ 3.920153] systemd[1]: Stopped Create Static Device Nodes in /dev.1324builder # [ 3.920941] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1325builder # [ 3.922118] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1326builder # [ 3.924131] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1327builder # [ 3.924993] systemd[1]: Stopped Create List of Static Device Nodes.1328builder # [ 3.939552] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1329builder # [ 3.942079] systemd[1]: Finished Cleanup udev Database.1330builder # [ 3.942813] systemd[1]: Reached target Switch Root.1331builder # [ 3.944364] systemd[1]: Starting NixOS Activation...1332builder # [ 4.095357] initrd-nixos-activation-start[194]: booting system configuration /nix/store/5h2chz07g5ynmnnz485l72mlqsfz71ix-nixos-system-builder-test1333builder # [ 4.160109] initrd-nixos-activation-start[194]: running activation script...1334builder # [ 4.558565] initrd-nixos-activation-start[217]: setting up /etc...1335server # [ 4.684609] systemd[1]: Mounting /sysroot/nix/store...1336server # [ 4.731125] systemd[1]: Mounted /sysroot/nix/store.1337server # [ 4.734220] systemd[1]: Reached target Initrd File Systems.1338server # [ 4.737322] systemd[1]: Starting Find NixOS closure...1339server # [ 4.743154] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1340server # [ 4.765820] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1341server # [ 4.768543] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1342server # [ 4.780615] systemd[1]: Finished Find NixOS closure.1343server # [ 4.782234] systemd[1]: Reached target Initrd Default Target.1344server # [ 4.783577] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1345server # [ 4.797704] systemd[1]: Stopped target Initrd Default Target.1346server # [ 4.798734] systemd[1]: Stopped target Basic System.1347builder # [ 4.806089] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1348builder # [ 4.807202] systemd[1]: Finished NixOS Activation.1349server # [ 4.801259] systemd[1]: Stopped target Initrd Root Device.1350builder # [ 4.808638] systemd[1]: Starting Switch Root...1351server # [ 4.802199] systemd[1]: Stopped target Path Units.1352server # [ 4.802943] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1353server # [ 4.803983] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1354server # [ 4.805081] systemd[1]: Stopped target Slice Units.1355server # [ 4.805764] systemd[1]: Stopped target Socket Units.1356server # [ 4.806460] systemd[1]: Stopped target System Initialization.1357server # [ 4.807217] systemd[1]: Stopped target Swaps.1358server # [ 4.807826] systemd[1]: Stopped target Timer Units.1359server # [ 4.808513] systemd[1]: dbus.socket: Deactivated successfully.1360server # [ 4.809370] systemd[1]: Closed D-Bus System Message Bus Socket.1361server # [ 4.810729] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1362server # [ 4.811738] systemd[1]: Stopped Find NixOS closure.1363server # [ 4.812413] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1364builder # [ 4.822241] systemd[1]: Switching root.1365server # [ 4.815169] systemd[1]: Starting rw-sysroot-nix-store.service...1366server # [ 4.816148] systemd[1]: systemd-sysctl.service: Deactivated successfully.1367server # [ 4.818054] systemd[1]: Stopped Apply Kernel Variables.1368server # [ 4.818784] systemd[1]: systemd-modules-load.service: Deactivated successfully.1369server # [ 4.819726] systemd[1]: Stopped Load Kernel Modules.1370server # [ 4.821172] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1371server # [ 4.822236] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1372server # [ 4.823748] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1373server # [ 4.824727] systemd[1]: Stopped Create System Files and Directories.1374server # [ 4.826143] systemd[1]: Stopped target Local File Systems.1375server # [ 4.827552] systemd[1]: Stopped target Preparation for Local File Systems.1376server # [ 4.829135] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1377server # [ 4.830524] systemd[1]: Stopped Coldplug All udev Devices.1378server # [ 4.831285] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1379server # [ 4.833141] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1380server # [ 4.834749] systemd[1]: Stopped Virtual Console Setup.1381server # [ 4.843471] systemd[1]: initrd-cleanup.service: Deactivated successfully.1382server # [ 4.845109] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1383server # [ 4.847647] systemd[1]: systemd-udevd.service: Deactivated successfully.1384server # [ 4.849096] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1385server # [ 4.853681] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1386server # [ 4.854796] systemd[1]: Closed udev Control Socket.1387server # [ 4.857089] systemd[1]: Starting Cleanup udev Database...1388server # [ 4.857854] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1389server # [ 4.858903] systemd[1]: Stopped Create Static Device Nodes in /dev.1390server # [ 4.859786] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1391server # [ 4.860853] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1392server # [ 4.862118] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1393server # [ 4.863149] systemd[1]: Stopped Create List of Static Device Nodes.1394server # [ 4.864115] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1395server # [ 4.866431] systemd[1]: Finished rw-sysroot-nix-store.service.1396server # [ 4.880414] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1397server # [ 4.881805] systemd[1]: Finished Cleanup udev Database.1398server # [ 4.883448] systemd[1]: Reached target Switch Root.1399server # [ 4.885070] systemd[1]: Starting NixOS Activation...1400builder # [ 5.090913] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).1401server # [ 5.024756] initrd-nixos-activation-start[194]: booting system configuration /nix/store/rlvrl3y8sqjv506lqpf227b08jmkcx9l-nixos-system-server-test1402server # [ 5.081896] initrd-nixos-activation-start[194]: running activation script...1403builder # [ 5.262015] NET: Registered PF_VSOCK protocol family1404server # [ 5.471940] initrd-nixos-activation-start[217]: setting up /etc...1405builder # [ 5.646461] 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)1406builder # [ 5.656046] systemd[1]: Detected virtualization kvm.1407builder # [ 5.657871] systemd[1]: Detected architecture x86-64.1408builder # [ 5.659692] systemd[1]: Detected first boot.1409builder # [ 5.666075] systemd[1]: Initializing machine ID from random generator.1410builder # [ 5.802082] systemd[1]: bpf-restrict-fs: LSM BPF program attached1411server # [ 5.723196] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1412server # [ 5.726075] systemd[1]: Finished NixOS Activation.1413server # [ 5.727447] systemd[1]: Starting Switch Root...1414server # [ 5.742403] systemd[1]: Switching root.1415builder # [ 5.918970] systemd[1]: Applying preset policy.1416server # [ 6.014077] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).1417server # [ 6.148995] NET: Registered PF_VSOCK protocol family1418builder # [ 6.334331] systemd[1]: Populated /etc with preset unit settings.1419server # [ 6.530085] 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)1420server # [ 6.539784] systemd[1]: Detected virtualization kvm.1421server # [ 6.541542] systemd[1]: Detected architecture x86-64.1422server # [ 6.543624] systemd[1]: Detected first boot.1423server # [ 6.550576] systemd[1]: Initializing machine ID from random generator.1424builder # [ 6.780482] systemd[1]: initrd-switch-root.service: Deactivated successfully.1425builder # [ 6.781899] systemd[1]: Stopped initrd-switch-root.service.1426builder # [ 6.784546] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1427builder # [ 6.786593] systemd[1]: Created slice Slice /system/getty.1428builder # [ 6.788023] systemd[1]: Created slice User and Session Slice.1429builder # [ 6.788950] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1430builder # [ 6.790170] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1431builder # [ 6.791248] systemd[1]: Expecting device /dev/hvc0...1432builder # [ 6.792012] systemd[1]: Expecting device /dev/ttyS0...1433builder # [ 6.792718] systemd[1]: Reached target Local Encrypted Volumes.1434builder # [ 6.793550] systemd[1]: Stopped target initrd-fs.target.1435builder # [ 6.794297] systemd[1]: Stopped target initrd-root-fs.target.1436builder # [ 6.795095] systemd[1]: Stopped target initrd-switch-root.target.1437builder # [ 6.795945] systemd[1]: Reached target Virtual Machines and Containers.1438builder # [ 6.796860] systemd[1]: Reached target Path Units.1439builder # [ 6.797529] systemd[1]: Reached target Remote File Systems.1440builder # [ 6.809573] systemd[1]: Reached target Slice Units.1441builder # [ 6.810298] systemd[1]: Reached target Swaps.1442builder # [ 6.813381] systemd[1]: Listening on Query the User Interactively for a Password.1443server # [ 6.803805] systemd[1]: bpf-restrict-fs: LSM BPF program attached1444builder # [ 6.816958] systemd[1]: Listening on Process Core Dump Socket.1445builder # [ 6.819544] systemd[1]: Listening on Credential Encryption/Decryption.1446builder # [ 6.822115] systemd[1]: Listening on Factory Reset Management.1447builder # [ 6.823051] systemd[1]: Listening on Hostname Service Socket.1448builder # [ 6.829216] systemd[1]: Starting Journal Log Access Socket...1449builder # [ 6.830506] systemd[1]: Listening on Journal Audit Socket.1450builder # [ 6.833295] systemd[1]: Listening on Console Output Muting Service Socket.1451builder # [ 6.834560] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1452builder # [ 6.836249] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1453builder # [ 6.837552] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1454builder # [ 6.845787] systemd[1]: Listening on Disk Repartitioning Service Socket.1455builder # [ 6.846786] systemd[1]: Listening on udev Control Socket.1456builder # [ 6.847659] systemd[1]: Listening on udev Varlink Socket.1457builder # [ 6.851507] systemd[1]: Mounting Huge Pages File System...1458builder # [ 6.854623] systemd[1]: Mounting POSIX Message Queue File System...1459builder # [ 6.861131] systemd[1]: Mounting Kernel Debug File System...1460builder # [ 6.867547] systemd[1]: Mounting Kernel Trace File System...1461builder # [ 6.873673] systemd[1]: Starting Create List of Static Device Nodes...1462builder # [ 6.878059] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1463builder # [ 6.886268] systemd[1]: Starting Load Kernel Module configfs...1464builder # [ 6.887522] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1465builder # [ 6.889675] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1466builder # [ 6.891753] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1467builder # [ 6.900444] systemd[1]: Mounting FUSE Control File System...1468builder # [ 6.905734] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671469server # [ 6.925862] systemd[1]: Applying preset policy.1470builder # [ 6.939346] systemd[1]: Starting Journal Service...1471builder # [ 6.950994] systemd[1]: Starting Load Kernel Modules...1472builder # [ 6.964160] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1473builder # [ 6.970738] systemd[1]: Starting Remount Root and Kernel File Systems...1474builder # [ 6.972463] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1475builder # [ 6.977517] systemd[1]: Starting Coldplug All udev Devices...1476builder # [ 6.981393] systemd[1]: Listening on Journal Log Access Socket.1477builder # [ 6.984182] systemd[1]: Mounted Huge Pages File System.1478builder # [ 6.986019] systemd[1]: Mounted POSIX Message Queue File System.1479builder # [ 6.989021] systemd[1]: Mounted Kernel Debug File System.1480builder # [ 6.989974] systemd[1]: Mounted Kernel Trace File System.1481builder # [ 6.991954] systemd[1]: Mounted FUSE Control File System.1482builder # [ 6.995991] systemd[1]: Finished Create List of Static Device Nodes.1483builder # [ 7.001156] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1484builder # [ 7.030981] systemd[1]: modprobe@configfs.service: Deactivated successfully.1485builder # [ 7.033915] systemd[1]: Finished Load Kernel Module configfs.1486builder # [ 7.037977] systemd[1]: Mounting Kernel Configuration File System...1487builder # [ 7.064604] systemd[1]: Mounted Kernel Configuration File System.1488builder # [ 7.073013] EXT4-fs (vda): re-mounted b449b44d-1399-4e13-8b5f-30124f59106d.1489builder # [ 7.083724] systemd[1]: Finished Remount Root and Kernel File Systems.1490builder # [ 7.085465] systemd[1]: Listening on Disk Image Download Service Socket.1491builder # [ 7.087632] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1492builder # [ 7.096255] systemd[1]: Starting Load/Save OS Random Seed...1493builder # [ 7.097906] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1494builder # [ 7.101025] systemd-journald[287]: Collecting audit messages is enabled.1495builder # [ 7.125913] loop: module loaded1496builder # [ 7.135938] systemd[1]: Started Journal Service.1497builder # [ 7.005434] systemd[1]: Queued start job for default target Multi-User System.1498builder # [ 7.009779] systemd[1]: systemd-journald.service: Deactivated successfully.1499builder # [ 7.016743] systemd-modules-load[288]: Inserted module 'loop'1500builder # [ 7.153968] tun: Universal TUN/TAP device driver, 1.61501builder # [ 7.020133] systemd[1]: Starting Flush Journal to Persistent Storage...1502builder # [ 7.029305] systemd-modules-load[288]: Inserted module 'tun'1503builder # [ 7.041386] systemd[1]: Finished Load Kernel Modules.1504builder # [ 7.045218] systemd[1]: Starting Firewall...1505builder # [ 7.051811] systemd[1]: Starting Apply Kernel Variables...1506builder # [ 7.052715] systemd[1]: Finished Load/Save OS Random Seed.1507builder # [ 7.053549] systemd[1]: Reached target First Boot Complete.1508builder # [ 7.062821] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1509builder # [ 7.065464] systemd[1]: Starting Create Static Device Nodes in /dev...1510builder # [ 7.094187] systemd-oomd[289]: No swap; memory pressure usage will be degraded1511builder # [ 7.240240] systemd-journald[287]: Received client request to flush runtime journal.1512server # [ 7.347138] systemd[1]: Populated /etc with preset unit settings.1513builder # [ 7.240133] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1514builder # [ 7.242253] systemd[1]: Finished Apply Kernel Variables.1515builder # [ 7.242956] systemd[1]: Finished Create Static Device Nodes in /dev.1516builder # [ 7.246808] systemd[1]: Reached target Preparation for Local File Systems.1517builder # [ 7.249890] systemd[1]: Starting Rule-based Manager for Device Events and Files...1518builder # [ 7.251430] systemd[1]: Finished Flush Journal to Persistent Storage.1519builder # [ 7.315890] systemd-udevd[319]: Using default interface naming scheme 'v261'.1520builder # [ 7.390267] systemd[1]: Finished Coldplug All udev Devices.1521builder # [ 7.440364] systemd[1]: Started Rule-based Manager for Device Events and Files.1522builder # [ 7.569588] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1523builder # [ 7.640343] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1524server # [ 7.767939] systemd[1]: initrd-switch-root.service: Deactivated successfully.1525server # [ 7.769318] systemd[1]: Stopped initrd-switch-root.service.1526server # [ 7.771980] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1527server # [ 7.774062] systemd[1]: Created slice Slice /system/getty.1528server # [ 7.775423] systemd[1]: Created slice User and Session Slice.1529server # [ 7.776383] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1530builder # [ 7.650237] systemd[1]: Mounting /run/wrappers...1531server # [ 7.777577] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1532server # [ 7.778674] systemd[1]: Expecting device /dev/hvc0...1533server # [ 7.779402] systemd[1]: Expecting device /dev/ttyS0...1534server # [ 7.780160] systemd[1]: Reached target Local Encrypted Volumes.1535server # [ 7.780963] systemd[1]: Stopped target initrd-fs.target.1536server # [ 7.781659] systemd[1]: Stopped target initrd-root-fs.target.1537server # [ 7.782451] systemd[1]: Stopped target initrd-switch-root.target.1538server # [ 7.783289] systemd[1]: Reached target Virtual Machines and Containers.1539server # [ 7.784203] systemd[1]: Reached target Path Units.1540server # [ 7.784896] systemd[1]: Reached target Remote File Systems.1541server # [ 7.785632] systemd[1]: Reached target Slice Units.1542server # [ 7.786336] systemd[1]: Reached target Swaps.1543server # [ 7.800994] systemd[1]: Listening on Query the User Interactively for a Password.1544server # [ 7.804732] systemd[1]: Listening on Process Core Dump Socket.1545server # [ 7.808041] systemd[1]: Listening on Credential Encryption/Decryption.1546builder # [ 7.683737] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1547server # [ 7.811074] systemd[1]: Listening on Factory Reset Management.1548server # [ 7.811973] systemd[1]: Listening on Hostname Service Socket.1549server # [ 7.816091] systemd[1]: Starting Journal Log Access Socket...1550server # [ 7.817641] systemd[1]: Listening on Journal Audit Socket.1551server # [ 7.821043] systemd[1]: Listening on Console Output Muting Service Socket.1552server # [ 7.822147] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1553builder # [ 7.696756] systemd[1]: Mounted /run/wrappers.1554server # [ 7.823782] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1555builder # [ 7.698405] systemd[1]: Reached target Local File Systems.1556server # [ 7.825039] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1557builder # [ 7.701753] systemd[1]: Listening on Boot Loader Control Service Socket.1558server # [ 7.833447] systemd[1]: Listening on Disk Repartitioning Service Socket.1559builder # [ 7.707197] systemd[1]: Starting register-nix-paths.service...1560server # [ 7.834493] systemd[1]: Listening on udev Control Socket.1561server # [ 7.835388] systemd[1]: Listening on udev Varlink Socket.1562builder # [ 7.711365] systemd[1]: Starting Create SUID/SGID Wrappers...1563builder # [ 7.712353] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1564server # [ 7.839780] systemd[1]: Mounting Huge Pages File System...1565builder # [ 7.715988] systemd[1]: Starting Save Transient machine-id to Disk...1566server # [ 7.843059] systemd[1]: Mounting POSIX Message Queue File System...1567builder # [ 7.720972] systemd[1]: Starting Create System Files and Directories...1568server # [ 7.848289] systemd[1]: Mounting Kernel Debug File System...1569server # [ 7.855280] systemd[1]: Mounting Kernel Trace File System...1570server # [ 7.861066] systemd[1]: Starting Create List of Static Device Nodes...1571server # [ 7.865263] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1572server # [ 7.874049] systemd[1]: Starting Load Kernel Module configfs...1573server # [ 7.874988] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1574server # [ 7.876592] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1575server # [ 7.878544] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1576server # [ 7.886343] systemd[1]: Mounting FUSE Control File System...1577server # [ 7.894986] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671578builder # [ 7.778245] (udev-worker)[357]: Network interface NamePolicy= disabled on kernel command line.1579builder # [ 7.783164] (udev-worker)[360]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1580builder # [ 7.787868] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line.1581server # [ 7.926673] systemd[1]: Starting Journal Service...1582server # [ 7.940193] systemd[1]: Starting Load Kernel Modules...1583server # [ 7.951355] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1584server # [ 7.957221] systemd[1]: Starting Remount Root and Kernel File Systems...1585server # [ 7.959830] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1586server # [ 7.967015] systemd[1]: Starting Coldplug All udev Devices...1587builder # [ 7.840378] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1588server # [ 7.971320] systemd[1]: Listening on Journal Log Access Socket.1589builder # [ 7.844583] systemd[1]: Finished Save Transient machine-id to Disk.1590server # [ 7.974187] systemd[1]: Mounted Huge Pages File System.1591server # [ 7.976076] systemd[1]: Mounted POSIX Message Queue File System.1592server # [ 7.978400] systemd[1]: Mounted Kernel Debug File System.1593server # [ 7.980351] systemd[1]: Mounted Kernel Trace File System.1594server # [ 7.983779] systemd[1]: Finished Create List of Static Device Nodes.1595server # [ 7.986243] systemd[1]: Mounted FUSE Control File System.1596server # [ 7.994577] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1597builder # [ 7.874109] systemd[1]: Finished Create System Files and Directories.1598builder # [ 7.880095] systemd[1]: Starting Rebuild Journal Catalog...1599builder # [ 7.883912] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1600server # [ 8.020307] systemd[1]: modprobe@configfs.service: Deactivated successfully.1601server # [ 8.022433] systemd[1]: Finished Load Kernel Module configfs.1602server # [ 8.028114] systemd[1]: Mounting Kernel Configuration File System...1603server # [ 8.059961] systemd[1]: Mounted Kernel Configuration File System.1604server # [ 8.062908] EXT4-fs (vda): re-mounted 4b0c22a8-7562-4956-ad7a-557d65704f97.1605server # [ 8.069950] systemd[1]: Finished Remount Root and Kernel File Systems.1606server # [ 8.071095] systemd[1]: Listening on Disk Image Download Service Socket.1607server # [ 8.073448] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1608builder # [ 7.954226] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1609server # [ 8.081159] systemd[1]: Starting Load/Save OS Random Seed...1610server # [ 8.082757] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1611server # [ 8.087223] loop: module loaded1612server # [ 8.094026] systemd-journald[287]: Collecting audit messages is enabled.1613server # [ 8.124388] tun: Universal TUN/TAP device driver, 1.61614server # [ 8.133729] systemd[1]: Started Journal Service.1615server # [ 8.003585] systemd[1]: Queued start job for default target Multi-User System.1616server # [ 8.007858] systemd[1]: systemd-journald.service: Deactivated successfully.1617builder # [ 8.020251] systemd[1]: Finished Rebuild Journal Catalog.1618server # [ 8.013402] systemd-modules-load[288]: Inserted module 'loop'1619builder # [ 8.022620] systemd[1]: Starting Update is Completed...1620server # [ 8.018117] systemd-modules-load[288]: Inserted module 'tls'1621server # [ 8.024041] systemd-modules-load[288]: Inserted module 'tun'1622server # [ 8.027929] systemd[1]: Starting Flush Journal to Persistent Storage...1623server # [ 8.030530] systemd[1]: Finished Load Kernel Modules.1624server # [ 8.038406] systemd[1]: Starting Firewall...1625server # [ 8.042293] systemd[1]: Starting Apply Kernel Variables...1626server # [ 8.044293] systemd[1]: Finished Load/Save OS Random Seed.1627server # [ 8.047994] systemd[1]: Reached target First Boot Complete.1628server # [ 8.065074] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1629builder # [ 8.076824] systemd[1]: Finished Update is Completed.1630server # [ 8.070079] systemd[1]: Starting Create Static Device Nodes in /dev...1631builder # [ 8.080859] systemd[1]: Condition check resulted in Virtio network device being skipped.1632builder # [ 8.082912] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1633builder # [ 8.085306] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1634builder # [ 8.087156] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671635builder # [ 8.090179] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1636builder # [ 8.092203] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1637builder # [ 8.095113] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1638server # [ 8.093758] systemd-oomd[289]: No swap; memory pressure usage will be degraded1639server # [ 8.238269] systemd-journald[287]: Received client request to flush runtime journal.1640server # [ 8.255604] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1641server # [ 8.258504] systemd[1]: Finished Apply Kernel Variables.1642server # [ 8.262457] systemd[1]: Finished Create Static Device Nodes in /dev.1643server # [ 8.264195] systemd[1]: Reached target Preparation for Local File Systems.1644server # [ 8.265182] systemd[1]: Starting Rule-based Manager for Device Events and Files...1645server # [ 8.267103] systemd[1]: Finished Flush Journal to Persistent Storage.1646builder # [ 8.439222] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1647server # [ 8.305950] systemd-udevd[321]: Using default interface naming scheme 'v261'.1648builder # [ 8.466422] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31649builder # [ 8.381191] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1650builder # [ 8.382470] systemd[1]: Finished Create SUID/SGID Wrappers.1651builder # [ 8.400705] systemd[1]: Finished Firewall.1652server # [ 8.423554] systemd[1]: Finished Coldplug All udev Devices.1653server # [ 8.425454] systemd[1]: Started Rule-based Manager for Device Events and Files.1654builder # [ 8.512612] mousedev: PS/2 mouse device common for all mice1655builder # [ 8.525874] ACPI: button: Power Button [PWRF]1656builder # [ 8.595317] rtc_cmos 00:04: RTC can wake from S41657builder # [ 8.611910] rtc_cmos 00:04: registered as rtc01658builder # [ 8.550244] systemd[1]: Finished register-nix-paths.service.1659builder # [ 8.552306] systemd[1]: Reached target System Initialization.1660builder # [ 8.554503] systemd[1]: Started Discard unused filesystem blocks once a week.1661builder # [ 8.555556] systemd[1]: Started Daily Cleanup of Temporary Directories.1662builder # [ 8.557331] systemd[1]: Reached target Timer Units.1663builder # [ 8.558847] systemd[1]: Listening on D-Bus System Message Bus Socket.1664builder # [ 8.560926] systemd[1]: Starting niks3 auto-upload socket...1665builder # [ 8.564700] systemd[1]: Listening on Nix Daemon Socket.1666builder # [ 8.565542] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1667builder # [ 8.566754] systemd[1]: Listening on niks3 auto-upload socket.1668builder # [ 8.570173] systemd[1]: Reached target Socket Units.1669server # [ 8.562786] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1670builder # [ 8.573103] systemd[1]: Starting D-Bus System Message Bus...1671builder # [ 8.611980] rtc_cmos 00:04: setting system clock to 2026-09-08T08:17:01 UTC (1788855421)1672builder # [ 8.612053] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1673builder # [ 8.613081] systemd-journald[287]: Time jumped backwards, rotating.1674builder # [ 8.628249] parport_pc 00:02: reported by Plug and Play ACPI1675builder # [ 8.628330] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1676builder # [ 8.642501] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input41677builder # [ 8.669418] Console: switching to colour dummy device 80x251678builder # [ 8.683684] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1679server # [ 8.631309] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1680builder # [ 8.761033] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1681server # [ 8.639212] systemd[1]: Mounting /run/wrappers...1682builder # [ 8.761140] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1683builder # [ 8.795095] [drm] Found bochs VGA, ID 0xb0c5.1684builder # [ 8.795097] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1685builder # [ 8.798908] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1686builder # [ 8.799671] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01687server # [ 8.673705] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1688builder # [ 8.818884] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61689builder # [ 8.819993] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51690server # [ 8.684181] systemd[1]: Mounted /run/wrappers.1691server # [ 8.685720] systemd[1]: Reached target Local File Systems.1692server # [ 8.688634] systemd[1]: Listening on Boot Loader Control Service Socket.1693builder # [ 8.696824] dbus-broker-launch[511]: Looking up NSS user entry for 'systemd-timesync'...1694server # [ 8.694403] systemd[1]: Starting register-nix-paths.service...1695server # [ 8.697657] systemd[1]: Starting Create SUID/SGID Wrappers...1696builder # [ 8.705685] dbus-broker-launch[511]: NSS returned no entry for 'systemd-timesync'1697builder # [ 8.706780] dbus-broker-launch[511]: Invalid user-name in /nix/store/4izapa6lc8pjjwkdkkys3rcag91nh0f8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1698server # [ 8.700617] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1699server # [ 8.704522] systemd[1]: Starting Save Transient machine-id to Disk...1700server # [ 8.712716] systemd[1]: Starting Create System Files and Directories...1701builder # [ 8.730607] systemd[1]: Starting Virtual Console Setup...1702builder # [ 8.746562] systemd[1]: Started D-Bus System Message Bus.1703builder # [ 8.747651] systemd[1]: Reached target Basic System.1704builder # [ 8.752124] systemd[1]: Started backdoor.service.1705builder # [ 8.756473] systemd[1]: Starting Import lastlog data into lastlog2 database...1706builder # [ 8.761691] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1707builder # [ 8.768977] systemd[1]: Starting Post-Boot Actions...1708builder # [ 8.773210] systemd[1]: Started Reset console on configuration changes.1709builder # [ 8.782148] systemd[1]: Starting resolvconf update...1710server # [ 8.787716] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1711server # [ 8.791659] systemd[1]: Finished Save Transient machine-id to Disk.1712server # [ 8.802981] (udev-worker)[366]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1713server # [ 8.806674] (udev-worker)[366]: Network interface NamePolicy= disabled on kernel command line.1714server # [ 8.811893] (udev-worker)[363]: Network interface NamePolicy= disabled on kernel command line.1715server # [ 8.856139] systemd[1]: Finished Create System Files and Directories.1716server # [ 8.862139] systemd[1]: Starting Rebuild Journal Catalog...1717server # [ 8.865486] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1718builder # [ 9.058556] Console: switching to colour frame buffer device 160x501719server # [ 8.941691] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1720builder # [ 9.092656] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1721builder # [ 8.839670] dbus-broker-launch[511]: Ready1722builder # [ 8.963673] systemd[1]: Finished Post-Boot Actions.1723builder # connecting to host...1724builder # [ 8.970688] nsncd[519]: Sep 08 08:17:01.929 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1725builder # [ 8.974735] systemd[1]: Started Name Service Cache Daemon (nsncd).1726builder # [ 8.981358] systemd[1]: Reached target Host and Network Name Lookups.1727builder # [ 8.982657] systemd[1]: Reached target User and Group Name Lookups.1728builder # [ 8.985051] systemd[1]: Starting User Login Management...1729builder # [ 8.985791] systemd[1]: Finished Import lastlog data into lastlog2 database.1730builder # [ 8.987142] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1731builder # [ 8.990398] systemd[1]: Stopped Virtual Console Setup.1732builder # [ 8.991154] systemd[1]: Starting Virtual Console Setup...1733server # [ 8.997355] systemd[1]: Finished Rebuild Journal Catalog.1734server # [ 9.001931] systemd[1]: Starting Update is Completed...1735builder # [ 9.188547] ppdev: user-space parallel port driver1736server # [ 9.058277] systemd[1]: Finished Update is Completed.1737builder # [ 9.071852] systemd[1]: Stopped target Host and Network Name Lookups.1738builder # [ 9.078158] systemd[1]: Stopping Host and Network Name Lookups...1739builder # [ 9.083931] systemd[1]: Stopped target User and Group Name Lookups.1740builder # [ 9.085776] systemd[1]: Stopping User and Group Name Lookups...1741builder # [ 9.089360] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1742server # [ 9.085145] systemd[1]: Condition check resulted in Virtio network device being skipped.1743server # [ 9.086464] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1744builder # [ 9.095226] systemd[1]: nscd.service: Deactivated successfully.1745builder # [ 9.096224] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1746server # [ 9.089299] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1747builder # [ 9.098795] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1748server # [ 9.091836] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671749server # [ 9.094198] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1750server # [ 9.096329] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1751server # [ 9.098662] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1752builder # [ 9.113780] systemd-logind[541]: Watching system buttons on /dev/input/event2 (Power Button)1753builder # [ 9.117047] systemd-logind[541]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1754builder # [ 9.120130] systemd-logind[541]: New seat seat0.1755builder # [ 9.124116] systemd[1]: Started User Login Management.1756builder # [ 9.129587] systemd[1]: Starting linger-users.service...1757builder # [ 9.330509] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1758builder # [ 9.199865] systemd[1]: Started Name Service Cache Daemon (nsncd).1759builder # [ 9.203426] nsncd[591]: Sep 08 08:17:02.222 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1760builder # [ 9.206379] systemd[1]: Reached target Host and Network Name Lookups.1761builder # [ 9.207966] systemd[1]: Reached target User and Group Name Lookups.1762builder # [ 9.212467] systemd[1]: linger-users.service: Deactivated successfully.1763builder # [ 9.215470] systemd[1]: Finished linger-users.service.1764builder # [ 9.234408] systemd[1]: Finished resolvconf update.1765builder # [ 9.236592] systemd[1]: Reached target Preparation for Network.1766builder # [ 9.241260] systemd[1]: Starting DHCP Client...1767builder # [ 9.245306] systemd[1]: Starting Address configuration of eth1...1768builder # [ 9.249854] systemd[1]: Starting Extra networking commands....1769builder # [ 9.416995] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1770builder # [ 9.296712] systemd-logind[541]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1771server # [ 9.471934] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31772server # [ 9.483305] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1773server # [ 9.362479] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1774server # [ 9.363925] systemd[1]: Finished Create SUID/SGID Wrappers.1775builder # [ 9.372732] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1776builder # [ 9.373962] systemd[1]: Stopped Virtual Console Setup.1777builder # [ 9.381937] systemd[1]: Starting Virtual Console Setup...1778builder # [ 9.389600] network-addresses-eth1-start[621]: adding address 192.168.1.1/24... done1779builder # [ 9.409137] network-addresses-eth1-start[621]: adding address 2001:db8:1::1/64... done1780builder # [ 9.441831] systemd[1]: Finished Address configuration of eth1.1781server # [ 9.477409] systemd[1]: Finished Firewall.1782builder # [ 9.551859] dhcpcd[631]: dhcpcd-10.3.2 starting1783builder # [ 9.561494] systemd[1]: Finished Extra networking commands..1784builder # [ 9.562726] systemd[1]: Reached target Network.1785builder # [ 9.565216] systemd[1]: Starting Permit User Sessions...1786server # [ 9.558887] systemd[1]: Finished register-nix-paths.service.1787builder # [ 9.571856] dhcpcd[689]: dev: loaded udev1788server # [ 9.566284] systemd[1]: Reached target System Initialization.1789server # [ 9.570242] systemd[1]: Started Discard unused filesystem blocks once a week.1790builder # [ 9.719708] kvm_amd: TSC scaling supported1791server # [ 9.577109] systemd[1]: Started niks3 garbage collection timer.1792builder # [ 9.720421] kvm_amd: Nested Virtualization enabled1793builder # [ 9.721156] kvm_amd: Nested Paging enabled1794builder # [ 9.721582] kvm_amd: LBR virtualization supported1795server # [ 9.578530] systemd[1]: Started Daily Cleanup of Temporary Directories.1796builder # [ 9.722339] kvm_amd: Virtual VMLOAD VMSAVE supported1797builder # [ 9.723394] kvm_amd: Virtual GIF supported1798builder # [ 9.724103] kvm_amd: Virtual NMI enabled1799server # [ 9.583219] systemd[1]: Reached target Timer Units.1800server # [ 9.586310] systemd[1]: Listening on D-Bus System Message Bus Socket.1801server # [ 9.594875] systemd[1]: Listening on niks3 server socket.1802server # [ 9.595846] systemd[1]: Listening on Nix Daemon Socket.1803server # [ 9.598141] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1804builder # [ 9.741722] 8021q: 802.1Q VLAN Support v1.81805server # [ 9.599374] systemd[1]: Reached target Socket Units.1806builder # [ 9.743303] 8021q: adding VLAN 0 to HW filter on device eth11807server # [ 9.603374] systemd[1]: Reached target Basic System.1808server # [ 9.608777] systemd[1]: Started backdoor.service.1809server # [ 9.613260] systemd[1]: Starting Import lastlog data into lastlog2 database...1810builder # [ 9.620436] systemd[1]: Finished Permit User Sessions.1811builder # [ 9.623961] systemd[1]: Started Getty on tty1.1812builder # [ 9.625451] systemd[1]: Reached target Login Prompts.1813server # [ 9.618889] systemd[1]: Starting Generate test mTLS certs...1814server # [ 9.623097] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1815server # [ 9.540986] ACPI: button: Power Button [PWRF]1816server # [ 9.628071] systemd[1]: Starting Post-Boot Actions...1817server # [ 9.628940] systemd[1]: Started Reset console on configuration changes.1818server # [ 9.544610] mousedev: PS/2 mouse device common for all mice1819server # [ 9.647143] rtc_cmos 00:04: RTC can wake from S41820server # [ 9.671869] rtc_cmos 00:04: registered as rtc01821server # [ 9.632325] systemd[1]: Starting resolvconf update...1822builder # [ 9.642372] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1823server # [ 9.638066] systemd[1]: Starting D-Bus System Message Bus...1824builder # [ 9.804653] EDAC MC: Ver: 3.0.01825builder # [ 9.847622] cfg80211: Loading compiled-in X.509 certificates for regulatory database1826server # [ 9.712134] systemd[1]: Finished Post-Boot Actions.1827server # [ 9.671935] rtc_cmos 00:04: setting system clock to 2026-09-08T08:17:02 UTC (1788855422)1828server # [ 9.672012] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1829builder # [ 9.874711] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1830builder # [ 9.875590] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1831builder # [ 9.879032] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21832builder # [ 9.879942] cfg80211: failed to load regulatory.db1833server # connecting to host...1834server # [ 9.762324] systemd[1]: Started Name Service Cache Daemon (nsncd).1835server # [ 9.764362] systemd[1]: Reached target Host and Network Name Lookups.1836server # [ 9.765220] systemd[1]: Reached target User and Group Name Lookups.1837server # [ 9.770594] systemd[1]: Starting User Login Management...1838server # [ 9.773720] nsncd[525]: Sep 08 08:17:02.719 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1839server # [ 9.777161] systemd[1]: Finished Import lastlog data into lastlog2 database.1840builder # [ 9.931151] 8021q: adding VLAN 0 to HW filter on device eth01841builder # [ 9.796585] dhcpcd[689]: eth0: waiting for carrier1842server # [ 9.673185] systemd-journald[287]: Time jumped backwards, rotating.1843builder # [ 9.798458] dhcpcd[689]: libudev: received NULL device1844server # [ 9.690523] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input41845builder # [ 9.799335] dhcpcd[689]: libudev: received NULL device1846builder # [ 9.800217] dhcpcd[689]: eth0: carrier acquired1847server: Guest shell says: b'Spawning backdoor root shell...\n'1848builder # [ 9.809288] dhcpcd[689]: DUID 00:01:00:01:32:32:80:fe:52:54:00:12:34:561849builder # [ 9.810531] dhcpcd[689]: eth0: IAID 00:12:34:561850builder # [ 9.811242] dhcpcd[689]: eth0: adding address fe80::5054:ff:fe12:34561851server: connected to guest root shell1852server: (connecting took 10.56 seconds)1853server: (finished: waiting for the VM to finish booting, in 10.56 seconds)1854server # [ 9.833392] niks3-test-certs-start[538]: -----1855server # [ 9.854393] dbus-broker-launch[529]: Looking up NSS user entry for 'systemd-timesync'...1856server # [ 9.885895] dbus-broker-launch[529]: NSS returned no entry for 'systemd-timesync'1857server # [ 9.886979] dbus-broker-launch[529]: Invalid user-name in /nix/store/qcv8ncv3y1bpgqi7sfxbkyqxm15m4323-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1858server # [ 9.890789] niks3-test-certs-start[575]: -----1859server # [ 9.696366] parport_pc 00:02: reported by Plug and Play ACPI1860server # [ 9.696456] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1861server # [ 9.760462] Console: switching to colour dummy device 80x251862server # [ 9.801527] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1863builder # [ 9.909267] systemd-vconsole-setup[638]: Configuration of first virtual console was skipped, ignoring remaining ones.1864builder # [ 9.913242] systemd[1]: Finished Virtual Console Setup.1865server # [ 10.160053] [drm] Found bochs VGA, ID 0xb0c5.1866server # [ 10.160056] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1867server # [ 9.990070] dbus-broker-launch[529]: Ready1868server # [ 10.031246] systemd[1]: Started D-Bus System Message Bus.1869server # [ 10.169072] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1870server # [ 10.169081] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01871server # [ 10.040063] systemd[1]: Stopped target Host and Network Name Lookups.1872server # [ 10.041219] niks3-test-certs-start[587]: Certificate request self-signature ok1873server # [ 10.042164] niks3-test-certs-start[587]: subject=CN=server1874server # [ 10.042892] systemd[1]: Stopping Host and Network Name Lookups...1875server # [ 10.045889] systemd[1]: Stopped target User and Group Name Lookups.1876server # [ 10.046809] systemd[1]: Stopping User and Group Name Lookups...1877server # [ 10.048433] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1878server # [ 10.053827] systemd[1]: nscd.service: Deactivated successfully.1879server # [ 10.054703] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1880server # [ 10.056741] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1881server # [ 10.058823] systemd-logind[552]: New seat seat0.1882server # [ 10.062546] systemd[1]: Started User Login Management.1883server # [ 10.065168] systemd[1]: Starting linger-users.service...1884server # [ 10.209419] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1885server # [ 10.210095] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1886server # [ 10.097357] niks3-test-certs-start[615]: -----1887server # [ 10.134094] systemd[1]: Started Name Service Cache Daemon (nsncd).1888server # [ 10.135783] systemd[1]: Reached target Host and Network Name Lookups.1889server # [ 10.137344] systemd[1]: Reached target User and Group Name Lookups.1890server # [ 10.141220] nsncd[605]: Sep 08 08:17:03.096 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1891server # [ 10.152244] systemd[1]: linger-users.service: Deactivated successfully.1892server # [ 10.153279] systemd[1]: Finished linger-users.service.1893server # [ 10.188813] systemd[1]: Finished resolvconf update.1894server # [ 10.189797] niks3-test-certs-start[622]: Certificate request self-signature ok1895server # [ 10.191440] niks3-test-certs-start[622]: subject=CN=niks3 test client1896server # [ 10.193231] systemd[1]: Reached target Preparation for Network.1897server # [ 10.204260] systemd[1]: Starting DHCP Client...1898server # [ 10.209645] systemd[1]: Starting Address configuration of eth1...1899server # [ 10.213068] systemd[1]: Starting Extra networking commands....1900server # [ 10.227955] systemd[1]: Finished Generate test mTLS certs.1901server # [ 10.330815] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61902server # [ 10.332068] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51903server # [ 10.341718] systemd[1]: Starting Virtual Console Setup...1904server # [ 10.350539] network-addresses-eth1-start[642]: adding address 192.168.1.2/24... done1905server # [ 10.373558] network-addresses-eth1-start[642]: adding address 2001:db8:1::2/64... done1906server # [ 10.402469] Console: switching to colour frame buffer device 160x501907server # [ 10.402450] systemd[1]: Finished Address configuration of eth1.1908server # [ 10.553923] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1909server # [ 10.471782] dhcpcd[653]: dhcpcd-10.3.2 starting1910server # [ 10.477858] systemd-logind[552]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1911server # [ 10.489060] dhcpcd[710]: dev: loaded udev1912server # [ 10.492905] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1913server # [ 10.494377] systemd[1]: Stopped Virtual Console Setup.1914server # [ 10.497510] systemd[1]: Starting Virtual Console Setup...1915server # [ 10.508441] systemd[1]: Finished Extra networking commands..1916server # [ 10.509236] systemd[1]: Reached target Network.1917server # [ 10.515122] systemd-logind[552]: Watching system buttons on /dev/input/event2 (Power Button)1918server # [ 10.516308] systemd[1]: Started Mock OIDC server for testing.1919server # [ 10.521606] systemd[1]: Starting Nginx Web Server...1920server # [ 10.529620] systemd[1]: Starting PostgreSQL Server...1921server # [ 10.668082] 8021q: 802.1Q VLAN Support v1.81922server # [ 10.668498] 8021q: adding VLAN 0 to HW filter on device eth11923server # [ 10.539126] systemd[1]: Started RustFS S3-compatible object storage.1924server # [ 10.548808] systemd[1]: Starting Setup RustFS bucket...1925server # [ 10.558200] systemd[1]: Starting Permit User Sessions...1926server # [ 10.822518] cfg80211: Loading compiled-in X.509 certificates for regulatory database1927server # [ 10.852238] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1928server # [ 10.852991] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1929server # [ 10.855865] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21930server # [ 10.856702] cfg80211: failed to load regulatory.db1931builder # [ 10.735641] dhcpcd[689]: eth0: soliciting a DHCP lease1932server # [ 10.733425] systemd[1]: Finished Permit User Sessions.1933server # [ 10.739255] systemd[1]: Started Getty on tty1.1934server # [ 10.740161] systemd[1]: Reached target Login Prompts.1935builder # [ 10.896058] NET: Registered PF_PACKET protocol family1936builder # [ 10.765897] dhcpcd[689]: eth0: offered 10.0.2.15 from 10.0.2.21937builder # [ 10.769804] dhcpcd[689]: eth0: probing address 10.0.2.15/241938server # [ 11.023651] 8021q: adding VLAN 0 to HW filter on device eth01939server # [ 10.893691] dhcpcd[710]: eth0: waiting for carrier1940server # [ 10.895786] dhcpcd[710]: eth0: carrier acquired1941server # [ 10.900319] mock-oidc-server[716]: Mock OIDC Server running1942server # [ 10.902629] mock-oidc-server[716]: OIDC Address: 127.0.0.1:80801943server # [ 10.903937] mock-oidc-server[716]: Issue Address: 127.0.0.1:80811944server # [ 10.905854] mock-oidc-server[716]: Issuer: http://127.0.0.1:8080/oidc1945server # [ 10.908474] mock-oidc-server[716]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1946server # [ 10.910508] mock-oidc-server[716]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1947server # [ 10.912980] mock-oidc-server[716]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1948server # [ 10.930534] dhcpcd[710]: DUID 00:01:00:01:32:32:80:ff:52:54:00:12:34:561949server # [ 10.931695] dhcpcd[710]: eth0: IAID 00:12:34:561950server # [ 10.933161] dhcpcd[710]: eth0: adding address fe80::5054:ff:fe12:34561951server # [ 11.111112] ppdev: user-space parallel port driver1952server # [ 10.994742] systemd-logind[552]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1953server # [ 11.231579] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1954server # [ 11.163510] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1955server # [ 11.189838] nginx-pre-start[752]: nginx: the configuration file /nix/store/qklp9g1w1b16lk2fc6xbcrhhv79wkd5f-nginx.conf syntax is ok1956server # [ 11.192804] nginx-pre-start[752]: nginx: configuration file /nix/store/qklp9g1w1b16lk2fc6xbcrhhv79wkd5f-nginx.conf test is successful1957server # [ 11.331635] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1958server # [ 11.205875] systemd[1]: Started Nginx Web Server.1959server # [ 11.225408] postgresql-pre-start[757]: The files belonging to this database system will be owned by user "postgres".1960server # [ 11.228483] postgresql-pre-start[757]: This user must also own the server process.1961server # [ 11.240278] postgresql-pre-start[757]: The database cluster will be initialized with locale "en_US.UTF-8".1962server # [ 11.241758] postgresql-pre-start[757]: The default database encoding has accordingly been set to "UTF8".1963server # [ 11.243610] postgresql-pre-start[757]: The default text search configuration will be set to "english".1964server # [ 11.245161] postgresql-pre-start[757]: Data page checksums are enabled.1965server # [ 11.247145] postgresql-pre-start[757]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1966server # [ 11.249227] postgresql-pre-start[757]: creating subdirectories ... ok1967server # [ 11.252421] postgresql-pre-start[757]: selecting dynamic shared memory implementation ... posix1968server # [ 11.286315] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1969server # [ 11.287556] systemd[1]: Stopped Virtual Console Setup.1970server # [ 11.294161] systemd[1]: Starting Virtual Console Setup...1971server # [ 11.430842] postgresql-pre-start[757]: selecting default "max_connections" ... 1001972server # [ 11.676917] kvm_amd: TSC scaling supported1973server # [ 11.677380] kvm_amd: Nested Virtualization enabled1974server # [ 11.680352] kvm_amd: Nested Paging enabled1975server # [ 11.683197] kvm_amd: LBR virtualization supported1976server # [ 11.685316] kvm_amd: Virtual VMLOAD VMSAVE supported1977server # [ 11.687265] kvm_amd: Virtual GIF supported1978server # [ 11.689163] kvm_amd: Virtual NMI enabled1979server # [ 11.610304] postgresql-pre-start[757]: selecting default "shared_buffers" ... 128MB1980server # [ 11.789953] EDAC MC: Ver: 3.0.01981server # [ 11.765254] systemd-vconsole-setup[777]: Configuration of first virtual console was skipped, ignoring remaining ones.1982server # [ 11.770649] systemd[1]: Finished Virtual Console Setup.1983builder # [ 12.275402] dhcpcd[689]: eth0: soliciting an IPv6 router1984builder # [ 12.278368] dhcpcd[689]: eth0: Router Advertisement from fe80::21985builder # [ 12.280665] dhcpcd[689]: eth0: adding address fec0::5054:ff:fe12:3456/641986builder # [ 12.282871] dhcpcd[689]: eth0: adding route to fec0::/641987builder # [ 12.284687] dhcpcd[689]: eth0: adding default route via fe80::21988server # [ 12.373239] dhcpcd[710]: eth0: soliciting a DHCP lease1989server # [ 12.523254] NET: Registered PF_PACKET protocol family1990server # [ 12.394660] dhcpcd[710]: eth0: offered 10.0.2.15 from 10.0.2.21991server # [ 12.397183] dhcpcd[710]: eth0: probing address 10.0.2.15/241992server # [ 13.188246] dhcpcd[710]: eth0: soliciting an IPv6 router1993server # [ 13.189664] dhcpcd[710]: eth0: Router Advertisement from fe80::21994server # [ 13.190752] dhcpcd[710]: eth0: adding address fec0::5054:ff:fe12:3456/641995server # [ 13.191854] dhcpcd[710]: eth0: adding route to fec0::/641996server # [ 13.192978] dhcpcd[710]: eth0: adding default route via fe80::21997server # [ 13.698969] postgresql-pre-start[757]: selecting default time zone ... UTC1998server # [ 13.703158] postgresql-pre-start[757]: creating configuration files ... ok1999server # [ 13.929786] postgresql-pre-start[757]: running bootstrap script ... ok2000server # [ 14.455516] postgresql-pre-start[757]: performing post-bootstrap initialization ... ok2001server # [ 14.597689] postgresql-pre-start[757]: syncing data to disk ... ok2002server # [ 14.599490] postgresql-pre-start[757]: initdb: warning: enabling "trust" authentication for local connections2003server # [ 14.600710] postgresql-pre-start[757]: 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.2004server # [ 14.602674] postgresql-pre-start[757]: Success. You can now start the database server using:2005server # [ 14.603689] postgresql-pre-start[757]: pg_ctl -D /var/lib/postgresql/18 -l logfile start2006server # [ 14.724371] postgres[822]: [822] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit2007server # [ 14.728473] postgres[822]: [822] LOG: listening on IPv6 address "::1", port 54322008server # [ 14.729570] postgres[822]: [822] LOG: listening on IPv4 address "127.0.0.1", port 54322009server # [ 14.733120] postgres[822]: [822] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2010server # [ 14.746426] postgres[831]: [831] LOG: database system was shut down at 2026-09-08 08:17:07 GMT2011server # [ 14.752971] postgres[822]: [822] LOG: database system is ready to accept connections2012server # [ 14.758364] systemd[1]: Started PostgreSQL Server.2013server # [ 14.763896] systemd[1]: Starting PostgreSQL Setup Scripts...2014server # [ 14.960664] postgresql-setup-start[842]: CREATE DATABASE2015server # [ 15.008444] postgresql-setup-start[847]: CREATE ROLE2016server # [ 15.030979] postgresql-setup-start[849]: ALTER DATABASE2017server # [ 15.037563] systemd[1]: Finished PostgreSQL Setup Scripts.2018server # [ 15.038652] systemd[1]: Reached target PostgreSQL.2019server: (finished: waiting for unit postgresql.service, in 16.18 seconds)2020server: waiting for unit rustfs.service2021server: (finished: waiting for unit rustfs.service, in 0.06 seconds)2022server: waiting for unit rustfs-setup.service2023builder # [ 16.283662] dhcpcd[689]: eth0: leased 10.0.2.15 for 86400 seconds2024builder # [ 16.286499] dhcpcd[689]: eth0: adding route to 10.0.2.0/242025builder # [ 16.288721] dhcpcd[689]: eth0: adding default route via 10.0.2.22026builder # [ 16.411626] systemd[1]: Started DHCP Client.2027builder # [ 16.413750] systemd[1]: Reached target Multi-User System.2028builder # [ 16.414718] systemd[1]: Startup finished in 871ms (kernel) + 4.190s (initrd) + 11.351s (userspace) = 16.413s.2029server # [ 17.967776] dhcpcd[710]: eth0: leased 10.0.2.15 for 86400 seconds2030server # [ 17.971671] dhcpcd[710]: eth0: adding route to 10.0.2.0/242031server # [ 17.974220] dhcpcd[710]: eth0: adding default route via 10.0.2.22032server # [ 18.104689] systemd[1]: Started DHCP Client.2033server # [ 23.524639] rustfs-setup-start[954]: mb s3://niks3-test2034server # [ 23.529370] systemd[1]: Finished Setup RustFS bucket.2035server # [ 23.536701] systemd[1]: Starting niks3 server...2036server # [ 23.758910] postgres[968]: [968] ERROR: relation "goose_db_version" does not exist at character 362037server # [ 23.760695] postgres[968]: [968] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC2038server # [ 23.796753] niks3-server[961]: 2026/09/08 08:17:16 OK 20241026095416_initial_model.sql (20.2ms)2039server # [ 23.805934] niks3-server[961]: 2026/09/08 08:17:16 OK 20251210153512_drop_unused_gin_index.sql (3.82ms)2040server # [ 23.809257] niks3-server[961]: 2026/09/08 08:17:16 OK 20251218171726_add_pins.sql (4.39ms)2041server # [ 23.811927] niks3-server[961]: 2026/09/08 08:17:16 OK 20260628120000_add_object_size_and_stats.sql (6.94ms)2042server # [ 23.813246] niks3-server[961]: 2026/09/08 08:17:16 goose: successfully migrated database to version: 202606281200002043server # [ 23.819992] niks3-server[961]: 2026/09/08 08:17:16 OK 1_commit_pending_closure.sql (5.4ms)2044server # [ 23.822981] niks3-server[961]: 2026/09/08 08:17:16 OK 2_object_stats_trigger.sql (1.59ms)2045server # [ 23.824311] niks3-server[961]: 2026/09/08 08:17:16 goose: up to current file version: 22046server # [ 23.852610] niks3-server[961]: 2026/09/08 08:17:16 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc2047server # [ 23.854417] niks3-server[961]: 2026/09/08 08:17:16 INFO OIDC authentication enabled config=/nix/store/z6045pdivqf5pqlg34fhh8w07v284xnl-niks3-oidc.json2048server # [ 23.856883] niks3-server[961]: 2026/09/08 08:17:16 INFO Loaded signing key name=niks3-test-1 path=/nix/store/q21mypxclhwpjnls1yg49g73bmn6m6f3-niks3-signing-key2049server # [ 23.913253] niks3-server[961]: 2026/09/08 08:17:16 INFO Using socket-activated listener address=0.0.0.0:57512050server # [ 23.916303] systemd[1]: Started niks3 server.2051server # [ 23.918636] systemd[1]: Reached target Multi-User System.2052server # [ 23.920130] niks3-server[961]: 2026/09/08 08:17:16 INFO systemd watchdog enabled interval=15s2053server # [ 23.921718] niks3-server[961]: 2026/09/08 08:17:16 INFO Starting HTTP server address=0.0.0.0:57512054server # [ 23.923234] systemd[1]: Startup finished in 864ms (kernel) + 5.094s (initrd) + 17.959s (userspace) = 23.918s.2055server: (finished: waiting for unit rustfs-setup.service, in 8.53 seconds)2056server: waiting for unit mock-oidc.service2057server: (finished: waiting for unit mock-oidc.service, in 0.05 seconds)2058server: waiting for unit niks3.service2059server: (finished: waiting for unit niks3.service, in 0.05 seconds)2060server: waiting for TCP port 5751 on localhost2061server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!2062server: (finished: waiting for TCP port 5751 on localhost, in 0.08 seconds)2063server: waiting for TCP port 8080 on localhost2064server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!2065server: (finished: waiting for TCP port 8080 on localhost, in 0.03 seconds)2066server: waiting for TCP port 9000 on localhost2067server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!2068server: (finished: waiting for TCP port 9000 on localhost, in 0.03 seconds)2069server: must succeed: mkdir -p /tmp/test-config2070server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.02 seconds)2071server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token2072server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)2073server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32074server # [ 24.498934] systemd[1]: Created slice Slice /system/nix-daemon.2075server # [ 24.503296] systemd[1]: Started Nix Daemon instance (PID 1012/UID 0).2076server # [ 24.602129] nix-daemon[1014]: remote pid 1012 is unknown user (trusted)2077server # [ 24.635692] systemd[1]: nix-daemon@0-1-1012_1013-0.service: Deactivated successfully.2078server # [ 24.670890] niks3-server[961]: 2026/09/08 08:17:17 INFO Received uploads request method=POST path=/api/pending_closures2079server # time=2026-09-08T08:17:17.672Z level=INFO msg="Uploading 5 paths to server (0 already cached)"2080server # time=2026-09-08T08:17:17.675Z level=INFO msg="Uploading wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3 (273.1KB)"2081server # time=2026-09-08T08:17:17.683Z level=INFO msg="Uploading n51dhmdbik1kfrsm62j5knavmigwrl1a-glibc-2.42-84 (33.4MB)"2082server # time=2026-09-08T08:17:17.685Z level=INFO msg="Uploading ssvq1r0xd8f7paf6zqgpfql1a4drwhy2-xgcc-15.3.0-libgcc (193.0KB)"2083server # time=2026-09-08T08:17:17.689Z level=INFO msg="Uploading yh8rykx8wakl1ccn8rc351f6r2wbg4cn-libunistring-1.4.2 (2.0MB)"2084server # time=2026-09-08T08:17:17.690Z level=INFO msg="Uploading nga9d6m9iplygw3iqghk2g840nz7b0gy-libidn2-2.3.8 (359.5KB)"2085server # [ 24.816732] niks3-server[961]: 2026/09/08 08:17:17 INFO Registered completed upload object_key=nar/1bvhsl1iqnbbgmxgp2hi0j8nj5ip5n6h6m2awanbnhwrl8fazjvh.nar.zst2086server # [ 24.842135] niks3-server[961]: 2026/09/08 08:17:17 INFO Registered completed upload object_key=ssvq1r0xd8f7paf6zqgpfql1a4drwhy2.ls2087server # [ 24.905369] niks3-server[961]: 2026/09/08 08:17:17 INFO Registered completed upload object_key=nar/178xz8sz0llzvvx7zc6zhqyhdv4d6n621g6yxmxhiabma9bwb14s.nar.zst2088server # [ 24.923072] niks3-server[961]: 2026/09/08 08:17:17 INFO Registered completed upload object_key=yh8rykx8wakl1ccn8rc351f6r2wbg4cn.ls2089server # [ 25.029441] niks3-server[961]: 2026/09/08 08:17:17 INFO Registered completed upload object_key=nar/0l0mcrrkj3msvp6j9f4mhkpkc3i3xvhf1yx4brgs6q0wb6nrwhx6.nar.zst2090server # [ 25.041694] niks3-server[961]: 2026/09/08 08:17:18 INFO Registered completed upload object_key=nga9d6m9iplygw3iqghk2g840nz7b0gy.ls2091server # [ 25.105648] niks3-server[961]: 2026/09/08 08:17:18 INFO Registered completed upload object_key=nar/1mklj120xjz7k0pddd6pvfisaxv5ym1lpb1bvvha6jiqykr17kcq.nar.zst2092server # [ 25.115704] niks3-server[961]: 2026/09/08 08:17:18 INFO Registered completed upload object_key=wzr035k31pmpn2caabq8qwv1npg571z9.ls2093server # [ 26.934583] niks3-server[961]: 2026/09/08 08:17:19 INFO Received complete multipart upload request method=POST path=/api/multipart/complete2094server # [ 26.956497] niks3-server[961]: 2026/09/08 08:17:19 INFO Completed multipart upload object_key=nar/0ypl8gla1lil5zqb5ib1pi8p7niwiq11mcvpmbfnypvnz4n1k2j2.nar.zst upload_id=MmIzZjdhOTYtZjYzZS00YTQ4LTlmM2EtNmNjZmNiN2Q1Y2FmLjljZDE5ZjRkLTIyYmMtNDYxMS04YmJjLTMzZTJhOTQ4ZTlmNXgxNzg4ODU1NDM3NjU1OTc2MjQ2 parts=12095server # [ 26.967543] niks3-server[961]: 2026/09/08 08:17:19 INFO Registered completed upload object_key=n51dhmdbik1kfrsm62j5knavmigwrl1a.ls2096server # [ 26.970063] niks3-server[961]: 2026/09/08 08:17:19 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign2097server # [ 26.974412] niks3-server[961]: 2026/09/08 08:17:19 INFO Signed narinfos id=1 count=52098server # time=2026-09-08T08:17:19.937Z level=INFO msg="Uploading 5 narinfos"2099server # [ 27.007066] niks3-server[961]: 2026/09/08 08:17:19 INFO Registered completed upload object_key=nga9d6m9iplygw3iqghk2g840nz7b0gy.narinfo2100server # [ 27.014211] niks3-server[961]: 2026/09/08 08:17:19 INFO Registered completed upload object_key=ssvq1r0xd8f7paf6zqgpfql1a4drwhy2.narinfo2101server # [ 27.038995] niks3-server[961]: 2026/09/08 08:17:20 INFO Registered completed upload object_key=wzr035k31pmpn2caabq8qwv1npg571z9.narinfo2102server # [ 27.041960] niks3-server[961]: 2026/09/08 08:17:20 INFO Registered completed upload object_key=n51dhmdbik1kfrsm62j5knavmigwrl1a.narinfo2103server # [ 27.043731] niks3-server[961]: 2026/09/08 08:17:20 INFO Registered completed upload object_key=yh8rykx8wakl1ccn8rc351f6r2wbg4cn.narinfo2104server # [ 27.045848] niks3-server[961]: 2026/09/08 08:17:20 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete2105server # [ 27.051344] niks3-server[961]: 2026/09/08 08:17:20 INFO Completed upload id=12106server # time=2026-09-08T08:17:20.014Z level=INFO msg="Upload complete. (2.61s)"2107server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 2.76 seconds)2108server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token2109server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)2110server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32111server # [ 27.210045] systemd[1]: Started Nix Daemon instance (PID 1043/UID 0).2112server # [ 27.284300] nix-daemon[1045]: remote pid 1043 is unknown user (trusted)2113server # [ 27.297209] systemd[1]: nix-daemon@1-2-1043_1044-0.service: Deactivated successfully.2114server # [ 27.305453] niks3-server[961]: 2026/09/08 08:17:20 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]2115server # time=2026-09-08T08:17:20.269Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2116server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.24 seconds)2117server: waiting for unit nginx.service2118server: (finished: waiting for unit nginx.service, in 0.04 seconds)2119server: waiting for TCP port 443 on localhost2120server # Connection to localhost (::1) 443 port [tcp/https] succeeded!2121server: (finished: waiting for TCP port 443 on localhost, in 0.03 seconds)2122server: must succeed: /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32123server # time=2026-09-08T08:17:20.424Z 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.pem2124server # [ 27.532273] systemd[1]: Started Nix Daemon instance (PID 1070/UID 0).2125server # [ 27.603663] nix-daemon[1072]: remote pid 1070 is unknown user (trusted)2126server # [ 27.616726] systemd[1]: nix-daemon@2-3-1070_1071-0.service: Deactivated successfully.2127server # [ 27.661463] niks3-server[961]: 2026/09/08 08:17:20 INFO Received uploads request method=POST path=/api/pending_closures2128server # time=2026-09-08T08:17:20.628Z level=INFO msg="Uploading 0 paths to server (5 already cached)"2129server # [ 27.668449] niks3-server[961]: 2026/09/08 08:17:20 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete2130server # [ 27.671382] niks3-server[961]: 2026/09/08 08:17:20 INFO Completed upload id=22131server # time=2026-09-08T08:17:20.634Z level=INFO msg="Upload complete. (201ms)"2132server: (finished: must succeed: /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.29 seconds)2133server: must fail: /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32134server # time=2026-09-08T08:17:20.656Z 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)"2135server: (finished: must fail: /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.02 seconds)2136server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32137server # time=2026-09-08T08:17:20.736Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem2138server # [ 27.836503] systemd[1]: Started Nix Daemon instance (PID 1097/UID 0).2139server # [ 27.913733] nix-daemon[1099]: remote pid 1097 is unknown user (trusted)2140server # [ 27.928607] systemd[1]: nix-daemon@3-4-1097_1098-0.service: Deactivated successfully.2141server # [ 27.940507] niks3-server[961]: 2026/09/08 08:17:20 INFO Received uploads request method=POST path=/api/pending_closures2142server # time=2026-09-08T08:17:20.906Z level=INFO msg="Uploading 0 paths to server (5 already cached)"2143server # [ 27.946915] niks3-server[961]: 2026/09/08 08:17:20 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete2144server # [ 27.949142] niks3-server[961]: 2026/09/08 08:17:20 INFO Completed upload id=32145server # time=2026-09-08T08:17:20.912Z level=INFO msg="Upload complete. (174ms)"2146server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.26 seconds)2147server: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'2148server # -----2149server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.03 seconds)2150server: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem2151server # Certificate request self-signature ok2152server # subject=CN=other client2153server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/63b5kdhvzs4sq7xgg8bikkvdv3kf7c7p-openssl-3.6.3-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.04 seconds)2154server: must fail: /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32155server # time=2026-09-08T08:17:21.074Z 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.pem2156server # [ 28.188800] systemd[1]: Started Nix Daemon instance (PID 1124/UID 0).2157server # [ 28.273398] nix-daemon[1126]: remote pid 1124 is unknown user (trusted)2158server # [ 28.286847] systemd[1]: nix-daemon@4-5-1124_1125-0.service: Deactivated successfully.2159server # [ 28.298471] niks3-server[961]: 2026/09/08 08:17:21 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2160server # time=2026-09-08T08:17:21.262Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2161server: (finished: must fail: /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3, in 0.28 seconds)2162server: must succeed: mkdir -p /tmp/test-store2163server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.03 seconds)2164server: must succeed: 2165 export AWS_ACCESS_KEY_ID=rustfsadmin2166export AWS_SECRET_ACCESS_KEY=rustfsadmin2167 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.321682169server: (finished: must succeed: 2170 export AWS_ACCESS_KEY_ID=rustfsadmin2171export AWS_SECRET_ACCESS_KEY=rustfsadmin2172 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32173, in 0.66 seconds)2174server: must succeed: 2175cat > /tmp/test-drv.nix << 'EOF'2176derivation {2177 name = "test-build-log";2178 system = builtins.currentSystem;2179 builder = "/bin/sh";2180 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2181}2182EOF21832184server: (finished: must succeed: 2185cat > /tmp/test-drv.nix << 'EOF'2186derivation {2187 name = "test-build-log";2188 system = builtins.currentSystem;2189 builder = "/bin/sh";2190 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2191}2192EOF2193, in 0.03 seconds)2194server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix2195server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2196server # [ 29.116333] systemd[1]: Started Nix Daemon instance (PID 1169/UID 0).2197server # [ 29.203287] nix-daemon[1173]: remote pid 1169 is unknown user (trusted)2198server # this derivation will be built:2199server # /nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2200server # building '/nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv'...2201server # test-build-log> test build log output2202server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.39 seconds)2203server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2204server # [ 29.415750] systemd[1]: nix-daemon@5-6-1169_1170-0.service: Deactivated successfully.2205server # [ 29.572847] systemd[1]: Started Nix Daemon instance (PID 1199/UID 0).2206server # [ 29.658800] nix-daemon[1201]: remote pid 1199 is unknown user (trusted)2207server # [ 29.672784] systemd[1]: nix-daemon@6-7-1199_1200-0.service: Deactivated successfully.2208server # [ 29.684558] niks3-server[961]: 2026/09/08 08:17:22 INFO Received uploads request method=POST path=/api/pending_closures2209server # time=2026-09-08T08:17:22.651Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2210server # time=2026-09-08T08:17:22.652Z level=INFO msg="Uploading dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log (128B)"2211server # [ 29.707935] niks3-server[961]: 2026/09/08 08:17:22 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst2212server # [ 29.714221] niks3-server[961]: 2026/09/08 08:17:22 INFO Registered completed upload object_key=log/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2213server # [ 29.719797] niks3-server[961]: 2026/09/08 08:17:22 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.ls2214server # [ 29.721783] niks3-server[961]: 2026/09/08 08:17:22 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign2215server # [ 29.724106] niks3-server[961]: 2026/09/08 08:17:22 INFO Signed narinfos id=4 count=12216server # time=2026-09-08T08:17:22.686Z level=INFO msg="Uploading 1 narinfos"2217server # [ 29.733168] niks3-server[961]: 2026/09/08 08:17:22 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2218server # [ 29.734607] niks3-server[961]: 2026/09/08 08:17:22 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete2219server # time=2026-09-08T08:17:22.698Z level=INFO msg="Upload complete. (235ms)"2220server # [ 29.737917] niks3-server[961]: 2026/09/08 08:17:22 INFO Completed upload id=42221server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log, in 0.34 seconds)2222server: must succeed: 2223 export AWS_ACCESS_KEY_ID=rustfsadmin2224export AWS_SECRET_ACCESS_KEY=rustfsadmin2225 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log22262227server: (finished: must succeed: 2228 export AWS_ACCESS_KEY_ID=rustfsadmin2229export AWS_SECRET_ACCESS_KEY=rustfsadmin2230 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2231, in 0.18 seconds)2232server: must succeed: readlink /etc/niks3-test/symlink-wrapper2233server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.03 seconds)2234server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2235server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.03 seconds)2236server: must succeed: test -L /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2237server: (finished: must succeed: test -L /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper, in 0.01 seconds)2238server: must succeed: readlink /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2239server: (finished: must succeed: readlink /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper, in 0.02 seconds)2240server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2241server # [ 30.188584] systemd[1]: Started Nix Daemon instance (PID 1243/UID 0).2242server # [ 30.287268] nix-daemon[1245]: remote pid 1243 is unknown user (trusted)2243server # [ 30.302265] systemd[1]: nix-daemon@7-8-1243_1244-0.service: Deactivated successfully.2244server # [ 30.310110] niks3-server[961]: 2026/09/08 08:17:23 INFO Received uploads request method=POST path=/api/pending_closures2245server # time=2026-09-08T08:17:23.283Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2246server # time=2026-09-08T08:17:23.284Z level=INFO msg="Uploading ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper (192B)"2247server # time=2026-09-08T08:17:23.285Z level=INFO msg="Uploading la1lgrdh5wdf2jsxac162fx3pm4xmz7i-base-package (536B)"2248server # [ 30.338907] niks3-server[961]: 2026/09/08 08:17:23 INFO Registered completed upload object_key=nar/1pm27rpdcg3viv7qb7xqnvwv3khbddfj346mvzadxaiy7ydifcaw.nar.zst2249server # [ 30.346647] niks3-server[961]: 2026/09/08 08:17:23 INFO Registered completed upload object_key=ljlv7af0zhbpp672nplky93kj5hm7m9h.ls2250server # [ 30.351341] niks3-server[961]: 2026/09/08 08:17:23 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2251server # [ 30.358354] niks3-server[961]: 2026/09/08 08:17:23 INFO Registered completed upload object_key=la1lgrdh5wdf2jsxac162fx3pm4xmz7i.ls2252server # [ 30.360205] niks3-server[961]: 2026/09/08 08:17:23 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign2253server # [ 30.361807] niks3-server[961]: 2026/09/08 08:17:23 INFO Signed narinfos id=5 count=22254server # time=2026-09-08T08:17:23.324Z level=INFO msg="Uploading 2 narinfos"2255server # [ 30.371146] niks3-server[961]: 2026/09/08 08:17:23 INFO Registered completed upload object_key=ljlv7af0zhbpp672nplky93kj5hm7m9h.narinfo2256server # [ 30.375383] niks3-server[961]: 2026/09/08 08:17:23 INFO Registered completed upload object_key=la1lgrdh5wdf2jsxac162fx3pm4xmz7i.narinfo2257server # [ 30.376818] niks3-server[961]: 2026/09/08 08:17:23 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete2258server # [ 30.379609] niks3-server[961]: 2026/09/08 08:17:23 INFO Completed upload id=52259server # time=2026-09-08T08:17:23.342Z level=INFO msg="Upload complete. (265ms)"2260server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper, in 0.36 seconds)2261server: must succeed: 2262 export AWS_ACCESS_KEY_ID=rustfsadmin2263export AWS_SECRET_ACCESS_KEY=rustfsadmin2264 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper22652266server: (finished: must succeed: 2267 export AWS_ACCESS_KEY_ID=rustfsadmin2268export AWS_SECRET_ACCESS_KEY=rustfsadmin2269 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2270, in 0.19 seconds)2271server: must succeed: 2272cat > /tmp/oidc-test.nix << 'EOF'2273derivation {2274 name = "oidc-test";2275 system = builtins.currentSystem;2276 builder = "/bin/sh";2277 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2278}2279EOF22802281server: (finished: must succeed: 2282cat > /tmp/oidc-test.nix << 'EOF'2283derivation {2284 name = "oidc-test";2285 system = builtins.currentSystem;2286 builder = "/bin/sh";2287 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2288}2289EOF2290, in 0.03 seconds)2291server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2292server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2293server # [ 30.701484] systemd[1]: Started Nix Daemon instance (PID 1272/UID 0).2294server # [ 30.790532] nix-daemon[1276]: remote pid 1272 is unknown user (trusted)2295server # this derivation will be built:2296server # /nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2297server # building '/nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv'...2298server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.33 seconds)2299server: 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'2300server # [ 30.939352] systemd[1]: nix-daemon@8-9-1272_1273-0.service: Deactivated successfully.2301server: (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.08 seconds)2302server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODg4NTkwNDMsImlhdCI6MTc4ODg1NTQ0MywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.kOBAODAGJ7MIuyVzcOTFcm5TZ0cE3Se1vvjOOZzsYDc_fq8x9M3GohKgufblvCnNznsyqA0YREUlBlQPDCLgJS80x0eVUKXaGEu7OpHf2x72wUMNsDbxuLpovDPBkBqFpa0wWobmCXng8pVQqtOVATRZpMvO5QoUiiz9Wyhvb7Leab8lXEhEdpO8upf1p6oHP2aDiBh6Of9Wan3i3gszHiWk8c7yoOKZVITbC6wXkItySWrzlm6CNn6BnY0DoHRqv-9xc0U-9HVPKug7qZk7Pk01XnUtgRZ-GX64TzF1oldgBrLsMqVn9khp9QVOM4boEaIw_6lsXGICW1aN6VFhfA' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2303server # time=2026-09-08T08:17:24.001Z 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"2304server # [ 31.188939] systemd[1]: Started Nix Daemon instance (PID 1305/UID 0).2305server # [ 31.280671] nix-daemon[1307]: remote pid 1305 is unknown user (trusted)2306server # [ 31.295116] systemd[1]: nix-daemon@9-10-1305_1306-0.service: Deactivated successfully.2307server # [ 31.312801] niks3-server[961]: 2026/09/08 08:17:24 INFO OIDC auth successful provider=test scopes=[write]2308server # [ 31.314129] niks3-server[961]: 2026/09/08 08:17:24 INFO Received uploads request method=POST path=/api/pending_closures2309server # time=2026-09-08T08:17:24.280Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2310server # time=2026-09-08T08:17:24.281Z level=INFO msg="Uploading hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test (136B)"2311server # [ 31.330426] niks3-server[961]: 2026/09/08 08:17:24 INFO OIDC auth successful provider=test scopes=[write]2312server # [ 31.335861] niks3-server[961]: 2026/09/08 08:17:24 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2313server # [ 31.339647] niks3-server[961]: 2026/09/08 08:17:24 INFO OIDC auth successful provider=test scopes=[write]2314server # [ 31.343764] niks3-server[961]: 2026/09/08 08:17:24 INFO Registered completed upload object_key=log/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2315server # [ 31.346940] niks3-server[961]: 2026/09/08 08:17:24 INFO OIDC auth successful provider=test scopes=[write]2316server # [ 31.350201] niks3-server[961]: 2026/09/08 08:17:24 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.ls2317server # [ 31.352338] niks3-server[961]: 2026/09/08 08:17:24 INFO OIDC auth successful provider=test scopes=[write]2318server # [ 31.353567] niks3-server[961]: 2026/09/08 08:17:24 INFO Received sign narinfos request method=POST path=/api/pending_closures/6/sign2319server # time=2026-09-08T08:17:24.317Z level=INFO msg="Uploading 1 narinfos"2320server # [ 31.356809] niks3-server[961]: 2026/09/08 08:17:24 INFO Signed narinfos id=6 count=12321server # [ 31.360917] niks3-server[961]: 2026/09/08 08:17:24 INFO OIDC auth successful provider=test scopes=[write]2322server # [ 31.364189] niks3-server[961]: 2026/09/08 08:17:24 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.narinfo2323server # [ 31.366430] niks3-server[961]: 2026/09/08 08:17:24 INFO OIDC auth successful provider=test scopes=[write]2324server # [ 31.367637] niks3-server[961]: 2026/09/08 08:17:24 INFO Received complete upload request method=POST path=/api/pending_closures/6/complete2325server # [ 31.370763] niks3-server[961]: 2026/09/08 08:17:24 INFO Completed upload id=62326server # time=2026-09-08T08:17:24.333Z level=INFO msg="Upload complete. (258ms)"2327server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODg4NTkwNDMsImlhdCI6MTc4ODg1NTQ0MywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.kOBAODAGJ7MIuyVzcOTFcm5TZ0cE3Se1vvjOOZzsYDc_fq8x9M3GohKgufblvCnNznsyqA0YREUlBlQPDCLgJS80x0eVUKXaGEu7OpHf2x72wUMNsDbxuLpovDPBkBqFpa0wWobmCXng8pVQqtOVATRZpMvO5QoUiiz9Wyhvb7Leab8lXEhEdpO8upf1p6oHP2aDiBh6Of9Wan3i3gszHiWk8c7yoOKZVITbC6wXkItySWrzlm6CNn6BnY0DoHRqv-9xc0U-9HVPKug7qZk7Pk01XnUtgRZ-GX64TzF1oldgBrLsMqVn9khp9QVOM4boEaIw_6lsXGICW1aN6VFhfA' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test, in 0.36 seconds)2328server: must succeed: 2329cat > /tmp/oidc-test2.nix << 'EOF'2330derivation {2331 name = "oidc-test2";2332 system = builtins.currentSystem;2333 builder = "/bin/sh";2334 args = [ "-c" "echo 'OIDC test 2' > $out" ];2335}2336EOF23372338server: (finished: must succeed: 2339cat > /tmp/oidc-test2.nix << 'EOF'2340derivation {2341 name = "oidc-test2";2342 system = builtins.currentSystem;2343 builder = "/bin/sh";2344 args = [ "-c" "echo 'OIDC test 2' > $out" ];2345}2346EOF2347, in 0.03 seconds)2348server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2349server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2350server # [ 31.504535] systemd[1]: Started Nix Daemon instance (PID 1318/UID 0).2351server # [ 31.597420] nix-daemon[1322]: remote pid 1318 is unknown user (trusted)2352server # this derivation will be built:2353server # /nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv2354server # building '/nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv'...2355server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.34 seconds)2356server: 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'2357server # [ 31.749321] systemd[1]: nix-daemon@10-11-1318_1319-0.service: Deactivated successfully.2358server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.05 seconds)2359server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODg4NTkwNDQsImlhdCI6MTc4ODg1NTQ0NCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.Zi1aCUOMtVZuaMaFk035VsTe40TvvRGrLZmzhFqLDTe39jnfXWZFG_EKJeLdiwK39ADyt3yn3r8v-a1g-9hCZfgIAmc7-Dr9LTVHIAt5Y5QGbTFQhIdNrFFgh_sbnycLQDbN22w29bdZvgNfgAQJUF3_MPLhMT3JpZvynVpa8WYJFzsLXMAviMWAnxahMbhPt_bo104sZEM9ff1cMpMJdUqCTAGA_npHDumMfbU48XB0dDvCZ13VTz6_c6t7jFWwIdtAQx9qF_X_BkR5ZytX_w89xaqnFXY2VkfYSkAZS3uszIOvn1FRKeuiQl1IDBQjqykIKwjaFvKMM21JMJIE4g' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22360server # time=2026-09-08T08:17:24.781Z 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"2361server # [ 31.974037] systemd[1]: Started Nix Daemon instance (PID 1352/UID 0).2362server # [ 32.070216] nix-daemon[1354]: remote pid 1352 is unknown user (trusted)2363server # [ 32.083678] systemd[1]: nix-daemon@11-12-1352_1353-0.service: Deactivated successfully.2364server # [ 32.092151] niks3-server[961]: 2026/09/08 08:17:25 WARN Authentication failed token_preview=eyJhbGciOi...M21JMJIE4g token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2365server # time=2026-09-08T08:17:25.057Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2366server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODg4NTkwNDQsImlhdCI6MTc4ODg1NTQ0NCwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.Zi1aCUOMtVZuaMaFk035VsTe40TvvRGrLZmzhFqLDTe39jnfXWZFG_EKJeLdiwK39ADyt3yn3r8v-a1g-9hCZfgIAmc7-Dr9LTVHIAt5Y5QGbTFQhIdNrFFgh_sbnycLQDbN22w29bdZvgNfgAQJUF3_MPLhMT3JpZvynVpa8WYJFzsLXMAviMWAnxahMbhPt_bo104sZEM9ff1cMpMJdUqCTAGA_npHDumMfbU48XB0dDvCZ13VTz6_c6t7jFWwIdtAQx9qF_X_BkR5ZytX_w89xaqnFXY2VkfYSkAZS3uszIOvn1FRKeuiQl1IDBQjqykIKwjaFvKMM21JMJIE4g' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.30 seconds)2367server: 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'2368server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.04 seconds)2369server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4ODg1OTA0NSwiaWF0IjoxNzg4ODU1NDQ1LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.UNhZ3y4sYXdmhvfjBY86rsfzczq9F4aKlsZpyoZNGhry80LRXTS4hCXTlTpZ66zR3tkFk8QxdhjRdmXShoHPuIDBGakJdN2TGDtBXOPo-g7odlt35m28JksmBrMniVGXP-4WELxa2Ztbdg0-VXE7fEfMOBKDMeRxMpjCB6yOB3yQRmEwz8Fzh2xUHpSmn5vborevXl23Pz5hINai_JmYNrgVb_CBnCMM4j4b4V1R4vNBL_wsOikkZPKps6kY-9hXFo6ES3ompDLayst47V0daKM7fwm2pBZsad0zkispVx_ON95X4eXqcrKqeaA-qDa0wWqQsxrC-2QbdM7Xv2koew' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22370server # time=2026-09-08T08:17:25.124Z 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"2371server # [ 32.294028] systemd[1]: Started Nix Daemon instance (PID 1374/UID 0).2372server # [ 32.373347] nix-daemon[1376]: remote pid 1374 is unknown user (trusted)2373server # [ 32.387715] systemd[1]: nix-daemon@12-13-1374_1375-0.service: Deactivated successfully.2374server # [ 32.396409] niks3-server[961]: 2026/09/08 08:17:25 WARN Authentication failed token_preview=eyJhbGciOi...dM7Xv2koew token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2375server # time=2026-09-08T08:17:25.360Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2376server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4ODg1OTA0NSwiaWF0IjoxNzg4ODU1NDQ1LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.UNhZ3y4sYXdmhvfjBY86rsfzczq9F4aKlsZpyoZNGhry80LRXTS4hCXTlTpZ66zR3tkFk8QxdhjRdmXShoHPuIDBGakJdN2TGDtBXOPo-g7odlt35m28JksmBrMniVGXP-4WELxa2Ztbdg0-VXE7fEfMOBKDMeRxMpjCB6yOB3yQRmEwz8Fzh2xUHpSmn5vborevXl23Pz5hINai_JmYNrgVb_CBnCMM4j4b4V1R4vNBL_wsOikkZPKps6kY-9hXFo6ES3ompDLayst47V0daKM7fwm2pBZsad0zkispVx_ON95X4eXqcrKqeaA-qDa0wWqQsxrC-2QbdM7Xv2koew' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.26 seconds)2377server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22378server # time=2026-09-08T08:17:25.386Z 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"2379server # [ 32.570459] systemd[1]: Started Nix Daemon instance (PID 1395/UID 0).2380server # [ 32.660369] nix-daemon[1397]: remote pid 1395 is unknown user (trusted)2381server # [ 32.674814] systemd[1]: nix-daemon@13-14-1395_1396-0.service: Deactivated successfully.2382server # [ 32.683181] niks3-server[961]: 2026/09/08 08:17:25 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]2383server # time=2026-09-08T08:17:25.647Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2384server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.29 seconds)2385server: must succeed: 2386 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins create hello-pin /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.323872388server # [ 32.792679] niks3-server[961]: 2026/09/08 08:17:25 INFO Received create pin request method=POST path=/api/pins/hello-pin2389server # [ 32.801677] niks3-server[961]: 2026/09/08 08:17:25 INFO Created/updated pin name=hello-pin store_path=/nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.3 narinfo_key=wzr035k31pmpn2caabq8qwv1npg571z9.narinfo2390server # time=2026-09-08T08:17:25.765Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32391server: (finished: must succeed: 2392 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins create hello-pin /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32393, in 0.12 seconds)2394server: must succeed: 2395 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list23962397server # [ 32.907429] niks3-server[961]: 2026/09/08 08:17:25 INFO Received list pins request method=GET path=/api/pins2398server: (finished: must succeed: 2399 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list2400, in 0.10 seconds)2401server: must succeed: 2402 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --names-only24032404server # [ 33.010555] niks3-server[961]: 2026/09/08 08:17:25 INFO Received list pins request method=GET path=/api/pins2405server: (finished: must succeed: 2406 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --names-only2407, in 0.10 seconds)2408server: must succeed: 2409 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --json24102411server # [ 33.113824] niks3-server[961]: 2026/09/08 08:17:26 INFO Received list pins request method=GET path=/api/pins2412server: (finished: must succeed: 2413 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --json2414, in 0.10 seconds)2415server: must succeed: 2416 export S3_ENDPOINT_URL=http://localhost:90002417 export AWS_ACCESS_KEY_ID=rustfsadmin2418 export AWS_SECRET_ACCESS_KEY=rustfsadmin2419 /nix/store/x86g0y89gaymx0y932zhypbj1iwib7mp-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin24202421server: (finished: must succeed: 2422 export S3_ENDPOINT_URL=http://localhost:90002423 export AWS_ACCESS_KEY_ID=rustfsadmin2424 export AWS_SECRET_ACCESS_KEY=rustfsadmin2425 /nix/store/x86g0y89gaymx0y932zhypbj1iwib7mp-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2426, in 0.06 seconds)2427server: must succeed: 2428 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log24292430server # [ 33.346691] systemd[1]: Started Nix Daemon instance (PID 1472/UID 0).2431server # [ 33.437275] nix-daemon[1474]: remote pid 1472 is unknown user (trusted)2432server # [ 33.452149] systemd[1]: nix-daemon@14-15-1472_1473-0.service: Deactivated successfully.2433server # [ 33.459978] niks3-server[961]: 2026/09/08 08:17:26 INFO Received uploads request method=POST path=/api/pending_closures2434server # time=2026-09-08T08:17:26.425Z level=INFO msg="Uploading 0 paths to server (1 already cached)"2435server # [ 33.465916] niks3-server[961]: 2026/09/08 08:17:26 INFO Received complete upload request method=POST path=/api/pending_closures/7/complete2436server # [ 33.468282] niks3-server[961]: 2026/09/08 08:17:26 INFO Completed upload id=72437server # time=2026-09-08T08:17:26.431Z level=INFO msg="Upload complete. (194ms)"2438server # [ 33.470448] niks3-server[961]: 2026/09/08 08:17:26 INFO Received create pin request method=POST path=/api/pins/ca-pin2439server # [ 33.477277] niks3-server[961]: 2026/09/08 08:17:26 INFO Created/updated pin name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log narinfo_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2440server # time=2026-09-08T08:17:26.441Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2441server: (finished: must succeed: 2442 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2443, in 0.30 seconds)2444server: must succeed: 2445 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --names-only24462447server # [ 33.582380] niks3-server[961]: 2026/09/08 08:17:26 INFO Received list pins request method=GET path=/api/pins2448server: (finished: must succeed: 2449 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --names-only2450, in 0.10 seconds)2451server: must succeed: 2452 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins delete hello-pin24532454server # [ 33.688271] niks3-server[961]: 2026/09/08 08:17:26 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2455server # [ 33.706671] niks3-server[961]: 2026/09/08 08:17:26 INFO Deleted pin name=hello-pin2456server # time=2026-09-08T08:17:26.669Z level=INFO msg="Deleted pin" name=hello-pin2457server: (finished: must succeed: 2458 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins delete hello-pin2459, in 0.12 seconds)2460server: must succeed: 2461 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --names-only24622463server # [ 33.811377] niks3-server[961]: 2026/09/08 08:17:26 INFO Received list pins request method=GET path=/api/pins2464server: (finished: must succeed: 2465 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins list --names-only2466, in 0.10 seconds)2467server: must fail: 2468 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent24692470server # [ 33.911784] niks3-server[961]: 2026/09/08 08:17:26 INFO Received create pin request method=POST path=/api/pins/bad-pin2471server # [ 33.913742] niks3-server[961]: 2026/09/08 08:17:26 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2472server # time=2026-09-08T08:17:26.877Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2473server: (finished: must fail: 2474 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/3gb3ipy3vl3bw4d4qp1inha485z2bgia-niks3-1.10.1/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2475, in 0.10 seconds)2476server: must succeed: systemctl start niks3-gc.service2477server # [ 33.950247] systemd[1]: Starting niks3 garbage collection...2478server # [ 34.008760] niks3[1533]: time=2026-09-08T08:17:26.970Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2479server # [ 34.012600] niks3-server[961]: 2026/09/08 08:17:26 INFO Starting cleanup of old closures method=DELETE path=/api/closures2480server # [ 34.014989] niks3[1533]: time=2026-09-08T08:17:26.976Z level=INFO msg="Garbage collection started"2481server # [ 34.019494] niks3-server[961]: 2026/09/08 08:17:26 INFO Aborted multipart uploads count=02482server # [ 34.027871] niks3-server[961]: 2026/09/08 08:17:26 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=02483server # [ 34.032401] niks3-server[961]: 2026/09/08 08:17:26 INFO Vacuumed table table=pending_closures2484server # [ 34.036808] niks3-server[961]: 2026/09/08 08:17:26 INFO Vacuumed table table=pending_objects2485server # [ 34.040414] niks3-server[961]: 2026/09/08 08:17:27 INFO Vacuumed table table=multipart_uploads2486server # [ 34.044391] niks3-server[961]: 2026/09/08 08:17:27 INFO Vacuumed table table=closures2487server # [ 34.046424] niks3-server[961]: 2026/09/08 08:17:27 INFO Vacuumed table table=objects2488server # [ 36.017678] niks3[1533]: time=2026-09-08T08:17:28.978Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=2 objects_deleted=0 objects_failed=02489server # [ 36.024884] niks3[1533]: time=2026-09-08T08:17:28.978Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=02490server # [ 36.037369] systemd[1]: niks3-gc.service: Deactivated successfully.2491server # [ 36.040326] systemd[1]: Finished niks3 garbage collection.2492server # [ 36.044838] systemd[1]: niks3-gc.service: Consumed 35ms CPU time over 2.086s wall clock time, 2.7M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2493server: (finished: must succeed: systemctl start niks3-gc.service, in 2.14 seconds)2494builder: waiting for unit niks3-auto-upload.socket2495builder: waiting for the VM to finish booting2496builder: Guest shell says: b'Spawning backdoor root shell...\n'2497builder: connected to guest root shell2498builder: (connecting took 0.00 seconds)2499builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2500builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.10 seconds)2501builder: must succeed: test -S /run/niks3/upload-to-cache.sock2502builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2503builder: must succeed: grep post-build-hook /etc/nix/nix.conf2504builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.03 seconds)2505builder: must succeed: 2506cat > /tmp/test-drv.nix << 'EOF'2507derivation {2508 name = "post-build-hook-test";2509 system = builtins.currentSystem;2510 builder = "/bin/sh";2511 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2512}2513EOF25142515builder: (finished: must succeed: 2516cat > /tmp/test-drv.nix << 'EOF'2517derivation {2518 name = "post-build-hook-test";2519 system = builtins.currentSystem;2520 builder = "/bin/sh";2521 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2522}2523EOF2524, in 0.03 seconds)2525builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2526builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2527builder # [ 36.374546] systemd[1]: Created slice Slice /system/nix-daemon.2528builder # [ 36.378605] systemd[1]: Started Nix Daemon instance (PID 792/UID 0).2529builder # [ 36.461520] nix-daemon[796]: remote pid 792 is unknown user (trusted)2530builder # 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 541 ms (attempt 1/5)2531builder # 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 987 ms (attempt 2/5)2532builder # 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 2057 ms (attempt 3/5)2533builder # 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 4125 ms (attempt 4/5)2534builder # 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)2535builder # this derivation will be built:2536builder # /nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2537builder # building '/nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv'...2538builder # [ 44.581894] systemd[1]: Started niks3 auto-upload daemon.2539builder # [ 44.813459] niks3-hook[818]: time=2026-09-08T08:17:37.834Z 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=0s2540builder # [ 44.829099] niks3-hook[818]: time=2026-09-08T08:17:37.852Z level=INFO msg="Upload queue status" pending=12541builder # [ 44.830515] niks3-hook[818]: time=2026-09-08T08:17:37.852Z level=INFO msg="Uploading batch" count=12542builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 8.61 seconds)2543builder: waiting for unit niks3-auto-upload.service2544builder # [ 44.852225] systemd[1]: nix-daemon@0-1-792_793-0.service: Deactivated successfully.2545builder # [ 44.854441] systemd[1]: nix-daemon@0-1-792_793-0.service: Consumed 147ms CPU time over 8.475s wall clock time, 17M memory peak, 288B outgoing IP traffic.2546builder: (finished: waiting for unit niks3-auto-upload.service, in 0.12 seconds)2547??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2548 File "/nix/store/wqwvv2w9ih8r1f0lsa0h3fqf91nblq7g-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392549builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2550??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2551 File "/nix/store/wqwvv2w9ih8r1f0lsa0h3fqf91nblq7g-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392552builder # [ 44.980890] systemd[1]: Started Nix Daemon instance (PID 828/UID 0).2553builder # [ 45.074976] nix-daemon[839]: remote pid 828 is unknown user (trusted)2554builder # [ 45.090853] systemd[1]: nix-daemon@1-2-828_829-0.service: Deactivated successfully.2555server # [ 45.121922] niks3-server[961]: 2026/09/08 08:17:38 INFO Received uploads request method=POST path=/api/pending_closures2556builder # [ 45.140391] niks3-hook[818]: time=2026-09-08T08:17:38.164Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2557builder # [ 45.143914] niks3-hook[818]: time=2026-09-08T08:17:38.167Z level=INFO msg="Uploading ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test (144B)"2558server # [ 45.178704] niks3-server[961]: 2026/09/08 08:17:38 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2559server # [ 45.188415] niks3-server[961]: 2026/09/08 08:17:38 INFO Registered completed upload object_key=log/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2560server # [ 45.204249] niks3-server[961]: 2026/09/08 08:17:38 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.ls2561server # [ 45.207708] niks3-server[961]: 2026/09/08 08:17:38 INFO Received sign narinfos request method=POST path=/api/pending_closures/8/sign2562server # [ 45.211982] niks3-server[961]: 2026/09/08 08:17:38 INFO Signed narinfos id=8 count=12563builder # [ 45.220154] niks3-hook[818]: time=2026-09-08T08:17:38.242Z level=INFO msg="Uploading 1 narinfos"2564server # [ 45.225614] niks3-server[961]: 2026/09/08 08:17:38 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.narinfo2565server # [ 45.227342] niks3-server[961]: 2026/09/08 08:17:38 INFO Received complete upload request method=POST path=/api/pending_closures/8/complete2566server # [ 45.230955] niks3-server[961]: 2026/09/08 08:17:38 INFO Completed upload id=82567builder # [ 45.238336] niks3-hook[818]: time=2026-09-08T08:17:38.262Z level=INFO msg="Upload complete. (408ms)"2568builder # [ 49.826499] niks3-hook[818]: time=2026-09-08T08:17:42.849Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2569builder # [ 49.831337] niks3-hook[818]: time=2026-09-08T08:17:42.854Z level=INFO msg="niks3-hook serve stopped"2570builder # [ 49.846544] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2571builder # [ 49.850687] systemd[1]: niks3-auto-upload.service: Consumed 138ms CPU time over 5.264s wall clock time, 9.7M memory peak, 68K written to disk, 5.1K incoming IP traffic, 7.5K outgoing IP traffic.2572builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.33 seconds)2573server: must succeed: 2574 export AWS_ACCESS_KEY_ID=rustfsadmin2575export AWS_SECRET_ACCESS_KEY=rustfsadmin2576 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-test25772578server: (finished: must succeed: 2579 export AWS_ACCESS_KEY_ID=rustfsadmin2580export AWS_SECRET_ACCESS_KEY=rustfsadmin2581 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-test2582, in 0.25 seconds)2583server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2584server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test, in 0.10 seconds)2585(finished: run the VM test script, in 51.83 seconds)2586test script finished in 51.95s2587cleanup2588kill QemuMachine (pid 47)2589builder # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2590kill QemuMachine (pid 48)2591server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2592(finished: cleanup, in 0.39 seconds)2593additionally exposed symbols:2594 builder, server,2595 vlan1,2596 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_ssh2597Hello store path: /nix/store/wzr035k31pmpn2caabq8qwv1npg571z9-hello-2.12.32598Test output path: /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2599Symlink wrapper store path: /nix/store/ljlv7af0zhbpp672nplky93kj5hm7m9h-symlink-wrapper2600Symlink wrapper points to: /nix/store/la1lgrdh5wdf2jsxac162fx3pm4xmz7i-base-package/bin/test-program2601OIDC test store path: /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2602Valid OIDC token obtained (length=677)2603OIDC push with valid token: SUCCESS2604Invalid OIDC token obtained (wrong org)2605OIDC push with wrong org: correctly rejected2606Wrong audience OIDC token obtained2607OIDC push with wrong audience: correctly rejected2608OIDC push with malformed token: correctly rejected2609All OIDC tests passed!2610All pin tests passed!2611Built store path: /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2612Post-build-hook pipeline test passed!