vm-test-run-nixos-test-niks3
checks.x86_64-linux.nixos-test-niks3
· build #401
· 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 # Formatting '/build/vm-state-builder/tmp.9p2Dc4eh4g', fmt=raw size=107374182412builder: QEMU running (pid 47)13builder # mke2fs 1.47.4 (6-Mar-2025)14server # Disk image does not exist, creating the virtualisation disk image...15builder # Discarding device blocks: 0/262144 done16server # Formatting '/build/vm-state-server/tmp.fzR39BI02h', fmt=raw size=107374182417builder # Creating filesystem with 262144 4k blocks and 65536 inodes18server # mke2fs 1.47.4 (6-Mar-2025)19builder # Filesystem UUID: 34a35e01-97a0-40b0-9984-04e7c3b2212c20server # Discarding device blocks: 0/262144 done21builder # Superblock backups stored on blocks:22server # Creating filesystem with 262144 4k blocks and 65536 inodes23builder # 32768, 98304, 163840, 22937624server # Filesystem UUID: 42546167-a138-4f00-94c0-ba1fe6d4d8b025builder # 26server # Superblock backups stored on blocks:27builder # Allocating group tables: 0/8 done28server # 32768, 98304, 163840, 22937629builder # Writing inode tables: 0/8 done30server # 31builder # Creating journal (8192 blocks): done32server # Allocating group tables: 0/8 done33builder # Writing superblocks and filesystem accounting information: 0/8 done34server # Writing inode tables: 0/8 done35builder # 36server # Creating journal (8192 blocks): done37builder # Virtualisation disk image created.38server # Writing superblocks and filesystem accounting information: 0/8 done39builder # Starting virtiofs daemons...40server # 41builder # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)42server # Virtualisation disk image created.43builder # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether44server # Starting virtiofs daemons...45builder # [2026-10-08T21:39:35Z INFO virtiofsd] Waiting for vhost-user socket connection...46server # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)47builder # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)48server # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether49builder # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether50server # [2026-10-08T21:39:35Z INFO virtiofsd] Waiting for vhost-user socket connection...51builder # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)52server # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)53builder # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether54server # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether55builder # [2026-10-08T21:39:35Z INFO virtiofsd] Waiting for vhost-user socket connection...56server # [2026-10-08T21:39:35Z INFO virtiofsd] Waiting for vhost-user socket connection...57builder # [2026-10-08T21:39:35Z INFO virtiofsd] Waiting for vhost-user socket connection...58server # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)59builder # [2026-10-08T21:39:35Z INFO virtiofsd] Client connected, servicing requests60server # [2026-10-08T21:39:35Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61builder # [2026-10-08T21:39:35Z INFO virtiofsd] Client connected, servicing requests62server # [2026-10-08T21:39:35Z INFO virtiofsd] Waiting for vhost-user socket connection...63builder # [2026-10-08T21:39:35Z INFO virtiofsd] Client connected, servicing requests64server # [2026-10-08T21:39:35Z INFO virtiofsd] Client connected, servicing requests65server: QEMU running (pid 48)66server # [2026-10-08T21:39:35Z INFO virtiofsd] Client connected, servicing requests67server # [2026-10-08T21:39:35Z INFO virtiofsd] Client connected, servicing requests68(finished: start all VMs, in 0.24 seconds)69server: waiting for unit postgresql.service70server: waiting for the VM to finish booting71builder # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)72server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)73builder # 74builder # 75builder # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0076builder # Press Ctrl-B to configure iPXE (PCI 00:02.0)...77builder # 78builder # 79builder # 80builder # 81server # 82server # 83builder # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0084server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0085server # Press Ctrl-B to configure iPXE (PCI 00:02.0)...86server # 87server # 88server # 89server # 90builder # Press Ctrl-B to configure iPXE (PCI 00:05.0)...91builder # 92builder # 93server # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0094server # Press Ctrl-B to configure iPXE (PCI 00:05.0)...95server # 96server # 97builder # Booting from ROM...98server # Booting from ROM...99builder # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026100builder # [ 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/bwhdkbz57s1q9shrfdr9q266f8k9fv3w-nixos-system-builder-test/init regInfo=/nix/.ro-store/bjl0rgzjm4x48b5jx17cgyg6wvlfqnxx-closure-info/registration console=ttyS0,115200n8 console=tty0101builder # [ 0.000000] BIOS-provided physical RAM map:102builder # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable103builder # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved104builder # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved105server # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026106builder # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable107builder # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved108builder # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved109builder # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved110builder # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved111server # [ 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/yvlpxrjs96a6lmak3yh8krwrs49cbrw1-nixos-system-server-test/init regInfo=/nix/.ro-store/vk4vwl6dk39nbrfr67z8y8mfhgg85mg1-closure-info/registration console=ttyS0,115200n8 console=tty0112server # [ 0.000000] BIOS-provided physical RAM map:113builder # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved114server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable115builder # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved116server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved117builder # [ 0.000000] NX (Execute Disable) protection: active118builder # [ 0.000000] APIC: Static calls initialized119server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved120builder # [ 0.000000] SMBIOS 2.8 present.121server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable122builder # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014123server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved124builder # [ 0.000000] DMI: Memory slots populated: 1/1125builder # [ 0.000000] Hypervisor detected: KVM126server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved127builder # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000128server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved129builder # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00130server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved131builder # [ 0.000000] kvm-clock: using sched offset of 453584522 cycles132server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved133builder # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns134server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved135builder # [ 0.000004] tsc: Detected 2400.012 MHz processor136server # [ 0.000000] NX (Execute Disable) protection: active137builder # [ 0.000762] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000138server # [ 0.000000] APIC: Static calls initialized139server # [ 0.000000] SMBIOS 2.8 present.140builder # [ 0.000789] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs141server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014142builder # [ 0.000791] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT143server # [ 0.000000] DMI: Memory slots populated: 1/1144builder # [ 0.002683] found SMP MP-table at [mem 0x000f5450-0x000f545f]145server # [ 0.000000] Hypervisor detected: KVM146builder # [ 0.002693] Using GB pages for direct mapping147server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000148builder # [ 0.002796] RAMDISK: [mem 0x3e2e3000-0x3ffcffff]149server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00150builder # [ 0.002803] ACPI: Early table checksum verification disabled151server # [ 0.000001] kvm-clock: using sched offset of 453640041 cycles152builder # [ 0.002806] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )153server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns154builder # [ 0.002810] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)155server # [ 0.000005] tsc: Detected 2400.012 MHz processor156builder # [ 0.002814] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)157server # [ 0.000763] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000158builder # [ 0.002821] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)159server # [ 0.000790] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs160builder # [ 0.002823] ACPI: FACS 0x000000003FFE0000 000040161server # [ 0.000792] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT162builder # [ 0.002824] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)163server # [ 0.002678] found SMP MP-table at [mem 0x000f5450-0x000f545f]164server # [ 0.002688] Using GB pages for direct mapping165builder # [ 0.002826] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)166server # [ 0.002753] RAMDISK: [mem 0x3e2e3000-0x3ffcffff]167server # [ 0.002761] ACPI: Early table checksum verification disabled168builder # [ 0.002828] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)169server # [ 0.002764] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )170builder # [ 0.002829] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)171server # [ 0.002767] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)172builder # [ 0.002830] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]173server # [ 0.002772] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)174builder # [ 0.002831] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]175builder # [ 0.002832] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]176server # [ 0.002779] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)177server # [ 0.002781] ACPI: FACS 0x000000003FFE0000 000040178builder # [ 0.002832] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]179server # [ 0.002782] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)180builder # [ 0.002833] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]181builder # [ 0.002833] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]182server # [ 0.002784] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)183builder # [ 0.002834] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]184server # [ 0.002785] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)185builder # [ 0.003056] No NUMA configuration found186builder # [ 0.003057] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]187server # [ 0.002787] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)188builder # [ 0.003060] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]189server # [ 0.002788] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]190builder # [ 0.005386] Zone ranges:191builder # [ 0.005387] DMA [mem 0x0000000000001000-0x0000000000ffffff]192server # [ 0.002789] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]193builder # [ 0.005389] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]194server # [ 0.002790] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]195builder # [ 0.005390] Normal empty196builder # [ 0.005391] Device empty197server # [ 0.002790] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]198builder # [ 0.005391] Movable zone start for each node199builder # [ 0.005392] Early memory node ranges200server # [ 0.002791] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]201builder # [ 0.005392] node 0: [mem 0x0000000000001000-0x000000000009efff]202server # [ 0.002791] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]203builder # [ 0.005393] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]204server # [ 0.002792] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]205server # [ 0.003008] No NUMA configuration found206builder # [ 0.005394] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]207builder # [ 0.005416] On node 0, zone DMA: 1 pages in unavailable ranges208server # [ 0.003009] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]209builder # [ 0.006239] On node 0, zone DMA: 97 pages in unavailable ranges210server # [ 0.003012] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]211server # [ 0.005930] Zone ranges:212builder # [ 0.024896] On node 0, zone DMA32: 40 pages in unavailable ranges213server # [ 0.005931] DMA [mem 0x0000000000001000-0x0000000000ffffff]214builder # [ 0.025347] ACPI: PM-Timer IO Port: 0x608215builder # [ 0.025357] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])216server # [ 0.005932] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]217server # [ 0.005933] Normal empty218server # [ 0.005934] Device empty219builder # [ 0.025382] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23220server # [ 0.005935] Movable zone start for each node221builder # [ 0.025385] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)222server # [ 0.005936] Early memory node ranges223builder # [ 0.025386] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)224server # [ 0.005936] node 0: [mem 0x0000000000001000-0x000000000009efff]225builder # [ 0.025388] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)226server # [ 0.005937] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]227builder # [ 0.025389] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)228server # [ 0.005938] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]229server # [ 0.005958] On node 0, zone DMA: 1 pages in unavailable ranges230builder # [ 0.025389] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)231server # [ 0.006237] On node 0, zone DMA: 97 pages in unavailable ranges232builder # [ 0.025392] ACPI: Using ACPI (MADT) for SMP configuration information233server # [ 0.024594] On node 0, zone DMA32: 40 pages in unavailable ranges234builder # [ 0.025393] ACPI: HPET id: 0x8086a201 base: 0xfed00000235builder # [ 0.025395] TSC deadline timer available236server # [ 0.025036] ACPI: PM-Timer IO Port: 0x608237builder # [ 0.025399] CPU topo: Max. logical packages: 1238server # [ 0.025046] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])239builder # [ 0.025400] CPU topo: Max. logical dies: 1240server # [ 0.025074] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23241builder # [ 0.025400] CPU topo: Max. dies per package: 1242builder # [ 0.025403] CPU topo: Max. threads per core: 1243server # [ 0.025077] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)244builder # [ 0.025404] CPU topo: Num. cores per package: 1245server # [ 0.025079] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)246builder # [ 0.025404] CPU topo: Num. threads per package: 1247server # [ 0.025080] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)248builder # [ 0.025405] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs249server # [ 0.025080] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)250builder # [ 0.025421] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()251server # [ 0.025081] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)252builder # [ 0.025448] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]253server # [ 0.025083] ACPI: Using ACPI (MADT) for SMP configuration information254builder # [ 0.025449] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]255server # [ 0.025085] ACPI: HPET id: 0x8086a201 base: 0xfed00000256server # [ 0.025088] TSC deadline timer available257builder # [ 0.025451] [mem 0x40000000-0xafffffff] available for PCI devices258server # [ 0.025092] CPU topo: Max. logical packages: 1259builder # [ 0.025452] Booting paravirtualized kernel on KVM260server # [ 0.025093] CPU topo: Max. logical dies: 1261server # [ 0.025093] CPU topo: Max. dies per package: 1262builder # [ 0.025454] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns263server # [ 0.025096] CPU topo: Max. threads per core: 1264server # [ 0.025097] CPU topo: Num. cores per package: 1265builder # [ 0.030069] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1266server # [ 0.025097] CPU topo: Num. threads per package: 1267builder # [ 0.032288] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152268server # [ 0.025098] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs269builder # [ 0.032337] kvm-guest: PV spinlocks disabled, single CPU270server # [ 0.025113] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()271server # [ 0.025141] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]272server # [ 0.025143] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]273server # [ 0.025144] [mem 0x40000000-0xafffffff] available for PCI devices274builder # [ 0.032339] 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/bwhdkbz57s1q9shrfdr9q266f8k9fv3w-nixos-system-builder-test/init regInfo=/nix/.ro-store/bjl0rgzjm4x48b5jx17cgyg6wvlfqnxx-closure-info/registration console=ttyS0,115200n8 console=tty0275server # [ 0.025145] Booting paravirtualized kernel on KVM276server # [ 0.025147] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns277builder # [ 0.032428] Unknown kernel command line parameters "regInfo=/nix/.ro-store/bjl0rgzjm4x48b5jx17cgyg6wvlfqnxx-closure-info/registration", will be passed to user space.278builder # [ 0.032440] random: crng init done279server # [ 0.029726] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1280server # [ 0.031958] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152281builder # [ 0.032440] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes282server # [ 0.032003] kvm-guest: PV spinlocks disabled, single CPU283builder # [ 0.032461] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)284builder # [ 0.033017] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)285builder # [ 0.033047] Fallback order for Node 0: 0286builder # [ 0.033049] Built 1 zonelists, mobility grouping on. Total pages: 262006287builder # [ 0.033050] Policy zone: DMA32288builder # [ 0.035537] mem auto-init: stack:all(zero), heap alloc:on, heap free:off289server # [ 0.032005] 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/yvlpxrjs96a6lmak3yh8krwrs49cbrw1-nixos-system-server-test/init regInfo=/nix/.ro-store/vk4vwl6dk39nbrfr67z8y8mfhgg85mg1-closure-info/registration console=ttyS0,115200n8 console=tty0290builder # [ 0.037943] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1291builder # [ 0.040227] allocated 2097152 bytes of page_ext292server # [ 0.032099] Unknown kernel command line parameters "regInfo=/nix/.ro-store/vk4vwl6dk39nbrfr67z8y8mfhgg85mg1-closure-info/registration", will be passed to user space.293builder # [ 0.050527] ftrace: allocating 48820 entries in 192 pages294server # [ 0.032112] random: crng init done295builder # [ 0.050529] ftrace: allocated 192 pages with 2 groups296builder # [ 0.051370] Dynamic Preempt: lazy297server # [ 0.032113] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes298builder # [ 0.051540] rcu: Preemptible hierarchical RCU implementation.299builder # [ 0.051541] rcu: RCU event tracing is enabled.300server # [ 0.032134] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)301builder # [ 0.051541] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.302server # [ 0.032680] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)303builder # [ 0.051543] Trampoline variant of Tasks RCU enabled.304server # [ 0.032712] Fallback order for Node 0: 0305builder # [ 0.051543] Rude variant of Tasks RCU enabled.306server # [ 0.032715] Built 1 zonelists, mobility grouping on. Total pages: 262006307builder # [ 0.051544] Tracing variant of Tasks RCU enabled.308server # [ 0.032716] Policy zone: DMA32309server # [ 0.035226] mem auto-init: stack:all(zero), heap alloc:on, heap free:off310builder # [ 0.051544] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.311server # [ 0.037634] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1312builder # [ 0.051545] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1313server # [ 0.040041] allocated 2097152 bytes of page_ext314builder # [ 0.051554] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.315server # [ 0.049913] ftrace: allocating 48820 entries in 192 pages316server # [ 0.049914] ftrace: allocated 192 pages with 2 groups317builder # [ 0.051555] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.318server # [ 0.050740] Dynamic Preempt: lazy319server # [ 0.050864] rcu: Preemptible hierarchical RCU implementation.320builder # [ 0.051556] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.321server # [ 0.050865] rcu: RCU event tracing is enabled.322builder # [ 0.055897] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16323server # [ 0.050865] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.324builder # [ 0.056180] rcu: srcu_init: Setting srcu_struct sizes based on contention.325server # [ 0.050867] Trampoline variant of Tasks RCU enabled.326server # [ 0.050867] Rude variant of Tasks RCU enabled.327builder # [ 0.056186] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns328server # [ 0.050867] Tracing variant of Tasks RCU enabled.329server # [ 0.050868] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.330builder # [ 0.056288] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)331builder # [ 0.059796] Console: colour VGA+ 80x25332server # [ 0.050869] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1333builder # [ 0.059799] printk: legacy console [tty0] enabled334server # [ 0.050920] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.335builder # [ 0.089236] printk: legacy console [ttyS0] enabled336builder # [ 0.190822] ACPI: Core revision 20250807337server # [ 0.050922] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.338server # [ 0.050922] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.339builder # [ 0.191714] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns340server # [ 0.055204] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16341builder # [ 0.193347] APIC: Switch to symmetric I/O mode setup342server # [ 0.055485] rcu: srcu_init: Setting srcu_struct sizes based on contention.343builder # [ 0.194366] x2apic enabled344server # [ 0.055491] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns345builder # [ 0.195134] APIC: Switched APIC routing to: physical x2apic346server # [ 0.055592] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)347server # [ 0.059044] Console: colour VGA+ 80x25348builder # [ 0.197001] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1349server # [ 0.059046] printk: legacy console [tty0] enabled350server # [ 0.088552] printk: legacy console [ttyS0] enabled351server # [ 0.190610] ACPI: Core revision 20250807352builder # [ 0.198003] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns353builder # [ 0.199748] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)354server # [ 0.191557] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns355builder # [ 0.200832] x86/cpu: User Mode Instruction Prevention (UMIP) activated356server # [ 0.193187] APIC: Switch to symmetric I/O mode setup357server # [ 0.194226] x2apic enabled358builder # [ 0.202797] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127359server # [ 0.194968] APIC: Switched APIC routing to: physical x2apic360builder # [ 0.203740] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0361server # [ 0.196833] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1362builder # [ 0.204749] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto363builder # [ 0.205745] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl364server # [ 0.197838] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns365builder # [ 0.207746] Transient Scheduler Attacks: Vulnerable: No microcode366server # [ 0.199552] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)367builder # [ 0.208745] Spectre V2 : Mitigation: Enhanced / Automatic IBRS368server # [ 0.200635] x86/cpu: User Mode Instruction Prevention (UMIP) activated369builder # [ 0.209745] Speculative Return Stack Overflow: Mitigation: Safe RET370server # [ 0.202563] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127371server # [ 0.203511] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0372server # [ 0.204553] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto373builder # [ 0.210745] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization374builder # [ 0.212753] Spectre V2 : Enabling IBPB for BPF375server # [ 0.205549] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl376server # [ 0.207549] Transient Scheduler Attacks: Vulnerable: No microcode377builder # [ 0.213486] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier378server # [ 0.208549] Spectre V2 : Mitigation: Enhanced / Automatic IBRS379builder # [ 0.214746] active return thunk: srso_alias_return_thunk380server # [ 0.209549] Speculative Return Stack Overflow: Mitigation: Safe RET381builder # [ 0.215766] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'382builder # [ 0.216746] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'383builder # [ 0.217745] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'384builder # [ 0.218745] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'385server # [ 0.210549] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization386builder # [ 0.220524] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'387server # [ 0.212556] Spectre V2 : Enabling IBPB for BPF388builder # [ 0.221498] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'389server # [ 0.213285] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier390builder # [ 0.222558] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'391server # [ 0.214549] active return thunk: srso_alias_return_thunk392builder # [ 0.223746] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256393server # [ 0.215570] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'394builder # [ 0.224745] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64395server # [ 0.216549] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'396builder # [ 0.225708] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512397server # [ 0.217549] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'398builder # [ 0.226745] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024399server # [ 0.218549] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'400builder # [ 0.227745] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8401server # [ 0.219549] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'402server # [ 0.220549] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'403builder # [ 0.228745] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.404server # [ 0.221549] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'405server # [ 0.222549] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256406server # [ 0.224549] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64407server # [ 0.225549] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512408server # [ 0.226549] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024409server # [ 0.227549] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8410server # [ 0.228549] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.411builder # [ 0.262703] Freeing SMP alternatives memory: 48K412builder # [ 0.263324] pid_max: default: 32768 minimum: 301413builder # [ 0.264422] LSM: initializing lsm=capability,landlock,yama,bpf,ima414builder # [ 0.264849] landlock: Up and running.415builder # [ 0.266214] Yama: becoming mindful.416builder # [ 0.266960] LSM support for eBPF active417builder # [ 0.267847] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)418builder # [ 0.268766] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)419server # [ 0.262747] Freeing SMP alternatives memory: 48K420server # [ 0.263548] pid_max: default: 32768 minimum: 301421builder # [ 0.271609] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)422server # [ 0.264168] LSM: initializing lsm=capability,landlock,yama,bpf,ima423server # [ 0.265415] landlock: Up and running.424builder # [ 0.272311] Performance Events: Fam17h+ core perfctr, AMD PMU driver.425server # [ 0.266001] Yama: becoming mindful.426builder # [ 0.272750] ... version: 2427server # [ 0.266762] LSM support for eBPF active428builder # [ 0.273469] ... bit width: 48429builder # [ 0.273748] ... generic counters: 6430server # [ 0.267653] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)431builder # [ 0.274482] ... generic bitmap: 000000000000003f432server # [ 0.268569] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)433builder # [ 0.274758] ... fixed-purpose counters: 0434builder # [ 0.275491] ... fixed-purpose bitmap: 0000000000000000435builder # [ 0.275748] ... value mask: 0000ffffffffffff436server # [ 0.271339] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)437builder # [ 0.276629] ... max period: 00007fffffffffff438builder # [ 0.277439] ... global_ctrl mask: 000000000000003f439server # [ 0.272081] Performance Events: Fam17h+ core perfctr, AMD PMU driver.440builder # [ 0.277858] signal: max sigframe size: 3376441server # [ 0.272554] ... version: 2442server # [ 0.273252] ... bit width: 48443builder # [ 0.278678] rcu: Hierarchical SRCU implementation.444server # [ 0.273551] ... generic counters: 6445builder # [ 0.279395] rcu: Max phase no-delay instances is 400.446server # [ 0.274292] ... generic bitmap: 000000000000003f447server # [ 0.274563] ... fixed-purpose counters: 0448server # [ 0.275285] ... fixed-purpose bitmap: 0000000000000000449server # [ 0.275551] ... value mask: 0000ffffffffffff450server # [ 0.276503] ... max period: 00007fffffffffff451server # [ 0.277269] ... global_ctrl mask: 000000000000003f452builder # [ 0.284331] smp: Bringing up secondary CPUs ...453builder # [ 0.284763] smp: Brought up 1 node, 1 CPU454server # [ 0.277676] signal: max sigframe size: 3376455server # [ 0.278486] rcu: Hierarchical SRCU implementation.456builder # [ 0.285459] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)457server # [ 0.279178] rcu: Max phase no-delay instances is 400.458builder # [ 0.285907] Memory: 940512K/1048024K available (18120K kernel code, 2728K rwdata, 13640K rodata, 3656K init, 3632K bss, 100140K reserved, 0K cma-reserved)459builder # [ 0.286978] devtmpfs: initialized460builder # [ 0.287913] x86/mm: Memory block size: 128MB461server # [ 0.284068] smp: Bringing up secondary CPUs ...462builder # [ 0.289597] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)463server # [ 0.284566] smp: Brought up 1 node, 1 CPU464builder # [ 0.290639] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).465server # [ 0.285284] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)466builder # [ 0.291827] pinctrl core: initialized pinctrl subsystem467builder # [ 0.293008] PM: RTC time: 21:39:35, date: 2026-10-08468server # [ 0.285759] Memory: 940552K/1048024K available (18120K kernel code, 2728K rwdata, 13640K rodata, 3656K init, 3632K bss, 100140K reserved, 0K cma-reserved)469server # [ 0.286787] devtmpfs: initialized470server # [ 0.287683] x86/mm: Memory block size: 128MB471builder # [ 0.296533] NET: Registered PF_NETLINK/PF_ROUTE protocol family472server # [ 0.289382] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)473builder # [ 0.297871] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations474server # [ 0.290495] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).475builder # [ 0.298764] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations476server # [ 0.291634] pinctrl core: initialized pinctrl subsystem477server # [ 0.292774] PM: RTC time: 21:39:35, date: 2026-10-08478builder # [ 0.299893] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations479builder # [ 0.300756] audit: initializing netlink subsys (disabled)480builder # [ 0.301926] thermal_sys: Registered thermal governor 'fair_share'481builder # [ 0.301929] thermal_sys: Registered thermal governor 'bang_bang'482server # [ 0.296262] NET: Registered PF_NETLINK/PF_ROUTE protocol family483builder # [ 0.302749] thermal_sys: Registered thermal governor 'step_wise'484server # [ 0.296913] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations485builder # [ 0.303684] thermal_sys: Registered thermal governor 'user_space'486server # [ 0.297567] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations487builder # [ 0.304485] audit: type=2000 audit(1791495575.997:1): state=initialized audit_enabled=0 res=1488builder # [ 0.305750] thermal_sys: Registered thermal governor 'power_allocator'489server # [ 0.298697] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations490builder # [ 0.305765] cpuidle: using governor menu491server # [ 0.299561] audit: initializing netlink subsys (disabled)492builder # [ 0.307986] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5493server # [ 0.300719] thermal_sys: Registered thermal governor 'fair_share'494server # [ 0.300721] thermal_sys: Registered thermal governor 'bang_bang'495builder # [ 0.308970] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]496server # [ 0.301553] thermal_sys: Registered thermal governor 'step_wise'497builder # [ 0.309751] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry498server # [ 0.302526] thermal_sys: Registered thermal governor 'user_space'499builder # [ 0.310760] PCI: Using configuration type 1 for base access500server # [ 0.303291] audit: type=2000 audit(1791495576.002:1): state=initialized audit_enabled=0 res=1501builder # [ 0.311901] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.502server # [ 0.304554] thermal_sys: Registered thermal governor 'power_allocator'503server # [ 0.304569] cpuidle: using governor menu504server # [ 0.306810] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5505server # [ 0.308348] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]506server # [ 0.309553] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry507server # [ 0.310564] PCI: Using configuration type 1 for base access508builder # [ 0.319048] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages509server # [ 0.311716] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.510builder # [ 0.319749] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page511builder # [ 0.324749] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages512builder # [ 0.325749] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page513server # [ 0.318853] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages514server # [ 0.319552] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page515server # [ 0.324553] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages516builder # [ 0.334326] ACPI: Added _OSI(Module Device)517server # [ 0.325553] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page518builder # [ 0.334750] ACPI: Added _OSI(Processor Device)519builder # [ 0.335489] ACPI: Added _OSI(Processor Aggregator Device)520server # [ 0.334144] ACPI: Added _OSI(Module Device)521server # [ 0.334553] ACPI: Added _OSI(Processor Device)522builder # [ 0.342301] ACPI: 1 ACPI AML tables successfully acquired and loaded523server # [ 0.335312] ACPI: Added _OSI(Processor Aggregator Device)524builder # [ 0.345673] ACPI: Interpreter enabled525builder # [ 0.346238] ACPI: PM: (supports S0 S3 S4 S5)526builder # [ 0.346749] ACPI: Using IOAPIC for interrupt routing527builder # [ 0.347657] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug528server # [ 0.342119] ACPI: 1 ACPI AML tables successfully acquired and loaded529builder # [ 0.352748] PCI: Using E820 reservations for host bridge windows530server # [ 0.345503] ACPI: Interpreter enabled531builder # [ 0.353865] ACPI: Enabled 2 GPEs in block 00 to 3F532server # [ 0.346059] ACPI: PM: (supports S0 S3 S4 S5)533server # [ 0.346552] ACPI: Using IOAPIC for interrupt routing534server # [ 0.347508] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug535server # [ 0.352552] PCI: Using E820 reservations for host bridge windows536builder # [ 0.361442] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])537server # [ 0.353688] ACPI: Enabled 2 GPEs in block 00 to 3F538builder # [ 0.361756] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]539builder # [ 0.362826] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]540builder # [ 0.363872] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]541builder # [ 0.365090] PCI host bridge to bus 0000:00542builder # [ 0.365753] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]543builder # [ 0.366749] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]544server # [ 0.361308] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])545builder # [ 0.367749] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]546server # [ 0.361560] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]547builder # [ 0.368748] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]548builder # [ 0.369749] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]549server # [ 0.362644] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]550server # [ 0.363677] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]551builder # [ 0.370748] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]552builder # [ 0.371748] pci_bus 0000:00: root bus resource [bus 00-ff]553server # [ 0.364884] PCI host bridge to bus 0000:00554server # [ 0.365556] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]555builder # [ 0.372782] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint556server # [ 0.366552] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]557builder # [ 0.374234] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint558server # [ 0.367552] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]559server # [ 0.368552] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]560server # [ 0.369552] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]561server # [ 0.370552] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]562server # [ 0.371552] pci_bus 0000:00: root bus resource [bus 00-ff]563server # [ 0.372573] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint564server # [ 0.373993] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint565builder # [ 0.376828] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]566builder # [ 0.377760] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]567builder # [ 0.378836] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]568builder # [ 0.379916] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]569builder # [ 0.381660] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint570server # [ 0.376631] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]571server # [ 0.377564] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]572builder # [ 0.384787] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]573server # [ 0.378647] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]574builder # [ 0.385748] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]575builder # [ 0.386737] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]576server # [ 0.379718] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]577builder # [ 0.387624] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]578server # [ 0.381457] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint579builder # [ 0.389283] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint580server # [ 0.384592] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]581builder # [ 0.391679] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]582server # [ 0.385494] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]583builder # [ 0.392396] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]584server # [ 0.386321] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]585builder # [ 0.392770] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]586server # [ 0.386558] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]587builder # [ 0.394299] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint588server # [ 0.388116] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint589server # [ 0.390495] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]590builder # [ 0.397786] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]591server # [ 0.391223] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]592builder # [ 0.398662] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]593server # [ 0.391573] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]594builder # [ 0.399535] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]595server # [ 0.393122] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint596builder # [ 0.401242] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint597builder # [ 0.403663] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]598server # [ 0.396589] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]599builder # [ 0.404460] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]600server # [ 0.397487] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]601server # [ 0.398349] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]602builder # [ 0.404770] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]603builder # [ 0.406617] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]604server # [ 0.399973] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint605builder # [ 0.408091] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint606server # [ 0.402589] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]607builder # [ 0.409761] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]608server # [ 0.403490] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]609builder # [ 0.410712] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]610server # [ 0.405190] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]611builder # [ 0.412144] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint612server # [ 0.405558] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]613server # [ 0.407144] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint614builder # [ 0.413761] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]615builder # [ 0.415549] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]616server # [ 0.408565] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]617server # [ 0.409573] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]618builder # [ 0.417307] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint619server # [ 0.411111] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint620builder # [ 0.418761] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]621builder # [ 0.419770] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]622server # [ 0.413564] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]623builder # [ 0.421331] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint624server # [ 0.414573] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]625server # [ 0.416153] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint626builder # [ 0.423793] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]627builder # [ 0.424781] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]628server # [ 0.417565] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]629server # [ 0.418575] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]630builder # [ 0.426280] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint631server # [ 0.420145] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint632builder # [ 0.428513] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]633builder # [ 0.429396] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]634server # [ 0.421564] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]635builder # [ 0.429769] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]636server # [ 0.422557] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]637builder # [ 0.431865] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint638server # [ 0.424123] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint639builder # [ 0.434458] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]640server # [ 0.426514] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]641builder # [ 0.434756] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]642server # [ 0.427182] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]643builder # [ 0.435735] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]644server # [ 0.427574] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]645builder # [ 0.437187] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint646server # [ 0.429145] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint647builder # [ 0.438600] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]648server # [ 0.431259] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]649builder # [ 0.440315] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint650server # [ 0.431559] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]651builder # [ 0.441541] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]652server # [ 0.432574] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]653builder # [ 0.441940] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint654server # [ 0.434119] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint655builder # [ 0.443509] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]656server # [ 0.436047] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]657server # [ 0.436750] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint658server # [ 0.438408] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]659server # [ 0.439419] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint660server # [ 0.441258] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]661builder # [ 0.443961] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint662builder # [ 0.446498] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]663builder # [ 0.447086] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint664builder # [ 0.448033] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO665builder # [ 0.449010] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint666server # [ 0.441795] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint667builder # [ 0.450811] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]668builder # [ 0.451710] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]669server # [ 0.444544] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]670server # [ 0.445821] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint671builder # [ 0.452824] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint672server # [ 0.446871] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO673builder # [ 0.454559] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]674server # [ 0.447818] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint675server # [ 0.449610] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]676builder # [ 0.458533] ACPI: PCI: Interrupt link LNKA configured for IRQ 10677server # [ 0.450508] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]678builder # [ 0.459604] ACPI: PCI: Interrupt link LNKB configured for IRQ 10679builder # [ 0.460642] ACPI: PCI: Interrupt link LNKC configured for IRQ 11680server # [ 0.451645] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint681builder # [ 0.461606] ACPI: PCI: Interrupt link LNKD configured for IRQ 11682server # [ 0.453367] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]683builder # [ 0.462637] ACPI: PCI: Interrupt link LNKE configured for IRQ 10684builder # [ 0.463848] ACPI: PCI: Interrupt link LNKF configured for IRQ 10685builder # [ 0.464850] ACPI: PCI: Interrupt link LNKG configured for IRQ 11686builder # [ 0.465884] ACPI: PCI: Interrupt link LNKH configured for IRQ 11687server # [ 0.457263] ACPI: PCI: Interrupt link LNKA configured for IRQ 10688builder # [ 0.466785] ACPI: PCI: Interrupt link GSIA configured for IRQ 16689server # [ 0.457659] ACPI: PCI: Interrupt link LNKB configured for IRQ 10690builder # [ 0.467761] ACPI: PCI: Interrupt link GSIB configured for IRQ 17691server # [ 0.458654] ACPI: PCI: Interrupt link LNKC configured for IRQ 11692builder # [ 0.468758] ACPI: PCI: Interrupt link GSIC configured for IRQ 18693server # [ 0.459649] ACPI: PCI: Interrupt link LNKD configured for IRQ 11694builder # [ 0.469760] ACPI: PCI: Interrupt link GSID configured for IRQ 19695server # [ 0.460848] ACPI: PCI: Interrupt link LNKE configured for IRQ 10696builder # [ 0.470760] ACPI: PCI: Interrupt link GSIE configured for IRQ 20697server # [ 0.461658] ACPI: PCI: Interrupt link LNKF configured for IRQ 10698builder # [ 0.471741] ACPI: PCI: Interrupt link GSIF configured for IRQ 21699server # [ 0.462654] ACPI: PCI: Interrupt link LNKG configured for IRQ 11700builder # [ 0.472724] ACPI: PCI: Interrupt link GSIG configured for IRQ 22701server # [ 0.463669] ACPI: PCI: Interrupt link LNKH configured for IRQ 11702builder # [ 0.473519] ACPI: PCI: Interrupt link GSIH configured for IRQ 23703server # [ 0.464590] ACPI: PCI: Interrupt link GSIA configured for IRQ 16704builder # [ 0.474812] iommu: Default domain type: Translated705server # [ 0.465567] ACPI: PCI: Interrupt link GSIB configured for IRQ 17706builder # [ 0.475642] iommu: DMA domain TLB invalidation policy: lazy mode707server # [ 0.466541] ACPI: PCI: Interrupt link GSIC configured for IRQ 18708builder # [ 0.476793] ACPI: bus type USB registered709server # [ 0.467326] ACPI: PCI: Interrupt link GSID configured for IRQ 19710builder # [ 0.477564] usbcore: registered new interface driver usbfs711server # [ 0.467567] ACPI: PCI: Interrupt link GSIE configured for IRQ 20712builder # [ 0.478465] usbcore: registered new interface driver hub713server # [ 0.468567] ACPI: PCI: Interrupt link GSIF configured for IRQ 21714builder # [ 0.478757] usbcore: registered new device driver usb715server # [ 0.469564] ACPI: PCI: Interrupt link GSIG configured for IRQ 22716server # [ 0.470565] ACPI: PCI: Interrupt link GSIH configured for IRQ 23717builder # [ 0.480512] NetLabel: Initializing718builder # [ 0.480755] NetLabel: domain hash size = 128719server # [ 0.472617] iommu: Default domain type: Translated720builder # [ 0.481688] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO721server # [ 0.473468] iommu: DMA domain TLB invalidation policy: lazy mode722builder # [ 0.482570] NetLabel: unlabeled traffic allowed by default723builder # [ 0.483498] PCI: Using ACPI for IRQ routing724server # [ 0.474593] ACPI: bus type USB registered725server # [ 0.475407] usbcore: registered new interface driver usbfs726server # [ 0.476298] usbcore: registered new interface driver hub727server # [ 0.476561] usbcore: registered new device driver usb728server # [ 0.478308] NetLabel: Initializing729server # [ 0.479040] NetLabel: domain hash size = 128730server # [ 0.479551] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO731server # [ 0.480603] NetLabel: unlabeled traffic allowed by default732server # [ 0.481564] PCI: Using ACPI for IRQ routing733builder # [ 0.527495] pci 0000:00:01.0: vgaarb: setting as boot VGA device734builder # [ 0.527744] pci 0000:00:01.0: vgaarb: bridge control possible735builder # [ 0.527744] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none736builder # [ 0.527753] vgaarb: loaded737builder # [ 0.528471] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0738builder # [ 0.528750] hpet0: 3 comparators, 64-bit 100.000000 MHz counter739server # [ 0.526295] pci 0000:00:01.0: vgaarb: setting as boot VGA device740server # [ 0.526548] pci 0000:00:01.0: vgaarb: bridge control possible741server # [ 0.526548] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none742server # [ 0.526556] vgaarb: loaded743builder # [ 0.534834] clocksource: Switched to clocksource kvm-clock744server # [ 0.527332] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0745server # [ 0.527553] hpet0: 3 comparators, 64-bit 100.000000 MHz counter746builder # [ 0.538342] VFS: Disk quotas dquot_6.6.0747builder # [ 0.539116] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)748builder # [ 0.540511] pnp: PnP ACPI init749server # [ 0.531640] clocksource: Switched to clocksource kvm-clock750builder # [ 0.541321] ACPI: IRQ 4 override to edge(!), high(!)751builder # [ 0.542316] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved752server # [ 0.535151] VFS: Disk quotas dquot_6.6.0753builder # [ 0.543919] pnp: PnP ACPI: found 5 devices754server # [ 0.535813] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)755server # [ 0.537284] pnp: PnP ACPI init756server # [ 0.538052] ACPI: IRQ 4 override to edge(!), high(!)757server # [ 0.539042] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved758server # [ 0.540661] pnp: PnP ACPI: found 5 devices759builder # [ 0.551449] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns760builder # [ 0.553033] clocksource: Switched to clocksource acpi_pm761builder # [ 0.554015] NET: Registered PF_INET protocol family762builder # [ 0.555241] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)763server # [ 0.548315] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns764server # [ 0.549812] clocksource: Switched to clocksource acpi_pm765server # [ 0.550825] NET: Registered PF_INET protocol family766server # [ 0.551876] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)767builder # [ 0.572192] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)768builder # [ 0.573749] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)769builder # [ 0.575109] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)770builder # [ 0.576472] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)771builder # [ 0.577768] TCP: Hash tables configured (established 8192 bind 8192)772server # [ 0.569265] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)773builder # [ 0.578864] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)774server # [ 0.570666] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)775builder # [ 0.580183] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)776server # [ 0.571980] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)777builder # [ 0.581308] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)778server # [ 0.573310] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)779builder # [ 0.582518] NET: Registered PF_UNIX/PF_LOCAL protocol family780server # [ 0.574543] TCP: Hash tables configured (established 8192 bind 8192)781builder # [ 0.583499] NET: Registered PF_XDP protocol family782builder # [ 0.584363] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]783server # [ 0.575653] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)784builder # [ 0.585391] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]785server # [ 0.576907] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)786builder # [ 0.586492] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]787server # [ 0.578036] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)788builder # [ 0.587634] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]789server # [ 0.579266] NET: Registered PF_UNIX/PF_LOCAL protocol family790server # [ 0.580246] NET: Registered PF_XDP protocol family791builder # [ 0.588766] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]792server # [ 0.581082] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]793builder # [ 0.589855] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]794server # [ 0.582085] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]795builder # [ 0.591630] ACPI: \_SB_.GSIA: Enabled at IRQ 16796server # [ 0.583105] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]797server # [ 0.584207] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]798builder # [ 0.594112] ACPI: \_SB_.GSIB: Enabled at IRQ 17799server # [ 0.585355] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]800server # [ 0.586461] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]801builder # [ 0.596111] ACPI: \_SB_.GSIC: Enabled at IRQ 18802server # [ 0.588330] ACPI: \_SB_.GSIA: Enabled at IRQ 16803builder # [ 0.598377] ACPI: \_SB_.GSID: Enabled at IRQ 19804server # [ 0.590679] ACPI: \_SB_.GSIB: Enabled at IRQ 17805builder # [ 0.600095] PCI: CLS 0 bytes, default 64806server # [ 0.592642] ACPI: \_SB_.GSIC: Enabled at IRQ 18807builder # [ 0.600957] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns808builder # [ 0.602693] Trying to unpack rootfs image as initramfs...809server # [ 0.594624] ACPI: \_SB_.GSID: Enabled at IRQ 19810server # [ 0.596314] PCI: CLS 0 bytes, default 64811server # [ 0.597190] Trying to unpack rootfs image as initramfs...812server # [ 0.601572] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns813builder # [ 0.647988] Initialise system trusted keyrings814server # [ 0.642713] Initialise system trusted keyrings815builder # [ 0.651625] workingset: timestamp_bits=40 max_order=18 bucket_order=0816server # [ 0.646139] workingset: timestamp_bits=40 max_order=18 bucket_order=0817builder # [ 0.672642] Key type asymmetric registered818builder # [ 0.673401] Asymmetric key parser 'x509' registered819builder # [ 0.674302] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)820server # [ 0.666710] Key type asymmetric registered821builder # [ 0.677699] io scheduler mq-deadline registered822server # [ 0.669098] Asymmetric key parser 'x509' registered823builder # [ 0.678468] io scheduler kyber registered824server # [ 0.669920] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)825server # [ 0.673209] io scheduler mq-deadline registered826builder # [ 0.682149] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled827server # [ 0.673984] io scheduler kyber registered828builder # [ 0.683443] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A829server # [ 0.677698] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled830builder # [ 0.687353] Linux agpgart interface v0.103831builder # [ 0.688175] ACPI: bus type drm_connector registered832server # [ 0.679028] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A833builder # [ 0.691056] usbcore: registered new interface driver usbserial_generic834server # [ 0.682818] Linux agpgart interface v0.103835builder # [ 0.692173] usbserial: USB Serial support registered for generic836server # [ 0.683621] ACPI: bus type drm_connector registered837server # [ 0.686534] usbcore: registered new interface driver usbserial_generic838builder # [ 0.695602] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.839server # [ 0.687669] usbserial: USB Serial support registered for generic840builder # [ 0.695602] Please enable it if your BIOS has the CPPC option.841builder # [ 0.697837] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled842server # [ 0.691092] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.843server # [ 0.691092] Please enable it if your BIOS has the CPPC option.844builder # [ 0.701695] drop_monitor: Initializing network drop monitor service845server # [ 0.693435] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled846builder # [ 0.702894] NET: Registered PF_INET6 protocol family847builder # [ 0.706047] Segment Routing with IPv6848server # [ 0.697201] drop_monitor: Initializing network drop monitor service849server # [ 0.698345] NET: Registered PF_INET6 protocol family850builder # [ 0.708602] In-situ OAM (IOAM) with IPv6851builder # [ 0.709713] IPI shorthand broadcast: enabled852server # [ 0.701573] Segment Routing with IPv6853server # [ 0.705103] In-situ OAM (IOAM) with IPv6854server # [ 0.706176] IPI shorthand broadcast: enabled855builder # [ 0.718051] sched_clock: Marking stable (579013972, 138558462)->(792023410, -74450976)856server # [ 0.712661] sched_clock: Marking stable (573014288, 139056959)->(786997345, -74926098)857builder # [ 0.722756] registered taskstats version 1858builder # [ 0.723778] Loading compiled-in X.509 certificates859server # [ 0.719265] registered taskstats version 1860server # [ 0.720242] Loading compiled-in X.509 certificates861builder # [ 0.742586] Demotion targets for Node 0: null862builder # [ 0.743474] Key type .fscrypt registered863builder # [ 0.746578] Key type fscrypt-provisioning registered864builder # [ 0.747623] ima: No TPM chip found, activating TPM-bypass!865server # [ 0.739084] Demotion targets for Node 0: null866builder # [ 0.748524] ima: Allocated hash algorithm: sha1867server # [ 0.739942] Key type .fscrypt registered868builder # [ 0.750637] ima: No architecture policies found869server # [ 0.743077] Key type fscrypt-provisioning registered870server # [ 0.744040] ima: No TPM chip found, activating TPM-bypass!871builder # [ 0.753591] PM: Magic number: 14:985:701872server # [ 0.745007] ima: Allocated hash algorithm: sha1873builder # [ 0.755259] RAS: Correctable Errors collector initialized.874server # [ 0.747111] ima: No architecture policies found875server # [ 0.749086] PM: Magic number: 14:985:701876server # [ 0.750726] RAS: Correctable Errors collector initialized.877builder # [ 0.764343] clk: Disabling unused clocks878builder # [ 0.766588] PM: genpd: Disabling unused power domains879server # [ 0.759795] clk: Disabling unused clocks880server # [ 0.764088] PM: genpd: Disabling unused power domains881builder # [ 0.931598] Freeing initrd memory: 29620K882builder # [ 0.934832] Freeing unused decrypted memory: 2028K883server # [ 0.928439] Freeing initrd memory: 29620K884builder # [ 0.937592] Freeing unused kernel image (initmem) memory: 3656K885builder # [ 0.938690] Write protecting the kernel read-only data: 32768k886builder # [ 0.940052] Freeing unused kernel image (text/rodata gap) memory: 308K887server # [ 0.931655] Freeing unused decrypted memory: 2028K888builder # [ 0.941553] Freeing unused kernel image (rodata/data gap) memory: 696K889server # [ 0.934432] Freeing unused kernel image (initmem) memory: 3656K890server # [ 0.935563] Write protecting the kernel read-only data: 32768k891server # [ 0.936932] Freeing unused kernel image (text/rodata gap) memory: 308K892server # [ 0.938507] Freeing unused kernel image (rodata/data gap) memory: 696K893builder # [ 0.992188] x86/mm: Checked W+X mappings: passed, no W+X pages found.894builder # [ 0.993302] Run /init as init process895server # [ 0.989161] x86/mm: Checked W+X mappings: passed, no W+X pages found.896server # [ 0.990219] Run /init as init process897builder # [ 1.004037] systemd[1]: Inserted module 'autofs4'898server # [ 1.000867] systemd[1]: Inserted module 'autofs4'899builder # [ 1.023679] fuse: init (API version 7.45)900server # [ 1.020526] fuse: init (API version 7.45)901builder # [ 1.030953] ACPI: \_SB_.GSIG: Enabled at IRQ 22902builder # [ 1.033474] ACPI: \_SB_.GSIH: Enabled at IRQ 23903server # [ 1.027776] ACPI: \_SB_.GSIG: Enabled at IRQ 22904builder # [ 1.036746] ACPI: \_SB_.GSIE: Enabled at IRQ 20905builder # [ 1.038746] ACPI: \_SB_.GSIF: Enabled at IRQ 21906server # [ 1.030348] ACPI: \_SB_.GSIH: Enabled at IRQ 23907server # [ 1.033368] ACPI: \_SB_.GSIE: Enabled at IRQ 20908server # [ 1.035312] ACPI: \_SB_.GSIF: Enabled at IRQ 21909builder # [ 1.043946] virtiofs virtio5: discovered new tag: nix-store910builder # [ 1.045442] virtiofs virtio5: virtio_fs_setup_dax: No cache capability911server # [ 1.040767] virtiofs virtio5: discovered new tag: nix-store912server # [ 1.042357] virtiofs virtio5: virtio_fs_setup_dax: No cache capability913builder # [ 1.054846] virtiofs virtio6: discovered new tag: shared914builder # [ 1.056473] virtiofs virtio6: virtio_fs_setup_dax: No cache capability915server # [ 1.049511] virtiofs virtio6: discovered new tag: shared916builder # [ 1.060113] virtiofs virtio7: discovered new tag: xchg917server # [ 1.051292] virtiofs virtio6: virtio_fs_setup_dax: No cache capability918builder # [ 1.061633] virtiofs virtio7: virtio_fs_setup_dax: No cache capability919server # [ 1.054500] virtiofs virtio7: discovered new tag: xchg920server # [ 1.055922] virtiofs virtio7: virtio_fs_setup_dax: No cache capability921builder # [ 1.082650] systemd[1]: Successfully made /usr/ read-only.922server # [ 1.079206] systemd[1]: Successfully made /usr/ read-only.923builder # [ 1.419870] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)924builder # [ 1.432783] systemd[1]: Detected virtualization kvm.925server # [ 1.416155] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)926builder # [ 1.435384] systemd[1]: Detected architecture x86-64.927server # [ 1.427976] systemd[1]: Detected virtualization kvm.928builder # [ 1.438006] systemd[1]: Running in initrd.929server # [ 1.430172] systemd[1]: Detected architecture x86-64.930server # [ 1.432276] systemd[1]: Running in initrd.931builder # [ 1.441131] systemd[1]: Initializing machine ID from random generator.932server # [ 1.434792] systemd[1]: Initializing machine ID from random generator.933builder # [ 1.444738] systemd[1]: Hostname set to <builder>.934server # [ 1.437655] systemd[1]: Hostname set to <server>.935server # [ 1.528696] systemd[1]: bpf-restrict-fs: LSM BPF program attached936builder # [ 1.545031] systemd[1]: bpf-restrict-fs: LSM BPF program attached937server # [ 1.570328] systemd[1]: Queued start job for default target Initrd Default Target.938server # [ 1.574471] systemd[1]: Created slice Slice /system/modprobe.939server # [ 1.575711] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.940builder # [ 1.584650] systemd[1]: Queued start job for default target Initrd Default Target.941server # [ 1.577149] systemd[1]: Expecting device /dev/disk/by-label/nixos...942server # [ 1.578254] systemd[1]: Reached target Path Units.943server # [ 1.579151] systemd[1]: Reached target Slice Units.944builder # [ 1.587953] systemd[1]: Created slice Slice /system/modprobe.945server # [ 1.579997] systemd[1]: Reached target Swaps.946server # [ 1.580842] systemd[1]: Reached target Timer Units.947builder # [ 1.589133] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.948builder # [ 1.590591] systemd[1]: Expecting device /dev/disk/by-label/nixos...949server # [ 1.581858] systemd[1]: Listening on D-Bus System Message Bus Socket.950builder # [ 1.591744] systemd[1]: Reached target Path Units.951server # [ 1.583144] systemd[1]: Listening on Journal Socket (/dev/log).952builder # [ 1.592694] systemd[1]: Reached target Slice Units.953server # [ 1.584303] systemd[1]: Listening on Journal Sockets.954builder # [ 1.593611] systemd[1]: Reached target Swaps.955server # [ 1.585284] systemd[1]: Listening on udev Control Socket.956builder # [ 1.594436] systemd[1]: Reached target Timer Units.957server # [ 1.586295] systemd[1]: Listening on udev Kernel Socket.958builder # [ 1.595450] systemd[1]: Listening on D-Bus System Message Bus Socket.959server # [ 1.587239] systemd[1]: Reached target Socket Units.960builder # [ 1.596723] systemd[1]: Listening on Journal Socket (/dev/log).961builder # [ 1.597897] systemd[1]: Listening on Journal Sockets.962server # [ 1.589153] systemd[1]: Starting Create List of Static Device Nodes...963builder # [ 1.598894] systemd[1]: Listening on udev Control Socket.964builder # [ 1.599928] systemd[1]: Listening on udev Kernel Socket.965builder # [ 1.600868] systemd[1]: Reached target Socket Units.966server # [ 1.592949] systemd[1]: Starting Load Kernel Module configfs...967builder # [ 1.602742] systemd[1]: Starting Create List of Static Device Nodes...968builder # [ 1.606423] systemd[1]: Starting Load Kernel Module configfs...969server # [ 1.604178] systemd[1]: Starting Journal Service...970builder # [ 1.615791] systemd[1]: Starting Journal Service...971server # [ 1.631299] systemd[1]: Starting Load Kernel Modules...972builder # [ 1.642274] systemd[1]: Starting Load Kernel Modules...973server # [ 1.635263] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os974builder # [ 1.646715] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os975builder # [ 1.655810] systemd[1]: Starting Coldplug All udev Devices...976server # [ 1.648174] systemd[1]: Starting Coldplug All udev Devices...977server # [ 1.657002] systemd-journald[65]: Collecting audit messages is disabled.978builder # [ 1.669506] systemd-journald[65]: Collecting audit messages is disabled.979server # [ 1.663454] systemd[1]: Finished Create List of Static Device Nodes.980builder # [ 1.672661] systemd[1]: Finished Create List of Static Device Nodes.981builder # [ 1.676321] systemd[1]: modprobe@configfs.service: Deactivated successfully.982server # [ 1.668834] systemd[1]: modprobe@configfs.service: Deactivated successfully.983builder # [ 1.685024] systemd[1]: Finished Load Kernel Module configfs.984server # [ 1.677549] systemd[1]: Finished Load Kernel Module configfs.985builder # [ 1.692093] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config986server # [ 1.683587] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config987builder # [ 1.703817] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...988server # [ 1.697339] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...989server # [ 1.702950] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.990builder # [ 1.716580] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.991server # [ 1.713299] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev992builder # [ 1.725791] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev993server # [ 1.745748] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.994builder # [ 1.756255] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.995server # [ 1.755446] systemd[1]: Starting Create Static Device Nodes in /dev...996builder # [ 1.767053] systemd[1]: Starting Create Static Device Nodes in /dev...997server # [ 1.766153] systemd[1]: Finished Load Kernel Modules.998builder # [ 1.777650] systemd[1]: Finished Load Kernel Modules.999server # [ 1.779449] systemd[1]: Starting Apply Kernel Variables...1000server # [ 1.638747] systemd-modules-load[67]: Inserted module 'dm_mod'1001server # [ 1.642146] systemd-modules-load[67]: Inserted module 'virtio_balloon'1002builder # [ 1.650502] systemd-modules-load[66]: Inserted module 'dm_mod'[ 1.791662] systemd[1]: Starting Apply Kernel Variables...1003builder # 1004server # [ 1.644295] systemd-modules-load[67]: Inserted module 'virtio_gpu'1005builder # [ 1.654391] systemd-modules-load[66]: Inserted module 'virtio_balloon'1006builder # [ 1.655470] systemd-modules-load[66]: Inserted module 'virtio_gpu'1007server # [ 1.789439] systemd[1]: Started Journal Service.1008builder # [ 1.800618] systemd[1]: Started Journal Service.1009server # [ 1.668226] systemd[1]: Finished Create Static Device Nodes in /dev.1010builder # [ 1.678675] systemd[1]: Finished Create Static Device Nodes in /dev.1011server # [ 1.671103] systemd[1]: Reached target Preparation for Local File Systems.1012server # [ 1.672925] systemd[1]: Reached target Local File Systems.1013builder # [ 1.682104] systemd[1]: Reached target Preparation for Local File Systems.1014builder # [ 1.685079] systemd[1]: Reached target Local File Systems.1015server # [ 1.677084] systemd[1]: Starting Create System Files and Directories...1016builder # [ 1.687393] systemd[1]: Starting Create System Files and Directories...1017server # [ 1.685088] systemd[1]: Starting Rule-based Manager for Device Events and Files...1018builder # [ 1.695604] systemd[1]: Starting Rule-based Manager for Device Events and Files...1019builder # [ 1.703210] systemd[1]: Finished Apply Kernel Variables.1020server # [ 1.696628] systemd[1]: Finished Apply Kernel Variables.1021server # [ 1.722560] systemd[1]: Finished Create System Files and Directories.1022builder # [ 1.735102] systemd[1]: Finished Create System Files and Directories.1023server # [ 1.745865] systemd-udevd[78]: Using default interface naming scheme 'v261'.1024builder # [ 1.756198] systemd-udevd[79]: Using default interface naming scheme 'v261'.1025server # [ 1.771471] systemd[1]: Started Rule-based Manager for Device Events and Files.1026builder # [ 1.781084] systemd[1]: Started Rule-based Manager for Device Events and Files.1027server # [ 1.814898] systemd[1]: Finished Coldplug All udev Devices.1028builder # [ 1.825266] systemd[1]: Finished Coldplug All udev Devices.1029server # [ 1.816591] systemd[1]: Reached target System Initialization.1030server # [ 1.818390] systemd[1]: Reached target Basic System.1031builder # [ 1.827745] systemd[1]: Reached target System Initialization.1032builder # [ 1.828652] systemd[1]: Reached target Basic System.1033server # [ 2.142042] virtio_blk virtio2: 1/0/0 default/read/poll queues1034server # [ 2.145946] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1035server # [ 2.152324] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121036builder # [ 2.164350] virtio_blk virtio2: 1/0/0 default/read/poll queues1037builder # [ 2.169312] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121038server # [ 2.162582] serio: i8042 KBD port at 0x60,0x64 irq 11039server # [ 2.163312] serio: i8042 AUX port at 0x60,0x64 irq 121040builder # [ 2.174292] serio: i8042 KBD port at 0x60,0x64 irq 11041builder # [ 2.178458] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1042builder # [ 2.181582] serio: i8042 AUX port at 0x60,0x64 irq 121043server # [ 2.178598] ehci-pci 0000:00:1d.7: EHCI Host Controller1044server # [ 2.179375] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11045server # [ 2.181196] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001046server # [ 2.187202] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001047builder # [ 2.197199] ehci-pci 0000:00:1d.7: EHCI Host Controller1048builder # [ 2.198148] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11049server # [ 2.190272] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181050builder # [ 2.200009] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001051server # [ 2.191970] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11052server # [ 2.195441] usb usb1: Product: EHCI Host Controller1053server # [ 2.196840] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1054server # [ 2.198084] usb usb1: SerialNumber: 0000:00:1d.71055builder # [ 2.208355] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001056server # [ 2.200020] hub 1-0:1.0: USB hub found1057builder # [ 2.209283] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181058server # [ 2.201484] hub 1-0:1.0: 6 ports detected1059builder # [ 2.211303] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11060builder # [ 2.212582] usb usb1: Product: EHCI Host Controller1061builder # [ 2.213281] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1062server # [ 2.207091] uhci_hcd 0000:00:1d.0: UHCI Host Controller1063server # [ 2.207819] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21064builder # [ 2.217582] usb usb1: SerialNumber: 0000:00:1d.71065builder # [ 2.218909] hub 1-0:1.0: USB hub found1066builder # [ 2.219869] hub 1-0:1.0: 6 ports detected1067server # [ 2.213558] uhci_hcd 0000:00:1d.0: detected 2 ports1068builder # [ 2.223242] uhci_hcd 0000:00:1d.0: UHCI Host Controller1069builder # [ 2.224015] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21070server # [ 2.219388] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801071server # [ 2.226556] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181072builder # [ 2.237488] uhci_hcd 0000:00:1d.0: detected 2 ports1073builder # [ 2.243755] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801074server # [ 2.235845] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11075server # [ 2.236896] usb usb2: Product: UHCI Host Controller1076builder # [ 2.249144] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181077builder # [ 2.250309] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11078server # [ 2.248447] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1079builder # [ 2.259848] SCSI subsystem initialized1080server # [ 2.258098] usb usb2: SerialNumber: 0000:00:1d.01081server # [ 2.260651] SCSI subsystem initialized1082builder # [ 2.270843] usb usb2: Product: UHCI Host Controller1083builder # [ 2.271544] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1084server # [ 2.265085] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01085server # [ 2.128082] systemd[1]: Starting Virtual Console Setup...1086builder # [ 2.138302] (udev-worker)[83]: Network interface NamePolicy= disabled on kernel command line.1087server # [ 2.270233] hub 2-0:1.0: USB hub found1088server # [ 2.271593] hub 2-0:1.0: 2 ports detected1089builder # [ 2.281485] usb usb2: SerialNumber: 0000:00:1d.01090builder # [ 2.144345] systemd[1]: Starting Virtual Console Setup...1091server # [ 2.139242] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.1092builder # [ 2.295021] hub 2-0:1.0: USB hub found1093builder # [ 2.299185] hub 2-0:1.0: 2 ports detected1094builder # [ 2.300887] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01095server # [ 2.299775] uhci_hcd 0000:00:1d.1: UHCI Host Controller1096server # [ 2.163604] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1097server # [ 2.167145] systemd[1]: Finished Virtual Console Setup.1098server # [ 2.172520] (udev-worker)[87]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1099server # [ 2.175138] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.1100builder # [ 2.189787] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1101server # [ 2.324445] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31102builder # [ 2.195143] systemd[1]: Finished Virtual Console Setup.1103builder # [ 2.201431] (udev-worker)[88]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1104server # [ 2.334144] uhci_hcd 0000:00:1d.1: detected 2 ports1105builder # [ 2.204129] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.1106server # [ 2.334914] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01107builder # [ 2.346618] uhci_hcd 0000:00:1d.1: UHCI Host Controller1108builder # [ 2.347373] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31109server # [ 2.343702] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181110server # [ 2.344860] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11111builder # [ 2.354133] uhci_hcd 0000:00:1d.1: detected 2 ports1112builder # [ 2.355000] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01113server # [ 2.208063] systemd[1]: Found device /dev/disk/by-label/nixos.1114server # [ 2.208992] systemd[1]: Reached target Initrd Root Device.1115server # [ 2.210684] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1116server # [ 2.355857] usb usb3: Product: UHCI Host Controller1117server # [ 2.356589] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1118builder # [ 2.366665] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181119server # [ 2.359076] usb usb3: SerialNumber: 0000:00:1d.11120builder # [ 2.367774] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11121builder # [ 2.232273] systemd[1]: Found device /dev/disk/by-label/nixos.1122server # [ 2.362833] hub 3-0:1.0: USB hub found1123builder # [ 2.233508] systemd[1]: Reached target Initrd Root Device.1124builder # [ 2.238077] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1125server # [ 2.369101] hub 3-0:1.0: 2 ports detected1126builder # [ 2.381944] usb usb3: Product: UHCI Host Controller1127builder # [ 2.382886] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1128server # [ 2.377456] uhci_hcd 0000:00:1d.2: UHCI Host Controller1129server # [ 2.378274] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41130builder # [ 2.388612] usb usb3: SerialNumber: 0000:00:1d.11131builder # [ 2.390856] hub 3-0:1.0: USB hub found1132server # [ 2.382198] uhci_hcd 0000:00:1d.2: detected 2 ports1133server # [ 2.382954] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01134builder # [ 2.392990] hub 3-0:1.0: 2 ports detected1135server # [ 2.386331] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181136server # [ 2.389143] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11137server # [ 2.391539] usb usb4: Product: UHCI Host Controller1138builder # [ 2.401270] uhci_hcd 0000:00:1d.2: UHCI Host Controller1139builder # [ 2.403637] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41140builder # [ 2.406676] uhci_hcd 0000:00:1d.2: detected 2 ports1141builder # [ 2.407488] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01142server # [ 2.400146] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1143server # [ 2.400912] usb usb4: SerialNumber: 0000:00:1d.21144builder # [ 2.410857] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181145server # [ 2.263553] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1146server # [ 2.403832] hub 4-0:1.0: USB hub found1147server # [ 2.405147] hub 4-0:1.0: 2 ports detected1148builder # [ 2.413632] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11149server # [ 2.270993] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1150builder # [ 2.422919] usb usb4: Product: UHCI Host Controller1151builder # [ 2.423865] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1152builder # [ 2.424905] usb usb4: SerialNumber: 0000:00:1d.21153builder # [ 2.288664] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1154builder # [ 2.429310] hub 4-0:1.0: USB hub found1155builder # [ 2.294758] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1156builder # [ 2.435170] hub 4-0:1.0: 2 ports detected1157server # [ 2.434968] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1158server # [ 2.441893] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1159builder # [ 2.451365] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1160server # [ 2.443102] usb 1-1: new high-speed USB device number 2 using ehci-pci1161server # [ 2.444584] ahci 0000:00:1f.2: flags: 64bit ncq only1162builder # [ 2.459607] usb 1-1: new high-speed USB device number 2 using ehci-pci1163server # [ 2.451443] scsi host0: ahci1164server # [ 2.453354] scsi host1: ahci1165builder # [ 2.464402] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1166builder # [ 2.465370] ahci 0000:00:1f.2: flags: 64bit ncq only1167server # [ 2.457114] scsi host2: ahci1168server # [ 2.459165] scsi host3: ahci1169server # [ 2.462421] scsi host4: ahci1170server # [ 2.464839] scsi host5: ahci1171server # [ 2.467455] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11172server # [ 2.469809] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11173builder # [ 2.479710] scsi host0: ahci1174server # [ 2.471032] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11175server # [ 2.472226] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11176builder # [ 2.482610] scsi host1: ahci1177server # [ 2.473463] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11178server # [ 2.474633] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11179builder # [ 2.486528] scsi host2: ahci1180builder # [ 2.488364] scsi host3: ahci1181builder # [ 2.490144] scsi host4: ahci1182builder # [ 2.491511] scsi host5: ahci1183builder # [ 2.493058] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11184builder # [ 2.494601] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11185builder # [ 2.495772] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11186builder # [ 2.497029] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11187builder # [ 2.498197] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11188builder # [ 2.499452] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11189server # [ 2.573522] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001190server # [ 2.575835] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101191server # [ 2.578939] usb 1-1: Product: QEMU USB Tablet1192server # [ 2.580753] usb 1-1: Manufacturer: QEMU1193builder # [ 2.590000] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001194server # [ 2.582357] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11195builder # [ 2.592880] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101196builder # [ 2.596315] usb 1-1: Product: QEMU USB Tablet1197builder # [ 2.598264] usb 1-1: Manufacturer: QEMU1198builder # [ 2.600042] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11199server # [ 2.615323] hid: raw HID events driver (C) Jiri Kosina1200builder # [ 2.634981] hid: raw HID events driver (C) Jiri Kosina1201builder # [ 2.526851] systemd[1]: Mounting /sysroot...1202server # [ 2.518751] systemd[1]: Mounting /sysroot...1203server # [ 2.788825] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1204server # [ 2.791350] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001205server # [ 2.793703] ata3.00: applying bridge limits1206server # [ 2.795607] ata5: SATA link down (SStatus 0 SControl 300)1207server # [ 2.797938] ata1: SATA link down (SStatus 0 SControl 300)1208server # [ 2.800325] ata4: SATA link down (SStatus 0 SControl 300)1209server # [ 2.802517] ata6: SATA link down (SStatus 0 SControl 300)1210builder # [ 2.811039] ata6: SATA link down (SStatus 0 SControl 300)1211builder # [ 2.813436] ata5: SATA link down (SStatus 0 SControl 300)1212server # [ 2.804899] ata2: SATA link down (SStatus 0 SControl 300)1213server # [ 2.807228] ata3.00: configured for UDMA/1001214builder # [ 2.816309] ata2: SATA link down (SStatus 0 SControl 300)1215builder # [ 2.818676] ata1: SATA link down (SStatus 0 SControl 300)1216server # [ 2.809698] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51217builder # [ 2.821150] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1218builder # [ 2.823878] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001219builder # [ 2.826042] ata3.00: applying bridge limits1220builder # [ 2.828145] ata4: SATA link down (SStatus 0 SControl 300)1221builder # [ 2.830529] ata3.00: configured for UDMA/1001222builder # [ 2.833265] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51223server # [ 2.887210] usbcore: registered new interface driver usbhid1224server # [ 2.888002] usbhid: USB HID core driver1225builder # [ 2.914693] usbcore: registered new interface driver usbhid1226builder # [ 2.915496] usbhid: USB HID core driver1227server # [ 2.911273] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1228server # [ 2.918331] 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/input21229server # [ 2.922210] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01230builder # [ 2.933749] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1231server # [ 2.926206] cdrom: Uniform CD-ROM driver Revision: 3.201232builder # [ 2.947805] 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/input21233server # [ 2.940508] EXT4-fs (vda): mounted filesystem 42546167-a138-4f00-94c0-ba1fe6d4d8b0 r/w with ordered data mode. Quota mode: none.1234builder # [ 2.951310] cdrom: Uniform CD-ROM driver Revision: 3.201235builder # [ 2.952972] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01236server # [ 2.807878] systemd[1]: Mounted /sysroot.1237server # [ 2.810077] systemd[1]: Reached target Initrd Root File System.1238server # [ 2.815083] systemd[1]: Starting Mountpoints Configured in the Real Root...1239builder # [ 2.969920] EXT4-fs (vda): mounted filesystem 34a35e01-97a0-40b0-9984-04e7c3b2212c r/w with ordered data mode. Quota mode: none.1240builder # [ 2.837784] systemd[1]: Mounted /sysroot.1241server # [ 2.828950] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload.1242builder # [ 2.839762] systemd[1]: Reached target Initrd Root File System.1243server # [ 2.831931] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)...1244server # [ 2.833415] systemd[1]: Reloading...1245builder # [ 2.845277] systemd[1]: Mounting /sysroot/nix/.ro-store...1246builder # [ 2.851790] systemd[1]: Mounting /sysroot/nix/.rw-store...1247builder # [ 2.857183] systemd[1]: Mounting /sysroot/run...1248builder # [ 2.867160] systemd[1]: Mounting /sysroot/tmp/shared...1249builder # [ 2.872074] systemd[1]: Mounting /sysroot/tmp/xchg...1250builder # [ 2.881744] systemd[1]: Starting Mountpoints Configured in the Real Root...1251server # [ 2.916890] systemd[1]: Reloading finished in 82 ms.1252builder # [ 2.927909] systemd-sysroot-fstab-check[143]: /sysroot should be mounted in the initrd, will request daemon-reload.1253builder # [ 2.933085] systemd[1]: Mounted /sysroot/nix/.ro-store.1254builder # [ 2.933965] systemd[1]: Mounted /sysroot/nix/.rw-store.1255builder # [ 2.934780] systemd[1]: Mounted /sysroot/run.1256server # [ 2.925272] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace...1257builder # [ 2.937204] systemd[1]: Mounted /sysroot/tmp/shared.1258builder # [ 2.938603] systemd[1]: Mounted /sysroot/tmp/xchg.1259server # [ 2.930166] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace...1260server # [ 2.932822] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1261server # [ 2.934243] systemd[1]: Finished Mountpoints Configured in the Real Root.1262server # [ 2.935274] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1263builder # [ 2.944927] systemd[1]: Reload requested from client PID 143 ('systemd-sysroot') (unit initrd-parse-etc.service)...1264builder # [ 2.946467] systemd[1]: Reloading...1265builder # [ 3.012755] systemd[1]: Reloading finished in 65 ms.1266builder # [ 3.022305] systemd-sysroot-fstab-check[143]: Requesting initrd-fs.target/start/replace...1267builder # [ 3.025133] systemd-sysroot-fstab-check[143]: Requesting swap.target/start/replace...1268builder # [ 3.026913] systemd[1]: Starting rw-sysroot-nix-store.service...1269builder # [ 3.030468] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1270builder # [ 3.033479] systemd[1]: Finished Mountpoints Configured in the Real Root.1271builder # [ 3.035142] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1272builder # [ 3.045287] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1273builder # [ 3.046935] systemd[1]: Finished rw-sysroot-nix-store.service.1274builder # [ 3.529115] systemd[1]: Mounting /sysroot/nix/store...1275server # [ 3.521211] systemd[1]: Mounting /sysroot/nix/.ro-store...1276server # [ 3.528185] systemd[1]: Mounting /sysroot/nix/.rw-store...1277server # [ 3.536166] systemd[1]: Mounting /sysroot/run...1278server # [ 3.547184] systemd[1]: Mounting /sysroot/tmp/shared...1279server # [ 3.557165] systemd[1]: Mounting /sysroot/tmp/xchg...1280builder # [ 3.580804] systemd[1]: Mounted /sysroot/nix/store.1281builder # [ 3.584145] systemd[1]: Reached target Initrd File Systems.1282builder # [ 3.587133] systemd[1]: Starting Find NixOS closure...1283builder # [ 3.591214] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1284server # [ 3.594956] systemd[1]: Mounted /sysroot/nix/.ro-store.1285server # [ 3.600052] systemd[1]: Mounted /sysroot/nix/.rw-store.1286server # [ 3.603093] systemd[1]: Mounted /sysroot/run.1287server # [ 3.603934] systemd[1]: Mounted /sysroot/tmp/shared.1288builder # [ 3.613264] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1289server # [ 3.605676] systemd[1]: Mounted /sysroot/tmp/xchg.1290builder # [ 3.615173] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1291server # [ 3.610090] systemd[1]: Starting rw-sysroot-nix-store.service...1292builder # [ 3.622689] systemd[1]: Finished Find NixOS closure.1293builder # [ 3.623985] systemd[1]: Reached target Initrd Default Target.1294builder # [ 3.625906] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1295server # [ 3.620356] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1296server # [ 3.621801] systemd[1]: Finished rw-sysroot-nix-store.service.1297builder # [ 3.639645] systemd[1]: Stopped target Initrd Default Target.1298builder # [ 3.640911] systemd[1]: Stopped target Basic System.1299builder # [ 3.641913] systemd[1]: Stopped target Initrd Root Device.1300builder # [ 3.644111] systemd[1]: Stopped target Path Units.1301builder # [ 3.644888] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1302builder # [ 3.645899] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1303builder # [ 3.647036] systemd[1]: Stopped target Slice Units.1304builder # [ 3.647949] systemd[1]: Stopped target Socket Units.1305builder # [ 3.648735] systemd[1]: Stopped target System Initialization.1306builder # [ 3.649556] systemd[1]: Stopped target Swaps.1307builder # [ 3.650200] systemd[1]: Stopped target Timer Units.1308builder # [ 3.650931] systemd[1]: dbus.socket: Deactivated successfully.1309builder # [ 3.652135] systemd[1]: Closed D-Bus System Message Bus Socket.1310builder # [ 3.652949] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1311builder # [ 3.654446] systemd[1]: Stopped Find NixOS closure.1312builder # [ 3.655700] systemd[1]: Starting rw-sysroot-nix-store.service...1313builder # [ 3.656650] systemd[1]: systemd-sysctl.service: Deactivated successfully.1314builder # [ 3.658365] systemd[1]: Stopped Apply Kernel Variables.1315builder # [ 3.659323] systemd[1]: systemd-modules-load.service: Deactivated successfully.1316builder # [ 3.660510] systemd[1]: Stopped Load Kernel Modules.1317builder # [ 3.661476] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1318builder # [ 3.663342] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1319builder # [ 3.664589] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1320builder # [ 3.665748] systemd[1]: Stopped Create System Files and Directories.1321builder # [ 3.667659] systemd[1]: Stopped target Local File Systems.1322builder # [ 3.668605] systemd[1]: Stopped target Preparation for Local File Systems.1323builder # [ 3.670141] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1324builder # [ 3.672135] systemd[1]: Stopped Coldplug All udev Devices.1325builder # [ 3.675536] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1326builder # [ 3.676606] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1327builder # [ 3.677593] systemd[1]: Stopped Virtual Console Setup.1328builder # [ 3.685205] systemd[1]: initrd-cleanup.service: Deactivated successfully.1329builder # [ 3.688059] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1330builder # [ 3.689057] systemd[1]: systemd-udevd.service: Deactivated successfully.1331builder # [ 3.693125] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1332builder # [ 3.695929] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1333builder # [ 3.697498] systemd[1]: Closed udev Control Socket.1334builder # [ 3.699123] systemd[1]: Starting Cleanup udev Database...1335builder # [ 3.700143] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1336builder # [ 3.701173] systemd[1]: Stopped Create Static Device Nodes in /dev.1337builder # [ 3.703113] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1338builder # [ 3.705101] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1339builder # [ 3.706127] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1340builder # [ 3.707191] systemd[1]: Stopped Create List of Static Device Nodes.1341builder # [ 3.708088] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1342builder # [ 3.709171] systemd[1]: Finished rw-sysroot-nix-store.service.1343builder # [ 3.724856] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1344builder # [ 3.726541] systemd[1]: Finished Cleanup udev Database.1345builder # [ 3.727825] systemd[1]: Reached target Switch Root.1346builder # [ 3.729649] systemd[1]: Starting NixOS Activation...1347builder # [ 3.803939] initrd-nixos-activation-start[188]: booting system configuration /nix/store/bwhdkbz57s1q9shrfdr9q266f8k9fv3w-nixos-system-builder-test1348builder # [ 3.832642] initrd-nixos-activation-start[188]: running activation script...1349builder # [ 4.026906] initrd-nixos-activation-start[211]: setting up /etc...1350builder # [ 4.134711] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1351builder # [ 4.137075] systemd[1]: Finished NixOS Activation.1352builder # [ 4.139927] systemd[1]: Starting Switch Root...1353builder # [ 4.152988] systemd[1]: Switching root.1354builder # [ 4.417800] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1355builder # [ 4.507434] NET: Registered PF_VSOCK protocol family1356server # [ 4.521442] systemd[1]: Mounting /sysroot/nix/store...1357server # [ 4.568391] systemd[1]: Mounted /sysroot/nix/store.1358server # [ 4.569773] systemd[1]: Reached target Initrd File Systems.1359server # [ 4.571738] systemd[1]: Starting Find NixOS closure...1360server # [ 4.575146] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1361server # [ 4.593187] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1362server # [ 4.597082] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1363server # [ 4.603565] systemd[1]: Finished Find NixOS closure.1364server # [ 4.604703] systemd[1]: Reached target Initrd Default Target.1365server # [ 4.606489] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1366server # [ 4.619599] systemd[1]: Stopped target Initrd Default Target.1367server # [ 4.620939] systemd[1]: Stopped target Basic System.1368server # [ 4.621932] systemd[1]: Stopped target Initrd Root Device.1369server # [ 4.624122] systemd[1]: Stopped target Path Units.1370server # [ 4.624817] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1371server # [ 4.625837] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1372server # [ 4.627111] systemd[1]: Stopped target Slice Units.1373server # [ 4.627868] systemd[1]: Stopped target Socket Units.1374server # [ 4.628654] systemd[1]: Stopped target System Initialization.1375server # [ 4.629469] systemd[1]: Stopped target Swaps.1376server # [ 4.630075] systemd[1]: Stopped target Timer Units.1377server # [ 4.630766] systemd[1]: dbus.socket: Deactivated successfully.1378server # [ 4.632135] systemd[1]: Closed D-Bus System Message Bus Socket.1379server # [ 4.632949] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1380server # [ 4.633995] systemd[1]: Stopped Find NixOS closure.1381server # [ 4.635616] systemd[1]: Starting rw-sysroot-nix-store.service...1382server # [ 4.637188] systemd[1]: systemd-sysctl.service: Deactivated successfully.1383server # [ 4.638311] systemd[1]: Stopped Apply Kernel Variables.1384server # [ 4.639293] systemd[1]: systemd-modules-load.service: Deactivated successfully.1385server # [ 4.640470] systemd[1]: Stopped Load Kernel Modules.1386server # [ 4.641505] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1387server # [ 4.643224] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1388server # [ 4.644447] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1389server # [ 4.645657] systemd[1]: Stopped Create System Files and Directories.1390server # [ 4.647135] systemd[1]: Stopped target Local File Systems.1391server # [ 4.649055] systemd[1]: Stopped target Preparation for Local File Systems.1392server # [ 4.650160] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1393server # [ 4.652116] systemd[1]: Stopped Coldplug All udev Devices.1394server # [ 4.652918] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1395server # [ 4.654306] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1396server # [ 4.655952] systemd[1]: Stopped Virtual Console Setup.1397server # [ 4.664108] systemd[1]: initrd-cleanup.service: Deactivated successfully.1398server # [ 4.667424] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1399server # [ 4.675714] systemd[1]: systemd-udevd.service: Deactivated successfully.1400server # [ 4.676846] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1401server # [ 4.678514] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1402server # [ 4.680059] systemd[1]: Finished rw-sysroot-nix-store.service.1403server # [ 4.681492] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1404server # [ 4.683089] systemd[1]: Closed udev Control Socket.1405server # [ 4.685089] systemd[1]: Starting Cleanup udev Database...1406server # [ 4.685904] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1407server # [ 4.686990] systemd[1]: Stopped Create Static Device Nodes in /dev.1408server # [ 4.687976] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1409server # [ 4.690141] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1410server # [ 4.691128] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1411server # [ 4.692150] systemd[1]: Stopped Create List of Static Device Nodes.1412server # [ 4.706852] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1413server # [ 4.709163] systemd[1]: Finished Cleanup udev Database.1414server # [ 4.709896] systemd[1]: Reached target Switch Root.1415server # [ 4.711781] systemd[1]: Starting NixOS Activation...1416builder # [ 4.867120] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1417builder # [ 4.876701] systemd[1]: Detected virtualization kvm.1418builder # [ 4.878460] systemd[1]: Detected architecture x86-64.1419builder # [ 4.880368] systemd[1]: Detected first boot.1420builder # [ 4.884360] systemd[1]: Initializing machine ID from random generator.1421server # [ 4.787872] initrd-nixos-activation-start[189]: booting system configuration /nix/store/yvlpxrjs96a6lmak3yh8krwrs49cbrw1-nixos-system-server-test1422server # [ 4.817390] initrd-nixos-activation-start[189]: running activation script...1423builder # [ 4.991928] systemd[1]: bpf-restrict-fs: LSM BPF program attached1424builder # [ 5.078490] systemd[1]: Applying preset policy.1425server # [ 5.024843] initrd-nixos-activation-start[212]: setting up /etc...1426builder # [ 5.265103] systemd[1]: Populated /etc with preset unit settings.1427server # [ 5.137666] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1428server # [ 5.139094] systemd[1]: Finished NixOS Activation.1429server # [ 5.141080] systemd[1]: Starting Switch Root...1430server # [ 5.155557] systemd[1]: Switching root.1431server # [ 5.314755] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1432server # [ 5.387488] NET: Registered PF_VSOCK protocol family1433builder # [ 5.471945] systemd[1]: initrd-switch-root.service: Deactivated successfully.1434builder # [ 5.473322] systemd[1]: Stopped initrd-switch-root.service.1435builder # [ 5.475437] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1436builder # [ 5.477717] systemd[1]: Created slice Slice /system/getty.1437builder # [ 5.478987] systemd[1]: Created slice User and Session Slice.1438builder # [ 5.479900] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1439builder # [ 5.481082] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1440builder # [ 5.482173] systemd[1]: Expecting device /dev/hvc0...1441builder # [ 5.482888] systemd[1]: Expecting device /dev/ttyS0...1442builder # [ 5.483622] systemd[1]: Reached target Local Encrypted Volumes.1443builder # [ 5.484441] systemd[1]: Stopped target initrd-fs.target.1444builder # [ 5.485262] systemd[1]: Stopped target initrd-root-fs.target.1445builder # [ 5.486076] systemd[1]: Stopped target initrd-switch-root.target.1446builder # [ 5.486904] systemd[1]: Reached target Virtual Machines and Containers.1447builder # [ 5.487771] systemd[1]: Reached target Path Units.1448builder # [ 5.488440] systemd[1]: Reached target Remote File Systems.1449builder # [ 5.489233] systemd[1]: Reached target Slice Units.1450builder # [ 5.489967] systemd[1]: Reached target Swaps.1451builder # [ 5.491771] systemd[1]: Listening on Query the User Interactively for a Password.1452builder # [ 5.494193] systemd[1]: Listening on Process Core Dump Socket.1453builder # [ 5.496056] systemd[1]: Listening on Credential Encryption/Decryption.1454builder # [ 5.498012] systemd[1]: Listening on Factory Reset Management.1455builder # [ 5.510446] systemd[1]: Listening on Hostname Service Socket.1456builder # [ 5.513290] systemd[1]: Starting Journal Log Access Socket...1457builder # [ 5.514637] systemd[1]: Listening on Journal Audit Socket.1458builder # [ 5.516994] systemd[1]: Listening on Console Output Muting Service Socket.1459builder # [ 5.518096] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1460builder # [ 5.519604] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1461builder # [ 5.520876] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1462builder # [ 5.525091] systemd[1]: Listening on Disk Repartitioning Service Socket.1463builder # [ 5.526135] systemd[1]: Listening on udev Control Socket.1464builder # [ 5.526995] systemd[1]: Listening on udev Varlink Socket.1465builder # [ 5.529091] systemd[1]: Mounting Huge Pages File System...1466builder # [ 5.531239] systemd[1]: Mounting POSIX Message Queue File System...1467builder # [ 5.537637] systemd[1]: Mounting Kernel Debug File System...1468builder # [ 5.544216] systemd[1]: Mounting Kernel Trace File System...1469builder # [ 5.552480] systemd[1]: Starting Create List of Static Device Nodes...1470builder # [ 5.562425] systemd[1]: Starting Load Kernel Module configfs...1471builder # [ 5.567407] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1472builder # [ 5.576924] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1473builder # [ 5.591515] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1474builder # [ 5.600275] systemd[1]: Mounting FUSE Control File System...1475builder # [ 5.606742] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671476builder # [ 5.621665] systemd[1]: Starting Journal Service...1477builder # [ 5.628250] systemd[1]: Starting Load Kernel Modules...1478builder # [ 5.639609] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1479builder # [ 5.649672] systemd[1]: Starting Remount Root and Kernel File Systems...1480builder # [ 5.653647] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1481builder # [ 5.662660] systemd[1]: Starting Coldplug All udev Devices...1482builder # [ 5.673030] systemd-journald[281]: Collecting audit messages is enabled.1483builder # [ 5.678492] systemd[1]: Listening on Journal Log Access Socket.1484builder # [ 5.686015] systemd[1]: Mounted Huge Pages File System.1485builder # [ 5.689513] systemd[1]: Mounted POSIX Message Queue File System.1486builder # [ 5.696607] systemd[1]: Mounted Kernel Debug File System.1487builder # [ 5.697520] systemd[1]: Mounted Kernel Trace File System.1488builder # [ 5.561792] systemd[1]: Queued start job for default target Multi-User System.1489builder # [ 5.565526] systemd[1]: systemd-journald.service: Deactivated successfully.1490builder # [ 5.706697] systemd[1]: Started Journal Service.1491builder # [ 5.717714] loop: module loaded1492builder # [ 5.581247] systemd[1]: Finished Create List of Static Device Nodes.1493builder # [ 5.582169] systemd[1]: modprobe@configfs.service: Deactivated successfully.1494builder # [ 5.583127] systemd[1]: Finished Load Kernel Module configfs.1495builder # [ 5.587408] systemd-modules-load[282]: Inserted module 'loop'1496builder # [ 5.588303] systemd[1]: Mounted FUSE Control File System.1497builder # [ 5.597951] systemd[1]: Finished Load Kernel Modules.1498builder # [ 5.604112] systemd[1]: Mounting Kernel Configuration File System...1499builder # [ 5.609766] systemd[1]: Starting Firewall...1500builder # [ 5.617126] systemd[1]: Starting Apply Kernel Variables...1501server # [ 5.746509] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1502server # [ 5.756143] systemd[1]: Detected virtualization kvm.1503server # [ 5.757873] systemd[1]: Detected architecture x86-64.1504builder # [ 5.767612] EXT4-fs (vda): re-mounted 34a35e01-97a0-40b0-9984-04e7c3b2212c.1505server # [ 5.759804] systemd[1]: Detected first boot.1506builder # [ 5.632150] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1507server # [ 5.763681] systemd[1]: Initializing machine ID from random generator.1508builder # [ 5.648328] systemd[1]: Finished Remount Root and Kernel File Systems.1509builder # [ 5.652064] systemd[1]: Listening on Disk Image Download Service Socket.1510builder # [ 5.659074] systemd-oomd[284]: No swap; memory pressure usage will be degraded1511builder # [ 5.669085] systemd[1]: Starting Flush Journal to Persistent Storage...1512builder # [ 5.670071] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1513builder # [ 5.678631] systemd[1]: Starting Load/Save OS Random Seed...1514builder # [ 5.682138] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1515builder # [ 5.690073] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1516builder # [ 5.736631] systemd[1]: Mounted Kernel Configuration File System.1517builder # [ 5.881706] systemd-journald[281]: Received client request to flush runtime journal.1518server # [ 5.887866] systemd[1]: bpf-restrict-fs: LSM BPF program attached1519server # [ 5.954768] systemd[1]: Applying preset policy.1520builder # [ 5.836403] systemd[1]: Finished Load/Save OS Random Seed.1521builder # [ 5.838253] systemd[1]: Reached target First Boot Complete.1522builder # [ 5.839730] systemd[1]: Finished Apply Kernel Variables.1523builder # [ 5.843384] systemd[1]: Finished Flush Journal to Persistent Storage.1524builder # [ 5.855349] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1525builder # [ 5.861578] systemd[1]: Starting Create Static Device Nodes in /dev...1526builder # [ 5.933036] systemd[1]: Finished Coldplug All udev Devices.1527builder # [ 5.952736] systemd[1]: Finished Create Static Device Nodes in /dev.1528builder # [ 5.954054] systemd[1]: Reached target Preparation for Local File Systems.1529builder # [ 5.957494] systemd[1]: Starting Rule-based Manager for Device Events and Files...1530server # [ 6.123687] systemd[1]: Populated /etc with preset unit settings.1531builder # [ 6.002386] systemd-udevd[322]: Using default interface naming scheme 'v261'.1532builder # [ 6.056148] systemd[1]: Started Rule-based Manager for Device Events and Files.1533builder # [ 6.165860] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1534server # [ 6.318976] systemd[1]: initrd-switch-root.service: Deactivated successfully.1535server # [ 6.320407] systemd[1]: Stopped initrd-switch-root.service.1536server # [ 6.322480] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1537server # [ 6.324530] systemd[1]: Created slice Slice /system/getty.1538server # [ 6.325788] systemd[1]: Created slice User and Session Slice.1539server # [ 6.326727] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1540server # [ 6.327903] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1541server # [ 6.328986] systemd[1]: Expecting device /dev/hvc0...1542server # [ 6.329725] systemd[1]: Expecting device /dev/ttyS0...1543server # [ 6.330509] systemd[1]: Reached target Local Encrypted Volumes.1544server # [ 6.331349] systemd[1]: Stopped target initrd-fs.target.1545server # [ 6.332087] systemd[1]: Stopped target initrd-root-fs.target.1546server # [ 6.332853] systemd[1]: Stopped target initrd-switch-root.target.1547server # [ 6.333709] systemd[1]: Reached target Virtual Machines and Containers.1548server # [ 6.334621] systemd[1]: Reached target Path Units.1549server # [ 6.335341] systemd[1]: Reached target Remote File Systems.1550server # [ 6.336083] systemd[1]: Reached target Slice Units.1551server # [ 6.336754] systemd[1]: Reached target Swaps.1552server # [ 6.338503] systemd[1]: Listening on Query the User Interactively for a Password.1553server # [ 6.340853] systemd[1]: Listening on Process Core Dump Socket.1554server # [ 6.342594] systemd[1]: Listening on Credential Encryption/Decryption.1555server # [ 6.344466] systemd[1]: Listening on Factory Reset Management.1556server # [ 6.356844] systemd[1]: Listening on Hostname Service Socket.1557server # [ 6.359646] systemd[1]: Starting Journal Log Access Socket...1558server # [ 6.361327] systemd[1]: Listening on Journal Audit Socket.1559server # [ 6.363854] systemd[1]: Listening on Console Output Muting Service Socket.1560server # [ 6.364930] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1561server # [ 6.366021] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1562server # [ 6.367267] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1563server # [ 6.371153] systemd[1]: Listening on Disk Repartitioning Service Socket.1564server # [ 6.372193] systemd[1]: Listening on udev Control Socket.1565server # [ 6.373027] systemd[1]: Listening on udev Varlink Socket.1566server # [ 6.375123] systemd[1]: Mounting Huge Pages File System...1567server # [ 6.377355] systemd[1]: Mounting POSIX Message Queue File System...1568server # [ 6.384466] systemd[1]: Mounting Kernel Debug File System...1569builder # [ 6.259126] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1570server # [ 6.392265] systemd[1]: Mounting Kernel Trace File System...1571server # [ 6.402025] systemd[1]: Starting Create List of Static Device Nodes...1572builder # [ 6.277199] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1573server # [ 6.412779] systemd[1]: Starting Load Kernel Module configfs...1574server # [ 6.420250] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1575server # [ 6.430158] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1576builder # [ 6.303619] (udev-worker)[344]: Network interface NamePolicy= disabled on kernel command line.1577server # [ 6.435703] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1578builder # [ 6.307699] (udev-worker)[350]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1579builder # [ 6.310000] (udev-worker)[350]: Network interface NamePolicy= disabled on kernel command line.1580server # [ 6.447138] systemd[1]: Mounting FUSE Control File System...1581server # [ 6.447945] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671582server # [ 6.464408] systemd[1]: Starting Journal Service...1583builder # [ 6.338212] systemd[1]: Mounting /run/wrappers...1584server # [ 6.473270] systemd[1]: Starting Load Kernel Modules...1585server # [ 6.482397] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1586server # [ 6.492369] systemd[1]: Starting Remount Root and Kernel File Systems...1587server # [ 6.494816] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1588server # [ 6.503368] systemd[1]: Starting Coldplug All udev Devices...1589builder # [ 6.378576] systemd[1]: Mounted /run/wrappers.1590builder # [ 6.380045] systemd[1]: Reached target Local File Systems.1591builder # [ 6.384460] systemd[1]: Listening on Boot Loader Control Service Socket.1592builder # [ 6.387532] systemd[1]: Starting register-nix-paths.service...1593server # [ 6.521946] systemd[1]: Listening on Journal Log Access Socket.1594builder # [ 6.393568] systemd[1]: Starting Create SUID/SGID Wrappers...1595builder # [ 6.395165] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1596server # [ 6.525884] systemd[1]: Mounted Huge Pages File System.1597server # [ 6.528372] systemd-journald[282]: Collecting audit messages is enabled.1598server # [ 6.530467] systemd[1]: Mounted POSIX Message Queue File System.1599builder # [ 6.404189] systemd[1]: Starting Save Transient machine-id to Disk...1600server # [ 6.535417] systemd[1]: Mounted Kernel Debug File System.1601server # [ 6.539454] systemd[1]: Mounted Kernel Trace File System.1602server # [ 6.543619] systemd[1]: Finished Create List of Static Device Nodes.1603builder # [ 6.416415] systemd[1]: Starting Create System Files and Directories...1604server # [ 6.552729] systemd[1]: modprobe@configfs.service: Deactivated successfully.1605server # [ 6.416843] systemd[1]: Queued start job for default target Multi-User System.1606server # [ 6.418701] systemd[1]: systemd-journald.service: Deactivated successfully.1607server # [ 6.565707] systemd[1]: Finished Load Kernel Module configfs.1608server # [ 6.567586] loop: module loaded1609server # [ 6.569694] systemd[1]: Started Journal Service.1610server # [ 6.571078] EXT4-fs (vda): re-mounted 42546167-a138-4f00-94c0-ba1fe6d4d8b0.1611server # [ 6.433535] systemd-modules-load[283]: Inserted module 'loop'1612server # [ 6.439181] systemd[1]: Mounted FUSE Control File System.1613server # [ 6.447141] systemd[1]: Finished Remount Root and Kernel File Systems.1614server # [ 6.460078] systemd[1]: Listening on Disk Image Download Service Socket.1615server # [ 6.468531] systemd[1]: Mounting Kernel Configuration File System...1616server # [ 6.474086] systemd[1]: Starting Flush Journal to Persistent Storage...1617server # [ 6.474982] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1618server # [ 6.481098] systemd-modules-load[283]: Inserted module 'tls'1619server # [ 6.487185] systemd[1]: Starting Load/Save OS Random Seed...1620server # [ 6.495733] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1621server # [ 6.497056] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1622builder # [ 6.510253] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1623server # [ 6.502391] systemd[1]: Finished Load Kernel Modules.1624builder # [ 6.517435] systemd[1]: Finished Save Transient machine-id to Disk.1625server # [ 6.515986] systemd-oomd[285]: No swap; memory pressure usage will be degraded1626server # [ 6.517792] systemd[1]: Starting Firewall...1627builder # [ 6.531842] systemd[1]: Condition check resulted in Virtio network device being skipped.1628builder # [ 6.532964] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1629builder # [ 6.534712] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1630builder # [ 6.536895] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671631server # [ 6.530957] systemd[1]: Starting Apply Kernel Variables...1632builder # [ 6.539792] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1633server # [ 6.531814] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1634builder # [ 6.542583] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1635builder # [ 6.544413] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1636server # [ 6.685593] systemd-journald[282]: Received client request to flush runtime journal.1637builder # [ 6.581313] systemd[1]: Finished Create System Files and Directories.1638builder # [ 6.588839] systemd[1]: Starting Rebuild Journal Catalog...1639builder # [ 6.594343] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1640server # [ 6.662592] systemd[1]: Finished Load/Save OS Random Seed.1641server # [ 6.664402] systemd[1]: Reached target First Boot Complete.1642server # [ 6.666846] systemd[1]: Mounted Kernel Configuration File System.1643server # [ 6.670594] systemd[1]: Finished Flush Journal to Persistent Storage.1644builder # [ 6.686888] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1645server # [ 6.681418] systemd[1]: Finished Apply Kernel Variables.1646builder # [ 6.726175] systemd[1]: Finished Rebuild Journal Catalog.1647builder # [ 6.733200] systemd[1]: Starting Update is Completed...1648server # [ 6.727115] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1649server # [ 6.730145] systemd[1]: Starting Create Static Device Nodes in /dev...1650server # [ 6.769792] systemd[1]: Finished Coldplug All udev Devices.1651builder # [ 6.789590] systemd[1]: Finished Update is Completed.1652builder # [ 6.930318] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31653server # [ 6.800692] systemd[1]: Finished Create Static Device Nodes in /dev.1654server # [ 6.801939] systemd[1]: Reached target Preparation for Local File Systems.1655server # [ 6.805065] systemd[1]: Starting Rule-based Manager for Device Events and Files...1656builder # [ 6.986160] ACPI: button: Power Button [PWRF]1657server # [ 6.848620] systemd-udevd[323]: Using default interface naming scheme 'v261'.1658builder # [ 7.033277] mousedev: PS/2 mouse device common for all mice1659server # [ 6.897751] systemd[1]: Started Rule-based Manager for Device Events and Files.1660builder # [ 7.050263] rtc_cmos PNP0B00:00: RTC can wake from S41661builder # [ 7.093354] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1662builder # [ 7.095103] rtc_cmos PNP0B00:00: registered as rtc01663builder # [ 7.107412] parport_pc 00:02: reported by Plug and Play ACPI1664builder # [ 7.114924] rtc_cmos PNP0B00:00: setting system clock to 2026-10-08T21:39:42 UTC (1791495582)1665builder # [ 7.116716] systemd-journald[281]: Time jumped backwards, rotating.1666server # [ 7.011113] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1667builder # [ 7.076948] systemd[1]: Finished Firewall.1668server # [ 7.097347] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1669server # [ 7.122105] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1670builder # [ 7.129314] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41671server # [ 7.140158] (udev-worker)[350]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1672server # [ 7.142259] (udev-worker)[350]: Network interface NamePolicy= disabled on kernel command line.1673server # [ 7.144329] (udev-worker)[355]: Network interface NamePolicy= disabled on kernel command line.1674builder # [ 7.172705] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1675builder # [ 7.175448] systemd[1]: Finished Create SUID/SGID Wrappers.1676server # [ 7.185284] systemd[1]: Mounting /run/wrappers...1677builder # [ 7.147127] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1678builder # [ 7.151794] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1679builder # [ 7.234137] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1680builder # [ 7.212082] systemd[1]: Finished register-nix-paths.service.1681builder # [ 7.212935] systemd[1]: Reached target System Initialization.1682builder # [ 7.214448] systemd[1]: Started Discard unused filesystem blocks once a week.1683builder # [ 7.215799] systemd[1]: Started Daily Cleanup of Temporary Directories.1684builder # [ 7.218176] systemd[1]: Reached target Timer Units.1685builder # [ 7.218891] systemd[1]: Listening on D-Bus System Message Bus Socket.1686builder # [ 7.222639] systemd[1]: Starting niks3 auto-upload socket...1687builder # [ 7.223517] systemd[1]: Listening on Nix Daemon Socket.1688builder # [ 7.224288] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1689builder # [ 7.229771] systemd[1]: Starting D-Bus System Message Bus...1690builder # [ 7.230653] systemd[1]: Listening on niks3 auto-upload socket.1691builder # [ 7.231546] systemd[1]: Reached target Socket Units.1692builder # [ 7.285408] Console: switching to colour dummy device 80x251693server # [ 7.225970] systemd[1]: Mounted /run/wrappers.1694builder # [ 7.328835] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1695builder # [ 7.328942] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1696server # [ 7.227400] systemd[1]: Reached target Local File Systems.1697builder # [ 7.375494] [drm] Found bochs VGA, ID 0xb0c5.1698builder # [ 7.375496] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1699server # [ 7.230437] systemd[1]: Listening on Boot Loader Control Service Socket.1700server # [ 7.234303] systemd[1]: Starting register-nix-paths.service...1701builder # [ 7.382928] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61702builder # [ 7.384802] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51703server # [ 7.239385] systemd[1]: Starting Create SUID/SGID Wrappers...1704server # [ 7.241074] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1705server # [ 7.250266] systemd[1]: Starting Save Transient machine-id to Disk...1706builder # [ 7.404306] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1707server # [ 7.260152] systemd[1]: Starting Create System Files and Directories...1708builder # [ 7.411272] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01709builder # [ 7.286629] systemd[1]: Starting Virtual Console Setup...1710builder # [ 7.341378] dbus-broker-launch[506]: Looking up NSS user entry for 'systemd-timesync'...1711builder # [ 7.351181] dbus-broker-launch[506]: NSS returned no entry for 'systemd-timesync'1712builder # [ 7.352238] dbus-broker-launch[506]: Invalid user-name in /nix/store/jb9vkwms5d73z7zgxl4549cs91gzgnlf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1713server # [ 7.348874] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1714server # [ 7.354638] systemd[1]: Finished Save Transient machine-id to Disk.1715server # [ 7.359617] systemd[1]: Condition check resulted in Virtio network device being skipped.1716server # [ 7.364663] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1717server # [ 7.366194] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1718server # [ 7.367955] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671719server # [ 7.371492] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1720server # [ 7.374695] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1721server # [ 7.376584] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1722builder # [ 7.386654] systemd[1]: Started D-Bus System Message Bus.1723builder # [ 7.389231] systemd[1]: Reached target Basic System.1724builder # [ 7.395103] systemd[1]: Started backdoor.service.1725builder # [ 7.398130] systemd[1]: Starting Import lastlog data into lastlog2 database...1726builder # [ 7.404160] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1727builder # [ 7.415594] systemd[1]: Starting Post-Boot Actions...1728builder # [ 7.521275] Console: switching to colour frame buffer device 160x501729builder # [ 7.558396] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1730server # [ 7.416258] systemd[1]: Finished Create System Files and Directories.1731builder # [ 7.432127] dbus-broker-launch[506]: Ready1732server # [ 7.425186] systemd[1]: Starting Rebuild Journal Catalog...1733builder # [ 7.573332] ppdev: user-space parallel port driver1734builder # [ 7.436226] systemd[1]: Started Reset console on configuration changes.1735server # [ 7.430249] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1736builder # [ 7.463267] systemd[1]: Starting resolvconf update...1737server # [ 7.512491] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1738builder # connecting to host...1739builder # [ 7.550748] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1740builder # [ 7.555895] systemd[1]: Stopped Virtual Console Setup.1741server # [ 7.554912] systemd[1]: Finished Rebuild Journal Catalog.1742builder # [ 7.567215] nsncd[514]: Oct 08 21:39:43.090 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1743builder # [ 7.570276] systemd[1]: Finished Post-Boot Actions.1744server # [ 7.561683] systemd[1]: Starting Update is Completed...1745builder # [ 7.571028] systemd[1]: Started Name Service Cache Daemon (nsncd).1746builder # [ 7.582264] systemd[1]: Reached target Host and Network Name Lookups.1747builder # [ 7.583383] systemd[1]: Reached target User and Group Name Lookups.1748builder # [ 7.594427] systemd[1]: Starting User Login Management...1749builder # [ 7.600213] systemd[1]: Starting Virtual Console Setup...1750builder # [ 7.604819] systemd[1]: Finished Import lastlog data into lastlog2 database.1751builder # [ 7.767133] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1752builder # [ 7.635188] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1753server # [ 7.628699] systemd[1]: Finished Update is Completed.1754builder # [ 7.776460] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1755builder # [ 7.639478] systemd[1]: Stopped Virtual Console Setup.1756server # [ 7.769774] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input31757builder # [ 7.643963] systemd[1]: Starting Virtual Console Setup...1758server # [ 7.791775] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input41759server # [ 7.798565] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1760server # [ 7.820151] ACPI: button: Power Button [PWRF]1761builder # [ 7.747115] systemd[1]: Stopped target Host and Network Name Lookups.1762builder # [ 7.748103] systemd[1]: Stopping Host and Network Name Lookups...1763builder # [ 7.748897] systemd[1]: Stopped target User and Group Name Lookups.1764builder # [ 7.749740] systemd[1]: Stopping User and Group Name Lookups...1765builder # [ 7.751130] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1766builder # [ 7.759108] systemd[1]: nscd.service: Deactivated successfully.1767builder # [ 7.763053] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1768builder # [ 7.786531] systemd-logind[541]: New seat seat0.1769builder # [ 7.788927] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1770builder # [ 7.793070] systemd-logind[541]: Watching system buttons on /dev/input/event2 (Power Button)1771builder # [ 7.798475] systemd-logind[541]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1772builder # [ 7.799742] systemd-logind[541]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1773builder # [ 7.803858] systemd[1]: Started User Login Management.1774builder # [ 7.810396] systemd[1]: Starting linger-users.service...1775builder # [ 7.860594] systemd[1]: Started Name Service Cache Daemon (nsncd).1776builder # [ 7.861721] nsncd[597]: Oct 08 21:39:43.383 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1777builder # [ 7.863536] systemd[1]: Reached target Host and Network Name Lookups.1778builder # [ 7.864354] systemd[1]: Reached target User and Group Name Lookups.1779builder # [ 7.873452] systemd[1]: linger-users.service: Deactivated successfully.1780builder # [ 7.877475] systemd[1]: Finished linger-users.service.1781builder # [ 8.027918] kvm_amd: TSC scaling supported1782builder # [ 8.028316] kvm_amd: Nested Virtualization enabled1783builder # [ 8.029984] kvm_amd: Nested Paging enabled1784builder # [ 8.030382] kvm_amd: LBR virtualization supported1785builder # [ 8.031024] kvm_amd: Virtual VMLOAD VMSAVE supported1786builder # [ 8.031470] kvm_amd: Virtual GIF supported1787builder # [ 8.032163] kvm_amd: Virtual NMI enabled1788builder # [ 7.896249] systemd[1]: Finished resolvconf update.1789builder # [ 7.898253] systemd[1]: Reached target Preparation for Network.1790builder # [ 7.905825] systemd[1]: Starting DHCP Client...1791builder # [ 7.908111] systemd[1]: Starting Address configuration of eth1...1792builder # [ 7.911492] systemd[1]: Starting Extra networking commands....1793server # [ 7.865677] mousedev: PS/2 mouse device common for all mice1794server # [ 7.960667] rtc_cmos PNP0B00:00: RTC can wake from S41795builder # [ 8.088082] EDAC MC: Ver: 3.0.01796server # [ 7.977961] parport_pc 00:02: reported by Plug and Play ACPI1797server # [ 7.982400] rtc_cmos PNP0B00:00: registered as rtc01798server # [ 7.979156] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1799server # [ 7.982711] systemd[1]: Finished Create SUID/SGID Wrappers.1800server # [ 7.982470] rtc_cmos PNP0B00:00: setting system clock to 2026-10-08T21:39:43 UTC (1791495583)1801server # [ 7.982544] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1802server # [ 7.983307] systemd-journald[282]: Time jumped backwards, rotating.1803builder # [ 8.012651] network-addresses-eth1-start[622]: adding address 192.168.1.1/24... done1804server # [ 8.009525] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1805server # [ 8.061228] Console: switching to colour dummy device 80x251806server # [ 8.075406] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1807server # [ 8.161990] [drm] Found bochs VGA, ID 0xb0c5.1808server # [ 8.161992] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1809server # [ 8.024978] systemd[1]: Finished register-nix-paths.service.1810server # [ 8.025865] systemd[1]: Reached target System Initialization.1811server # [ 8.027236] systemd[1]: Started Discard unused filesystem blocks once a week.1812server # [ 8.028200] systemd[1]: Started niks3 garbage collection timer.1813builder # [ 8.038067] network-addresses-eth1-start[622]: adding address 2001:db8:1::1/64... done1814server # [ 8.029142] systemd[1]: Started Daily Cleanup of Temporary Directories.1815server # [ 8.031057] systemd[1]: Reached target Timer Units.1816server # [ 8.031775] systemd[1]: Listening on D-Bus System Message Bus Socket.1817server # [ 8.034585] systemd[1]: Starting niks3 server proxy socket...1818server # [ 8.041123] systemd[1]: Listening on niks3 server socket.1819server # [ 8.041936] systemd[1]: Listening on Nix Daemon Socket.1820server # [ 8.042784] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1821server # [ 8.046135] systemd[1]: Listening on niks3 server proxy socket.1822server # [ 8.048204] systemd[1]: Reached target Socket Units.1823server # [ 8.054898] systemd[1]: Starting D-Bus System Message Bus...1824builder # [ 8.068097] systemd[1]: Finished Address configuration of eth1.1825server # [ 8.059714] systemd[1]: Finished Firewall.1826server # [ 8.210415] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1827server # [ 8.211077] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01828builder # [ 8.085449] dhcpcd[631]: dhcpcd-10.3.2 starting1829builder # [ 8.092435] dhcpcd[684]: dev: loaded udev1830server # [ 8.224945] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1831server # [ 8.225677] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1832builder # [ 8.244175] 8021q: 802.1Q VLAN Support v1.81833builder # [ 8.244735] 8021q: adding VLAN 0 to HW filter on device eth11834builder # [ 8.108786] systemd[1]: Finished Extra networking commands..1835builder # [ 8.110055] systemd[1]: Reached target Network.1836builder # [ 8.114561] systemd[1]: Starting Permit User Sessions...1837builder # [ 8.141403] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1838server # [ 8.153477] systemd[1]: Starting Virtual Console Setup...1839server # [ 8.161452] dbus-broker-launch[516]: Looking up NSS user entry for 'systemd-timesync'...1840server # [ 8.168075] dbus-broker-launch[516]: NSS returned no entry for 'systemd-timesync'1841server # [ 8.169085] dbus-broker-launch[516]: Invalid user-name in /nix/store/cwglna2if3sijmj7gqsbsbwlrca6mvnz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1842builder # [ 8.197713] systemd[1]: Finished Permit User Sessions.1843builder # [ 8.338254] cfg80211: Loading compiled-in X.509 certificates for regulatory database1844server # [ 8.195065] systemd[1]: Started D-Bus System Message Bus.1845builder # [ 8.204615] systemd[1]: Started Getty on tty1.1846builder # [ 8.207532] systemd[1]: Reached target Login Prompts.1847server # [ 8.198943] systemd[1]: Reached target Basic System.1848builder # [ 8.358662] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1849builder # [ 8.359369] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1850server # [ 8.273853] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61851builder # [ 8.361379] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21852builder # [ 8.362234] cfg80211: failed to load regulatory.db1853server # [ 8.274414] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51854server # [ 8.297815] Console: switching to colour frame buffer device 160x501855builder # [ 8.251308] systemd-vconsole-setup[552]: Configuration of first virtual console was skipped, ignoring remaining ones.1856builder # [ 8.255061] systemd[1]: Finished Virtual Console Setup.1857builder # [ 8.397299] 8021q: adding VLAN 0 to HW filter on device eth01858builder # [ 8.259745] dhcpcd[684]: eth0: waiting for carrier1859builder # [ 8.260584] dhcpcd[684]: eth0: carrier acquired1860builder # [ 8.265414] dhcpcd[684]: DUID 00:01:00:01:32:5a:ca:1f:52:54:00:12:34:561861builder # [ 8.266341] dhcpcd[684]: eth0: IAID 00:12:34:561862builder # [ 8.266985] dhcpcd[684]: eth0: adding address fe80::5054:ff:fe12:34561863server # [ 8.446238] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1864server # [ 8.202617] systemd[1]: Started backdoor.service.1865server # [ 8.311873] systemd[1]: Starting Import lastlog data into lastlog2 database...1866server # [ 8.313236] systemd[1]: Starting Generate test mTLS certs...1867server # [ 8.320434] dbus-broker-launch[516]: Ready1868server # [ 8.323955] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1869server # connecting to host...1870server # [ 8.327745] systemd[1]: Starting Post-Boot Actions...1871server # [ 8.328756] systemd[1]: Started Reset console on configuration changes.1872server # [ 8.331388] systemd[1]: Starting resolvconf update...1873server # [ 8.361615] nsncd[526]: Oct 08 21:39:44.017 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1874server # [ 8.364211] systemd[1]: Started Name Service Cache Daemon (nsncd).1875server # [ 8.370099] niks3-test-certs-start[535]: -----1876server # [ 8.376706] systemd[1]: Finished Post-Boot Actions.1877server: Guest shell says: b'Spawning backdoor root shell...\n'1878server # [ 8.393107] systemd[1]: Reached target Host and Network Name Lookups.1879server: connected to guest root shell1880server # [ 8.395248] systemd[1]: Reached target User and Group Name Lookups.1881server: (connecting took 9.07 seconds)1882server: (finished: waiting for the VM to finish booting, in 9.07 seconds)1883server # [ 8.406802] niks3-test-certs-start[552]: -----1884server # [ 8.415767] systemd[1]: Starting User Login Management...1885server # [ 8.424427] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1886server # [ 8.430065] systemd[1]: Stopped Virtual Console Setup.1887server # [ 8.443233] systemd[1]: Finished Import lastlog data into lastlog2 database.1888server # [ 8.466244] systemd[1]: Starting Virtual Console Setup...1889server # [ 8.647113] ppdev: user-space parallel port driver1890server # [ 8.513882] niks3-test-certs-start[558]: Certificate request self-signature ok1891server # [ 8.515956] niks3-test-certs-start[558]: subject=CN=server1892server # [ 8.556315] niks3-test-certs-start[582]: -----1893server # [ 8.614689] niks3-test-certs-start[594]: Certificate request self-signature ok1894server # [ 8.617087] niks3-test-certs-start[594]: subject=CN=niks3 test client1895server # [ 8.623179] systemd-logind[554]: New seat seat0.1896server # [ 8.766829] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1897server # [ 8.628724] systemd-logind[554]: Watching system buttons on /dev/input/event2 (Power Button)1898server # [ 8.631143] systemd-logind[554]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1899server # [ 8.632352] systemd-logind[554]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1900server # [ 8.787659] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1901server # [ 8.659649] systemd[1]: Started User Login Management.1902server # [ 8.662336] systemd[1]: Finished Generate test mTLS certs.1903server # [ 8.685090] systemd[1]: Stopped target Host and Network Name Lookups.1904server # [ 8.685948] systemd[1]: Stopping Host and Network Name Lookups...1905server # [ 8.688169] systemd[1]: Stopped target User and Group Name Lookups.1906server # [ 8.688993] systemd[1]: Stopping User and Group Name Lookups...1907server # [ 8.697727] systemd[1]: Starting linger-users.service...1908server # [ 8.700340] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1909server # [ 8.705621] systemd[1]: nscd.service: Deactivated successfully.1910server # [ 8.709238] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1911server # [ 8.718905] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1912server # [ 8.724793] systemd[1]: Stopped Virtual Console Setup.1913server # [ 8.742343] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1914server # [ 8.748294] systemd[1]: Starting Virtual Console Setup...1915server # [ 8.793985] systemd[1]: linger-users.service: Deactivated successfully.1916server # [ 8.797901] systemd[1]: Finished linger-users.service.1917server # [ 8.834657] systemd[1]: Finished resolvconf update.1918server # [ 8.837377] systemd[1]: Reached target Preparation for Network.1919server # [ 8.845917] systemd[1]: Starting DHCP Client...1920server # [ 8.848171] systemd[1]: Starting Address configuration of eth1...1921server # [ 8.850684] nsncd[628]: Oct 08 21:39:44.506 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1922server # [ 8.862303] systemd[1]: Starting Extra networking commands....1923server # [ 8.863119] systemd[1]: Started Name Service Cache Daemon (nsncd).1924server # [ 8.863928] systemd[1]: Reached target Host and Network Name Lookups.1925server # [ 8.864788] systemd[1]: Reached target User and Group Name Lookups.1926server # [ 9.040064] kvm_amd: TSC scaling supported1927server # [ 9.040513] kvm_amd: Nested Virtualization enabled1928server # [ 9.040934] kvm_amd: Nested Paging enabled1929server # [ 9.045600] kvm_amd: LBR virtualization supported1930server # [ 9.046218] kvm_amd: Virtual VMLOAD VMSAVE supported1931server # [ 9.046668] kvm_amd: Virtual GIF supported1932server # [ 9.048199] kvm_amd: Virtual NMI enabled1933server # [ 9.113566] EDAC MC: Ver: 3.0.01934server # [ 8.991251] network-addresses-eth1-start[640]: adding address 192.168.1.2/24... done1935server # [ 9.016061] network-addresses-eth1-start[640]: adding address 2001:db8:1::2/64... done1936server # [ 9.040851] systemd[1]: Finished Address configuration of eth1.1937server # [ 9.051227] dhcpcd[657]: dhcpcd-10.3.2 starting1938server # [ 9.056950] dhcpcd[712]: dev: loaded udev1939server # [ 9.207269] 8021q: 802.1Q VLAN Support v1.81940server # [ 9.207688] 8021q: adding VLAN 0 to HW filter on device eth11941server # [ 9.072264] systemd[1]: Finished Extra networking commands..1942server # [ 9.075167] systemd[1]: Reached target Network.1943server # [ 9.079419] systemd[1]: Started Mock OIDC server for testing.1944server # [ 9.084664] systemd[1]: Starting Nginx Web Server...1945builder # [ 9.098144] dhcpcd[684]: eth0: soliciting a DHCP lease1946server # [ 9.095228] systemd[1]: Starting PostgreSQL Server...1947server # [ 9.098262] systemd[1]: Started RustFS S3-compatible object storage.1948server # [ 9.105221] systemd[1]: Starting Setup RustFS bucket...1949builder # [ 9.254803] NET: Registered PF_PACKET protocol family1950builder # [ 9.121871] dhcpcd[684]: eth0: offered 10.0.2.15 from 10.0.2.21951builder # [ 9.126365] dhcpcd[684]: eth0: probing address 10.0.2.15/241952server # [ 9.118404] systemd[1]: Starting Permit User Sessions...1953server # [ 9.150368] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1954server # [ 9.213179] systemd[1]: Finished Permit User Sessions.1955server # [ 9.222468] systemd[1]: Started Getty on tty1.1956server # [ 9.230488] systemd[1]: Reached target Login Prompts.1957server # [ 9.483952] cfg80211: Loading compiled-in X.509 certificates for regulatory database1958server # [ 9.384342] mock-oidc-server[719]: Mock OIDC Server running1959server # [ 9.386276] mock-oidc-server[719]: OIDC Address: 127.0.0.1:80801960server # [ 9.388305] mock-oidc-server[719]: Issue Address: 127.0.0.1:80811961server # [ 9.390245] mock-oidc-server[719]: Issuer: http://127.0.0.1:8080/oidc1962server # [ 9.392111] mock-oidc-server[719]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1963server # [ 9.393231] mock-oidc-server[719]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1964server # [ 9.394466] mock-oidc-server[719]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1965server # [ 9.574253] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1966server # [ 9.574909] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1967server # [ 9.584133] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21968server # [ 9.584957] cfg80211: failed to load regulatory.db1969server # [ 9.513334] nginx-pre-start[743]: nginx: the configuration file /nix/store/wq0r4aqvkh8wb8wvg82940njnf5njaa7-nginx.conf syntax is ok1970server # [ 9.514819] nginx-pre-start[743]: nginx: configuration file /nix/store/wq0r4aqvkh8wb8wvg82940njnf5njaa7-nginx.conf test is successful1971server # [ 9.526807] systemd[1]: Started Nginx Web Server.1972server # [ 9.688334] 8021q: adding VLAN 0 to HW filter on device eth01973server # [ 9.552649] dhcpcd[712]: eth0: waiting for carrier1974server # [ 9.553989] dhcpcd[712]: eth0: carrier acquired1975server # [ 9.558897] systemd-vconsole-setup[629]: Configuration of first virtual console was skipped, ignoring remaining ones.1976server # [ 9.565329] systemd[1]: Finished Virtual Console Setup.1977server # [ 9.579179] dhcpcd[712]: DUID 00:01:00:01:32:5a:ca:21:52:54:00:12:34:561978server # [ 9.580107] dhcpcd[712]: eth0: IAID 00:12:34:561979server # [ 9.580771] dhcpcd[712]: eth0: adding address fe80::5054:ff:fe12:34561980server # [ 9.612895] postgresql-pre-start[748]: The files belonging to this database system will be owned by user "postgres".1981server # [ 9.614279] postgresql-pre-start[748]: This user must also own the server process.1982server # [ 9.622853] postgresql-pre-start[748]: The database cluster will be initialized with locale "en_US.UTF-8".1983server # [ 9.624136] postgresql-pre-start[748]: The default database encoding has accordingly been set to "UTF8".1984server # [ 9.625302] postgresql-pre-start[748]: The default text search configuration will be set to "english".1985server # [ 9.626502] postgresql-pre-start[748]: Data page checksums are enabled.1986server # [ 9.627348] postgresql-pre-start[748]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1987server # [ 9.628501] postgresql-pre-start[748]: creating subdirectories ... ok1988server # [ 9.629376] postgresql-pre-start[748]: selecting dynamic shared memory implementation ... posix1989server # [ 9.732821] postgresql-pre-start[748]: selecting default "max_connections" ... 1001990server # [ 9.837558] postgresql-pre-start[748]: selecting default "shared_buffers" ... 128MB1991builder # [ 9.928443] dhcpcd[684]: eth0: soliciting an IPv6 router1992builder # [ 9.931242] dhcpcd[684]: eth0: Router Advertisement from fe80::21993builder # [ 9.933441] dhcpcd[684]: eth0: adding address fec0::5054:ff:fe12:3456/641994builder # [ 9.935601] dhcpcd[684]: eth0: adding route to fec0::/641995builder # [ 9.937567] dhcpcd[684]: eth0: adding default route via fe80::21996server # [ 10.133598] dhcpcd[712]: eth0: soliciting a DHCP lease1997server # [ 10.283936] NET: Registered PF_PACKET protocol family1998server # [ 10.149498] dhcpcd[712]: eth0: offered 10.0.2.15 from 10.0.2.21999server # [ 10.152155] dhcpcd[712]: eth0: probing address 10.0.2.15/242000server # [ 10.546440] postgresql-pre-start[748]: selecting default time zone ... UTC2001server # [ 10.549191] postgresql-pre-start[748]: creating configuration files ... ok2002server # [ 10.726541] postgresql-pre-start[748]: running bootstrap script ... ok2003server # [ 11.114889] postgresql-pre-start[748]: performing post-bootstrap initialization ... ok2004server # [ 11.261627] postgresql-pre-start[748]: syncing data to disk ... ok2005server # [ 11.263409] postgresql-pre-start[748]: initdb: warning: enabling "trust" authentication for local connections2006server # [ 11.264618] postgresql-pre-start[748]: 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.2007server # [ 11.266471] postgresql-pre-start[748]: Success. You can now start the database server using:2008server # [ 11.267530] postgresql-pre-start[748]: pg_ctl -D /var/lib/postgresql/18 -l logfile start2009server # [ 11.342252] postgres[803]: [803] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit2010server # [ 11.344706] postgres[803]: [803] LOG: listening on IPv6 address "::1", port 54322011server # [ 11.345781] postgres[803]: [803] LOG: listening on IPv4 address "127.0.0.1", port 54322012server # [ 11.348456] postgres[803]: [803] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2013server # [ 11.359309] postgres[812]: [812] LOG: database system was shut down at 2026-10-08 21:39:46 GMT2014server # [ 11.366286] postgres[803]: [803] LOG: database system is ready to accept connections2015server # [ 11.371494] systemd[1]: Started PostgreSQL Server.2016server # [ 11.376575] systemd[1]: Starting PostgreSQL Setup Scripts...2017server # [ 11.499375] postgresql-setup-start[823]: CREATE DATABASE2018server # [ 11.527837] postgresql-setup-start[828]: CREATE ROLE2019server # [ 11.540232] postgresql-setup-start[830]: ALTER DATABASE2020server # [ 11.544406] systemd[1]: Finished PostgreSQL Setup Scripts.2021server # [ 11.546282] systemd[1]: Reached target PostgreSQL.2022server # [ 11.641372] dhcpcd[712]: eth0: soliciting an IPv6 router2023server # [ 11.643832] dhcpcd[712]: eth0: Router Advertisement from fe80::22024server # [ 11.645485] dhcpcd[712]: eth0: adding address fec0::5054:ff:fe12:3456/642025server # [ 11.647300] dhcpcd[712]: eth0: adding route to fec0::/642026server # [ 11.648921] dhcpcd[712]: eth0: adding default route via fe80::22027server: (finished: waiting for unit postgresql.service, in 12.60 seconds)2028server: waiting for unit rustfs.service2029server: (finished: waiting for unit rustfs.service, in 0.03 seconds)2030server: waiting for unit rustfs-setup.service2031builder # [ 14.132263] dhcpcd[684]: eth0: leased 10.0.2.15 for 86400 seconds2032builder # [ 14.135255] dhcpcd[684]: eth0: adding route to 10.0.2.0/242033builder # [ 14.137472] dhcpcd[684]: eth0: adding default route via 10.0.2.22034builder # [ 14.218630] systemd[1]: Started DHCP Client.2035builder # [ 14.220899] systemd[1]: Reached target Multi-User System.2036builder # [ 14.221887] systemd[1]: Startup finished in 858ms (kernel) + 3.486s (initrd) + 9.876s (userspace) = 14.221s.2037server # [ 15.098462] dhcpcd[712]: eth0: leased 10.0.2.15 for 86400 seconds2038server # [ 15.100594] dhcpcd[712]: eth0: adding route to 10.0.2.0/242039server # [ 15.104336] dhcpcd[712]: eth0: adding default route via 10.0.2.22040server # [ 15.187309] systemd[1]: Started DHCP Client.2041server # [ 21.893628] rustfs-setup-start[949]: mb s3://niks3-test2042server # [ 21.901219] systemd[1]: Finished Setup RustFS bucket.2043server # [ 21.906771] systemd[1]: Starting niks3 server...2044server # [ 22.084990] niks3-server[959]: 2026/10/08 21:39:57 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc2045server # [ 22.091560] niks3-server[959]: 2026/10/08 21:39:57 INFO OIDC authentication enabled config=/nix/store/bkbqsygl8p1pr2ym64igz7c1024mfh8h-niks3-oidc.json2046server # [ 22.094104] niks3-server[959]: 2026/10/08 21:39:57 INFO Loaded signing key name=niks3-test-1 path=/nix/store/k9npvh083rh840nlx6wrpj8yxadviwqg-niks3-signing-key2047server # [ 22.118680] niks3-server[959]: 2026/10/08 21:39:57 INFO Using socket-activated listener address=0.0.0.0:57512048server # [ 22.120380] niks3-server[959]: 2026/10/08 21:39:57 INFO Using socket-activated listener address=/run/niks3/proxy.sock2049server # [ 22.122975] niks3-server[959]: 2026/10/08 21:39:57 INFO systemd watchdog enabled interval=15s2050server # [ 22.124192] niks3-server[959]: 2026/10/08 21:39:57 INFO Starting HTTP server address=0.0.0.0:57512051server # [ 22.125225] niks3-server[959]: 2026/10/08 21:39:57 INFO Starting HTTP server address=/run/niks3/proxy.sock2052server # [ 22.126479] systemd[1]: Started niks3 server.2053server # [ 22.128256] systemd[1]: Reached target Multi-User System.2054server # [ 22.129491] systemd[1]: Startup finished in 854ms (kernel) + 4.370s (initrd) + 16.903s (userspace) = 22.129s.2055server: (finished: waiting for unit rustfs-setup.service, in 10.47 seconds)2056server: waiting for unit mock-oidc.service2057server: (finished: waiting for unit mock-oidc.service, in 0.02 seconds)2058server: waiting for unit niks3.service2059server: (finished: waiting for unit niks3.service, in 0.02 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.02 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.02 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.02 seconds)2069server: must succeed: mkdir -p /tmp/test-config2070server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 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/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32074server # [ 22.711489] niks3-server[959]: 2026/10/08 21:39:58 INFO Received push request method=POST path=/api/pushes2075server # time=2026-10-08T21:39:58.390Z level=INFO msg="Uploading 5 paths to server (0 already cached)"2076server # time=2026-10-08T21:39:58.391Z level=INFO msg="Uploading jp8ql2fnd61xhvb4vsfp0bqwrzf16qrp-libunistring-1.4.2 (2.0MB)"2077server # time=2026-10-08T21:39:58.393Z level=INFO msg="Uploading h4wfwic161kxrr74jlzla5lsm28hgary-glibc-2.44-25 (33.9MB)"2078server # time=2026-10-08T21:39:58.393Z level=INFO msg="Uploading q0lbdx5yv93a3md74kjhm0v2kljjii89-libidn2-2.3.8 (363.6KB)"2079server # time=2026-10-08T21:39:58.393Z level=INFO msg="Uploading 5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3 (273.0KB)"2080server # time=2026-10-08T21:39:58.393Z level=INFO msg="Uploading g60m3aky9f59wgy1gi0y06xwmbw19d6m-xgcc-16.2.0-libgcc (197.5KB)"2081server # [ 22.907222] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=g60m3aky9f59wgy1gi0y06xwmbw19d6m.ls2082server # [ 22.977116] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=nar/02crsn39w9wxan1z3dz9g1zb76l09jb462qzb3q8r8zic4p2k6rg.nar.zst2083server # [ 23.029097] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=nar/0i9dmbwawwjffqf8xdnn52n7p0c0vb9ypla5yivcvf4nsfarl7qz.nar.zst2084server # [ 23.034791] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=jp8ql2fnd61xhvb4vsfp0bqwrzf16qrp.ls2085server # [ 23.041383] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=nar/1pcna1vp58anngysryn0b159180y9cm745s4qyxmdnxasbp47kcs.nar.zst2086server # [ 23.045527] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=q0lbdx5yv93a3md74kjhm0v2kljjii89.ls2087server # [ 23.051666] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=5z2yp3ysx8476c8g5w25b0smlgkjvaq3.ls2088server # [ 23.054424] niks3-server[959]: 2026/10/08 21:39:58 INFO Registered completed upload object_key=nar/1k684wya73qd387kbf1pdpzb986qalk7c7jbnh6ib5846i6q18yc.nar.zst2089server # [ 23.584775] niks3-server[959]: 2026/10/08 21:39:59 INFO Registered completed upload object_key=h4wfwic161kxrr74jlzla5lsm28hgary.ls2090server # [ 23.657773] niks3-server[959]: 2026/10/08 21:39:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete2091server # [ 23.675469] niks3-server[959]: 2026/10/08 21:39:59 INFO Completed multipart upload object_key=nar/0fr2nsbz2dnfhanm1flhgm8wr0adqk05zip2q6li5z87jk6s6b63.nar.zst upload_id=MTRmYzQzMWQtOTE2ZS00MzIxLTgyMzgtZWVkZmVlOWYxOTQ0LmY3YzEyMDEzLWY5NTktNDAxNS1hOGIxLWU2ZTE0ZDdkN2U1Y3gxNzkxNDk1NTk4Mzc5NDY1OTYz parts=12092server # [ 23.679161] niks3-server[959]: 2026/10/08 21:39:59 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign2093server # [ 23.683195] niks3-server[959]: 2026/10/08 21:39:59 INFO Signed narinfos id=1 count=52094server # time=2026-10-08T21:39:59.341Z level=INFO msg="Uploading 5 narinfos"2095server # [ 23.706850] niks3-server[959]: 2026/10/08 21:39:59 INFO Registered completed upload object_key=h4wfwic161kxrr74jlzla5lsm28hgary.narinfo2096server # [ 23.710223] niks3-server[959]: 2026/10/08 21:39:59 INFO Registered completed upload object_key=g60m3aky9f59wgy1gi0y06xwmbw19d6m.narinfo2097server # [ 23.714154] niks3-server[959]: 2026/10/08 21:39:59 INFO Registered completed upload object_key=q0lbdx5yv93a3md74kjhm0v2kljjii89.narinfo2098server # [ 23.716844] niks3-server[959]: 2026/10/08 21:39:59 INFO Received complete push request method=POST path=/api/pushes/1/complete2099server # [ 23.720117] niks3-server[959]: 2026/10/08 21:39:59 INFO Registered completed upload object_key=jp8ql2fnd61xhvb4vsfp0bqwrzf16qrp.narinfo2100server # [ 23.724375] niks3-server[959]: 2026/10/08 21:39:59 INFO Registered completed upload object_key=5z2yp3ysx8476c8g5w25b0smlgkjvaq3.narinfo2101server # time=2026-10-08T21:39:59.385Z level=INFO msg="Upload complete. (1.066s)"2102server # [ 23.731092] niks3-server[959]: 2026/10/08 21:39:59 INFO Completed push id=1 objects=15 duration=11.902909ms2103server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3, in 1.18 seconds)2104server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token2105server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)2106server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32107server # [ 23.817761] niks3-server[959]: 2026/10/08 21:39:59 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]2108server # [ 23.859222] niks3-server[959]: 2026/10/08 21:39:59 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]2109server # time=2026-10-08T21:39:59.518Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2110server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3, in 0.12 seconds)2111server: waiting for unit nginx.service2112server: (finished: waiting for unit nginx.service, in 0.02 seconds)2113server: waiting for TCP port 443 on localhost2114server # Connection to localhost (::1) 443 port [tcp/https] succeeded!2115server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)2116server: must succeed: /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32117server # time=2026-10-08T21:39:59.611Z 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.pem2118server # time=2026-10-08T21:39:59.628Z level=INFO msg="All 1 paths already cached"2119server: (finished: must succeed: /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3, in 0.07 seconds)2120server: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures2121server: (finished: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures, in 0.03 seconds)2122server: must fail: /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32123server # time=2026-10-08T21:39:59.667Z 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)"2124server: (finished: must fail: /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3, in 0.01 seconds)2125server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32126server # time=2026-10-08T21:39:59.719Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem2127server # time=2026-10-08T21:39:59.727Z level=INFO msg="All 1 paths already cached"2128server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3, in 0.06 seconds)2129server: must succeed: cd /etc/niks3-test-certs && /nix/store/8yv4f7k9phr5nri7z1sb3pm4f40q5wlb-openssl-3.5.8-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'2130server # -----2131server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/8yv4f7k9phr5nri7z1sb3pm4f40q5wlb-openssl-3.5.8-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.01 seconds)2132server: must succeed: cd /etc/niks3-test-certs && /nix/store/8yv4f7k9phr5nri7z1sb3pm4f40q5wlb-openssl-3.5.8-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem2133server # Certificate request self-signature ok2134server # subject=CN=other client2135server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/8yv4f7k9phr5nri7z1sb3pm4f40q5wlb-openssl-3.5.8-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.03 seconds)2136server: must fail: /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32137server # time=2026-10-08T21:39:59.821Z 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.pem2138server # [ 24.172944] niks3-server[959]: 2026/10/08 21:39:59 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2139server # [ 24.213603] niks3-server[959]: 2026/10/08 21:39:59 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2140server # time=2026-10-08T21:39:59.871Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2141server: (finished: must fail: /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3, in 0.10 seconds)2142server: must succeed: mkdir -p /tmp/test-store2143server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.01 seconds)2144server: must succeed: 2145 export AWS_ACCESS_KEY_ID=rustfsadmin2146export AWS_SECRET_ACCESS_KEY=rustfsadmin2147 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.321482149server # copying 5 paths...2150server # copying path '/nix/store/g60m3aky9f59wgy1gi0y06xwmbw19d6m-xgcc-16.2.0-libgcc' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2151server # copying path '/nix/store/jp8ql2fnd61xhvb4vsfp0bqwrzf16qrp-libunistring-1.4.2' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2152server # copying path '/nix/store/q0lbdx5yv93a3md74kjhm0v2kljjii89-libidn2-2.3.8' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2153server # copying path '/nix/store/h4wfwic161kxrr74jlzla5lsm28hgary-glibc-2.44-25' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2154server # copying path '/nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2155server: (finished: must succeed: 2156 export AWS_ACCESS_KEY_ID=rustfsadmin2157export AWS_SECRET_ACCESS_KEY=rustfsadmin2158 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32159, in 0.36 seconds)2160server: must succeed: 2161cat > /tmp/test-drv.nix << 'EOF'2162derivation {2163 name = "test-build-log";2164 system = builtins.currentSystem;2165 builder = "/bin/sh";2166 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2167}2168EOF21692170server: (finished: must succeed: 2171cat > /tmp/test-drv.nix << 'EOF'2172derivation {2173 name = "test-build-log";2174 system = builtins.currentSystem;2175 builder = "/bin/sh";2176 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2177}2178EOF2179, in 0.01 seconds)2180server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix2181server # this derivation will be built:2182server # /nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2183server # building '/nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv'...2184server # test-build-log> test build log output2185server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.15 seconds)2186server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2187server # [ 24.863177] niks3-server[959]: 2026/10/08 21:40:00 INFO Received push request method=POST path=/api/pushes2188server # time=2026-10-08T21:40:00.530Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2189server # time=2026-10-08T21:40:00.531Z level=INFO msg="Uploading dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log (128B)"2190server # [ 24.897794] niks3-server[959]: 2026/10/08 21:40:00 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign2191server # [ 24.901082] niks3-server[959]: 2026/10/08 21:40:00 INFO Registered completed upload object_key=log/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2192server # time=2026-10-08T21:40:00.560Z level=INFO msg="Uploading 1 narinfos"2193server # [ 24.905373] niks3-server[959]: 2026/10/08 21:40:00 INFO Signed narinfos id=2 count=12194server # [ 24.908697] niks3-server[959]: 2026/10/08 21:40:00 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.ls2195server # [ 24.916639] niks3-server[959]: 2026/10/08 21:40:00 INFO Received complete push request method=POST path=/api/pushes/2/complete2196server # [ 24.922963] niks3-server[959]: 2026/10/08 21:40:00 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst2197server # [ 24.927879] niks3-server[959]: 2026/10/08 21:40:00 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2198server # [ 24.935183] niks3-server[959]: 2026/10/08 21:40:00 INFO Completed push id=2 objects=4 duration=18.697348ms2199server # time=2026-10-08T21:40:00.593Z level=INFO msg="Upload complete. (115ms)"2200server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log, in 0.18 seconds)2201server: must succeed: 2202 export AWS_ACCESS_KEY_ID=rustfsadmin2203export AWS_SECRET_ACCESS_KEY=rustfsadmin2204 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log22052206server # got build log for '/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'2207server: (finished: must succeed: 2208 export AWS_ACCESS_KEY_ID=rustfsadmin2209export AWS_SECRET_ACCESS_KEY=rustfsadmin2210 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2211, in 0.09 seconds)2212subtest: push --stdin streams paths and reports each one2213server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'2214server # this derivation will be built:2215server # /nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2216server # building '/nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv'...2217server: (finished: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }', in 0.15 seconds)2218server: must succeed: printf '%s\n\n%s\n' /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --stdin2219server # [ 25.300981] niks3-server[959]: 2026/10/08 21:40:00 INFO Received push request method=POST path=/api/pushes2220server # time=2026-10-08T21:40:00.964Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2221server # time=2026-10-08T21:40:00.965Z level=INFO msg="Uploading 3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test (120B)"2222server # [ 25.329491] niks3-server[959]: 2026/10/08 21:40:00 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.ls2223server # [ 25.337718] niks3-server[959]: 2026/10/08 21:40:00 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst2224server # [ 25.341314] niks3-server[959]: 2026/10/08 21:40:00 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign2225server # time=2026-10-08T21:40:01.000Z level=INFO msg="Uploading 1 narinfos"2226server # [ 25.345265] niks3-server[959]: 2026/10/08 21:40:01 INFO Signed narinfos id=3 count=12227server # [ 25.355636] niks3-server[959]: 2026/10/08 21:40:01 INFO Registered completed upload object_key=log/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2228server # [ 25.359393] niks3-server[959]: 2026/10/08 21:40:01 INFO Received complete push request method=POST path=/api/pushes/3/complete2229server # [ 25.365565] niks3-server[959]: 2026/10/08 21:40:01 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.narinfo2230server # time=2026-10-08T21:40:01.026Z level=INFO msg="Upload complete. (123ms)"2231server # [ 25.371433] niks3-server[959]: 2026/10/08 21:40:01 INFO Completed push id=3 objects=4 duration=11.112306ms2232server: (finished: must succeed: printf '%s\n\n%s\n' /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --stdin, in 0.19 seconds)2233server: must succeed: 2234 export AWS_ACCESS_KEY_ID=rustfsadmin2235export AWS_SECRET_ACCESS_KEY=rustfsadmin2236 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2237 2238server # copying 1 paths...2239server # copying path '/nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2240server: (finished: must succeed: 2241 export AWS_ACCESS_KEY_ID=rustfsadmin2242export AWS_SECRET_ACCESS_KEY=rustfsadmin2243 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2244 , in 0.15 seconds)2245(finished: subtest: push --stdin streams paths and reports each one, in 0.49 seconds)2246server: must succeed: 2247cat > /tmp/ca-test.nix << 'EOF'2248derivation {2249 name = "ca-test";2250 system = builtins.currentSystem;2251 builder = "/bin/sh";2252 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];2253 __contentAddressed = true;2254 outputHashMode = "recursive";2255 outputHashAlgo = "sha256";2256}2257EOF22582259server: (finished: must succeed: 2260cat > /tmp/ca-test.nix << 'EOF'2261derivation {2262 name = "ca-test";2263 system = builtins.currentSystem;2264 builder = "/bin/sh";2265 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];2266 __contentAddressed = true;2267 outputHashMode = "recursive";2268 outputHashAlgo = "sha256";2269}2270EOF2271, in 0.01 seconds)2272server: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link2273server # this derivation will be built:2274server # /nix/store/r63z2m5ya18s9prsh98knax41688hrbd-ca-test.drv2275server # building '/nix/store/r63z2m5ya18s9prsh98knax41688hrbd-ca-test.drv'...2276server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link, in 0.15 seconds)2277server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2278server # [ 25.855673] niks3-server[959]: 2026/10/08 21:40:01 INFO Received push request method=POST path=/api/pushes2279server # time=2026-10-08T21:40:01.518Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2280server # time=2026-10-08T21:40:01.519Z level=INFO msg="Uploading 5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test (144B)"2281server # [ 25.887968] niks3-server[959]: 2026/10/08 21:40:01 INFO Registered completed upload object_key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst2282server # [ 25.891577] niks3-server[959]: 2026/10/08 21:40:01 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign2283server # [ 25.895251] niks3-server[959]: 2026/10/08 21:40:01 INFO Signed narinfos id=4 count=12284server # time=2026-10-08T21:40:01.554Z level=INFO msg="Uploading 1 narinfos"2285server # [ 25.902634] niks3-server[959]: 2026/10/08 21:40:01 INFO Registered completed upload object_key=log/r63z2m5ya18s9prsh98knax41688hrbd-ca-test.drv2286server # [ 25.912418] niks3-server[959]: 2026/10/08 21:40:01 INFO Received complete push request method=POST path=/api/pushes/4/complete2287server # [ 25.913910] niks3-server[959]: 2026/10/08 21:40:01 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.ls2288server # time=2026-10-08T21:40:01.576Z level=INFO msg="Upload complete. (164ms)"2289server # [ 25.921502] niks3-server[959]: 2026/10/08 21:40:01 INFO Completed push id=4 objects=4 duration=8.265296ms2290server # [ 25.926816] niks3-server[959]: 2026/10/08 21:40:01 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.narinfo2291server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.24 seconds)2292server: must succeed: mkdir -p /tmp/chroot-store2293server: (finished: must succeed: mkdir -p /tmp/chroot-store, in 0.02 seconds)2294server: must succeed: 2295 export AWS_ACCESS_KEY_ID=rustfsadmin2296export AWS_SECRET_ACCESS_KEY=rustfsadmin2297 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test22982299server # copying 1 paths...2300server # copying path '/nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2301server: (finished: must succeed: 2302 export AWS_ACCESS_KEY_ID=rustfsadmin2303export AWS_SECRET_ACCESS_KEY=rustfsadmin2304 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2305, in 0.12 seconds)2306server: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2307server: (finished: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.05 seconds)2308server: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2309server # warning: 'realisation' is a deprecated alias for 'store build-trace'2310server: (finished: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.05 seconds)2311server: must succeed: readlink /etc/niks3-test/symlink-wrapper2312server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.01 seconds)2313server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2314server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds)2315server: must succeed: test -L /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper2316server: (finished: must succeed: test -L /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper, in 0.01 seconds)2317server: must succeed: readlink /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper2318server: (finished: must succeed: readlink /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper, in 0.01 seconds)2319server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper2320server # [ 26.328370] niks3-server[959]: 2026/10/08 21:40:01 INFO Received push request method=POST path=/api/pushes2321server # time=2026-10-08T21:40:01.989Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2322server # time=2026-10-08T21:40:01.990Z level=INFO msg="Uploading 7nwsqp4js976gl0808sngrnshdk4cs7q-base-package (536B)"2323server # time=2026-10-08T21:40:01.991Z level=INFO msg="Uploading jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper (192B)"2324server # [ 26.367501] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=nar/0mbpnzm125x2wysrhagbzjdw6grgbjjh3i7rlgyvkgnbbc1yjx0q.nar.zst2325server # [ 26.373737] niks3-server[959]: 2026/10/08 21:40:02 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign2326server # [ 26.376771] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=jniavmcf0bw6qv82dk88mr7vy02xwyxp.ls2327server # [ 26.380756] niks3-server[959]: 2026/10/08 21:40:02 INFO Signed narinfos id=5 count=22328server # time=2026-10-08T21:40:02.038Z level=INFO msg="Uploading 2 narinfos"2329server # [ 26.385488] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2330server # [ 26.394353] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=7nwsqp4js976gl0808sngrnshdk4cs7q.ls2331server # [ 26.397987] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=jniavmcf0bw6qv82dk88mr7vy02xwyxp.narinfo2332server # [ 26.401282] niks3-server[959]: 2026/10/08 21:40:02 INFO Received complete push request method=POST path=/api/pushes/5/complete2333server # [ 26.411139] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=7nwsqp4js976gl0808sngrnshdk4cs7q.narinfo2334server # time=2026-10-08T21:40:02.071Z level=INFO msg="Upload complete. (136ms)"2335server # [ 26.417525] niks3-server[959]: 2026/10/08 21:40:02 INFO Completed push id=5 objects=6 duration=13.830808ms2336server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper, in 0.20 seconds)2337server: must succeed: 2338 export AWS_ACCESS_KEY_ID=rustfsadmin2339export AWS_SECRET_ACCESS_KEY=rustfsadmin2340 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper23412342server # copying 2 paths...2343server # copying path '/nix/store/7nwsqp4js976gl0808sngrnshdk4cs7q-base-package' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2344server # copying path '/nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2345server: (finished: must succeed: 2346 export AWS_ACCESS_KEY_ID=rustfsadmin2347export AWS_SECRET_ACCESS_KEY=rustfsadmin2348 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper2349, in 0.10 seconds)2350server: must succeed: 2351cat > /tmp/oidc-test.nix << 'EOF'2352derivation {2353 name = "oidc-test";2354 system = builtins.currentSystem;2355 builder = "/bin/sh";2356 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2357}2358EOF23592360server: (finished: must succeed: 2361cat > /tmp/oidc-test.nix << 'EOF'2362derivation {2363 name = "oidc-test";2364 system = builtins.currentSystem;2365 builder = "/bin/sh";2366 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2367}2368EOF2369, in 0.01 seconds)2370server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2371server # this derivation will be built:2372server # /nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2373server # building '/nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv'...2374server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.14 seconds)2375server: 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'2376server: (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.02 seconds)2377server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Il92Ny1iMlJBdFZWNnJ0V0o3OWFvcENwTlY2U2VIb0Q1NjVPc0dGRkVzVTQiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0OTkyMDIsImlhdCI6MTc5MTQ5NTYwMiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.LIqQYg2Q8ubVQJl6SGEo0AC5dmB-5ReiRwz0-vJD7jt1QPZwIi19pek2a2S3Sk0J_vq1I2Hk43o4Fr41v5aqyYdqL7FXhxfNteIOjtFAsIz2WOEi5Bo_0U-Mwx_JxpJ2wU_dUjHKP9GCaEO6ljtFrHejNTGJUGNIvEbFW_eswGyOE49Ru3GVv2NgY9i33p7Yf_MZKue_v0Moq-grtCz8Kcot7qrLvGgYAyrq3i19ZnsbbHEBY8clRZbKI9POFpoDhFVh_h-nsfoPTgiU_4XuPkKKlUN94I4LLjfFZuJhjXsfhINSOlebor9QN4V7HMLZ34VFn_-7d_bKh8BNXYEkpw' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2378server # time=2026-10-08T21:40:02.366Z 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 # [ 26.800654] niks3-server[959]: 2026/10/08 21:40:02 INFO Received push request method=POST path=/api/pushes2380server # time=2026-10-08T21:40:02.461Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2381server # time=2026-10-08T21:40:02.462Z level=INFO msg="Uploading hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test (136B)"2382server # [ 26.831292] niks3-server[959]: 2026/10/08 21:40:02 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign2383server # [ 26.832762] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2384server # time=2026-10-08T21:40:02.492Z level=INFO msg="Uploading 1 narinfos"2385server # [ 26.838349] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=log/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2386server # [ 26.840140] niks3-server[959]: 2026/10/08 21:40:02 INFO Signed narinfos id=6 count=12387server # [ 26.842849] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.ls2388server # [ 26.849260] niks3-server[959]: 2026/10/08 21:40:02 INFO Received complete push request method=POST path=/api/pushes/6/complete2389server # [ 26.852162] niks3-server[959]: 2026/10/08 21:40:02 INFO Completed push id=6 objects=4 duration=4.567899ms2390server # time=2026-10-08T21:40:02.510Z level=INFO msg="Upload complete. (100ms)"2391server # [ 26.857167] niks3-server[959]: 2026/10/08 21:40:02 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.narinfo2392server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Il92Ny1iMlJBdFZWNnJ0V0o3OWFvcENwTlY2U2VIb0Q1NjVPc0dGRkVzVTQiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0OTkyMDIsImlhdCI6MTc5MTQ5NTYwMiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.LIqQYg2Q8ubVQJl6SGEo0AC5dmB-5ReiRwz0-vJD7jt1QPZwIi19pek2a2S3Sk0J_vq1I2Hk43o4Fr41v5aqyYdqL7FXhxfNteIOjtFAsIz2WOEi5Bo_0U-Mwx_JxpJ2wU_dUjHKP9GCaEO6ljtFrHejNTGJUGNIvEbFW_eswGyOE49Ru3GVv2NgY9i33p7Yf_MZKue_v0Moq-grtCz8Kcot7qrLvGgYAyrq3i19ZnsbbHEBY8clRZbKI9POFpoDhFVh_h-nsfoPTgiU_4XuPkKKlUN94I4LLjfFZuJhjXsfhINSOlebor9QN4V7HMLZ34VFn_-7d_bKh8BNXYEkpw' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test, in 0.16 seconds)2393server: must succeed: 2394cat > /tmp/oidc-test2.nix << 'EOF'2395derivation {2396 name = "oidc-test2";2397 system = builtins.currentSystem;2398 builder = "/bin/sh";2399 args = [ "-c" "echo 'OIDC test 2' > $out" ];2400}2401EOF24022403server: (finished: must succeed: 2404cat > /tmp/oidc-test2.nix << 'EOF'2405derivation {2406 name = "oidc-test2";2407 system = builtins.currentSystem;2408 builder = "/bin/sh";2409 args = [ "-c" "echo 'OIDC test 2' > $out" ];2410}2411EOF2412, in 0.02 seconds)2413server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2414server # this derivation will be built:2415server # /nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv2416server # building '/nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv'...2417server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.13 seconds)2418server: 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'2419server: (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.02 seconds)2420server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Il92Ny1iMlJBdFZWNnJ0V0o3OWFvcENwTlY2U2VIb0Q1NjVPc0dGRkVzVTQiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0OTkyMDIsImlhdCI6MTc5MTQ5NTYwMiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.f-vuk52QssYWue-WDtzAnriiNG6X1IS8caonrk5Hv-Yppag_SwOLtFRC9k3AH7qWlL7KTx1RWA0ds1jtWQrA1cKrBkMywt1nXYZ_Uxq3nfV8xKod3CkEx9w7DOOG26jJghMrSNZQ7GUbgdzRjNUhLruKL1Diq1uCubzWNaisPhVvAH6XQEWmPOUYpI_vID4AJwnpBnz-V4fMPLe2XpluDwySRGJnwY5eFm8IbvC9zWdFvkq3Yp5zghoc_ax9p8BfeYGD1ZVgY5JX4DPw8Iv9nXETeddmGR-H5iihrveBC8X2rrb55orD4FpnqX4kQ9CJr8wwotkfGAt4R2kVAk_TSw' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22421server # time=2026-10-08T21:40:02.701Z 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"2422server # [ 27.088855] niks3-server[959]: 2026/10/08 21:40:02 WARN Authentication failed token_preview=eyJhbGciOi...R2kVAk_TSw token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2423server # [ 27.130829] niks3-server[959]: 2026/10/08 21:40:02 WARN Authentication failed token_preview=eyJhbGciOi...R2kVAk_TSw token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2424server # time=2026-10-08T21:40:02.790Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2425server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Il92Ny1iMlJBdFZWNnJ0V0o3OWFvcENwTlY2U2VIb0Q1NjVPc0dGRkVzVTQiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTE0OTkyMDIsImlhdCI6MTc5MTQ5NTYwMiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.f-vuk52QssYWue-WDtzAnriiNG6X1IS8caonrk5Hv-Yppag_SwOLtFRC9k3AH7qWlL7KTx1RWA0ds1jtWQrA1cKrBkMywt1nXYZ_Uxq3nfV8xKod3CkEx9w7DOOG26jJghMrSNZQ7GUbgdzRjNUhLruKL1Diq1uCubzWNaisPhVvAH6XQEWmPOUYpI_vID4AJwnpBnz-V4fMPLe2XpluDwySRGJnwY5eFm8IbvC9zWdFvkq3Yp5zghoc_ax9p8BfeYGD1ZVgY5JX4DPw8Iv9nXETeddmGR-H5iihrveBC8X2rrb55orD4FpnqX4kQ9CJr8wwotkfGAt4R2kVAk_TSw' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.10 seconds)2426server: 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'2427server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.02 seconds)2428server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Il92Ny1iMlJBdFZWNnJ0V0o3OWFvcENwTlY2U2VIb0Q1NjVPc0dGRkVzVTQiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MTQ5OTIwMiwiaWF0IjoxNzkxNDk1NjAyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.YupJX0L2bsVT85YBn51_fR29uXrPS9gAdyn_32IpBMJmlh3_UtR0eIi8WaRp6IsicEHue7sKHgL80oNROJ4LSFDVTUhQB0rr28xAEFPAjeyb8XFM0QcQGihxzGYsLN9zf__05UH-47glLNig_thLAfdrHyVSl7JdeTEW6Si48j9rTcMFxQkwxr-khcAjXgH_bdI_7g-6Rvm06_1ct7RnxO_rXwgxnM24tGvqttbbfXk0-ChDN6TvAc72aII7gDdN7vRUH8YN5Jt_7BFWHze5r9PZfRPfJlpkeQjyXJFav88Eg1YV9ZCKWIt3IlAeBhNOen6VuaL4vPt3PxNzXRlJkw' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22429server # time=2026-10-08T21:40:02.828Z 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"2430server # [ 27.216093] niks3-server[959]: 2026/10/08 21:40:02 WARN Authentication failed token_preview=eyJhbGciOi...PxNzXRlJkw token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2431server # [ 27.257683] niks3-server[959]: 2026/10/08 21:40:02 WARN Authentication failed token_preview=eyJhbGciOi...PxNzXRlJkw token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2432server # time=2026-10-08T21:40:02.916Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2433server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6Il92Ny1iMlJBdFZWNnJ0V0o3OWFvcENwTlY2U2VIb0Q1NjVPc0dGRkVzVTQiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MTQ5OTIwMiwiaWF0IjoxNzkxNDk1NjAyLCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.YupJX0L2bsVT85YBn51_fR29uXrPS9gAdyn_32IpBMJmlh3_UtR0eIi8WaRp6IsicEHue7sKHgL80oNROJ4LSFDVTUhQB0rr28xAEFPAjeyb8XFM0QcQGihxzGYsLN9zf__05UH-47glLNig_thLAfdrHyVSl7JdeTEW6Si48j9rTcMFxQkwxr-khcAjXgH_bdI_7g-6Rvm06_1ct7RnxO_rXwgxnM24tGvqttbbfXk0-ChDN6TvAc72aII7gDdN7vRUH8YN5Jt_7BFWHze5r9PZfRPfJlpkeQjyXJFav88Eg1YV9ZCKWIt3IlAeBhNOen6VuaL4vPt3PxNzXRlJkw' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.10 seconds)2434server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22435server # time=2026-10-08T21:40:02.931Z 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"2436server # [ 27.317862] niks3-server[959]: 2026/10/08 21:40:02 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]2437server # time=2026-10-08T21:40:03.014Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2438server # [ 27.359826] niks3-server[959]: 2026/10/08 21:40:03 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2439server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.10 seconds)2440server: must succeed: 2441 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins create hello-pin /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.324422443server # [ 27.416610] niks3-server[959]: 2026/10/08 21:40:03 INFO Received create pin request method=POST path=/api/pins/hello-pin2444server # [ 27.425438] niks3-server[959]: 2026/10/08 21:40:03 INFO Created/updated pin name=hello-pin store_path=/nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.3 narinfo_key=5z2yp3ysx8476c8g5w25b0smlgkjvaq3.narinfo2445server # time=2026-10-08T21:40:03.084Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32446server: (finished: must succeed: 2447 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins create hello-pin /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32448, in 0.07 seconds)2449server: must succeed: 2450 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list24512452server # [ 27.485644] niks3-server[959]: 2026/10/08 21:40:03 INFO Received list pins request method=GET path=/api/pins2453server: (finished: must succeed: 2454 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list2455, in 0.06 seconds)2456server: must succeed: 2457 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --names-only24582459server # [ 27.542190] niks3-server[959]: 2026/10/08 21:40:03 INFO Received list pins request method=GET path=/api/pins2460server: (finished: must succeed: 2461 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --names-only2462, in 0.06 seconds)2463server: must succeed: 2464 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --json24652466server # [ 27.597942] niks3-server[959]: 2026/10/08 21:40:03 INFO Received list pins request method=GET path=/api/pins2467server: (finished: must succeed: 2468 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --json2469, in 0.06 seconds)2470server: must succeed: 2471 export S3_ENDPOINT_URL=http://localhost:90002472 export AWS_ACCESS_KEY_ID=rustfsadmin2473 export AWS_SECRET_ACCESS_KEY=rustfsadmin2474 /nix/store/bln583k1n4rqwfpisyjxzkqdvni13g78-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin24752476server: (finished: must succeed: 2477 export S3_ENDPOINT_URL=http://localhost:90002478 export AWS_ACCESS_KEY_ID=rustfsadmin2479 export AWS_SECRET_ACCESS_KEY=rustfsadmin2480 /nix/store/bln583k1n4rqwfpisyjxzkqdvni13g78-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2481, in 0.02 seconds)2482server: must succeed: 2483 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log24842485server # time=2026-10-08T21:40:03.336Z level=INFO msg="All 1 paths already cached"2486server # [ 27.681197] niks3-server[959]: 2026/10/08 21:40:03 INFO Received create pin request method=POST path=/api/pins/ca-pin2487server # time=2026-10-08T21:40:03.344Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2488server # [ 27.689535] niks3-server[959]: 2026/10/08 21:40:03 INFO Created/updated pin name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log narinfo_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2489server: (finished: must succeed: 2490 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2491, in 0.07 seconds)2492server: must succeed: 2493 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --names-only24942495server # [ 27.748770] niks3-server[959]: 2026/10/08 21:40:03 INFO Received list pins request method=GET path=/api/pins2496server: (finished: must succeed: 2497 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --names-only2498, in 0.06 seconds)2499server: must succeed: 2500 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins delete hello-pin25012502server # [ 27.804666] niks3-server[959]: 2026/10/08 21:40:03 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2503server # [ 27.813064] niks3-server[959]: 2026/10/08 21:40:03 INFO Deleted pin name=hello-pin2504server # time=2026-10-08T21:40:03.471Z level=INFO msg="Deleted pin" name=hello-pin2505server: (finished: must succeed: 2506 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins delete hello-pin2507, in 0.06 seconds)2508server: must succeed: 2509 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --names-only25102511server # [ 27.873108] niks3-server[959]: 2026/10/08 21:40:03 INFO Received list pins request method=GET path=/api/pins2512server: (finished: must succeed: 2513 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins list --names-only2514, in 0.06 seconds)2515server: must fail: 2516 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent25172518server # [ 27.930729] niks3-server[959]: 2026/10/08 21:40:03 INFO Received create pin request method=POST path=/api/pins/bad-pin2519server # time=2026-10-08T21:40:03.589Z level=ERROR msg="Fatal error" error="creating pin: server returned 400: invalid store path: invalid store path format: /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent\n"2520server: (finished: must fail: 2521 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/2jaqan46fsqjzk0h2n27d9700hc64clq-niks3-1.15.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2522, in 0.06 seconds)2523server: must succeed: systemctl start niks3-gc.service2524server # [ 27.955481] systemd[1]: Starting niks3 garbage collection...2525server # [ 27.998697] niks3[1543]: time=2026-10-08T21:40:03.654Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2526server # [ 28.002307] niks3-server[959]: 2026/10/08 21:40:03 INFO Starting cleanup of old closures method=DELETE path=/api/closures2527server # [ 28.003681] niks3[1543]: time=2026-10-08T21:40:03.658Z level=INFO msg="Garbage collection started"2528server # [ 28.006396] niks3-server[959]: 2026/10/08 21:40:03 INFO Aborted multipart uploads count=0 kept=02529server # [ 28.013057] niks3-server[959]: 2026/10/08 21:40:03 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=02530server # [ 28.019492] niks3-server[959]: 2026/10/08 21:40:03 INFO Vacuumed table table=pending_closures2531server # [ 28.023768] niks3-server[959]: 2026/10/08 21:40:03 INFO Vacuumed table table=pending_objects2532server # [ 28.027316] niks3-server[959]: 2026/10/08 21:40:03 INFO Vacuumed table table=multipart_uploads2533server # [ 28.031233] niks3-server[959]: 2026/10/08 21:40:03 INFO Vacuumed table table=closures2534server # [ 28.033235] niks3-server[959]: 2026/10/08 21:40:03 INFO Vacuumed table table=objects2535server # [ 30.008856] niks3[1543]: time=2026-10-08T21:40:05.664Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=02536server # [ 30.022771] systemd[1]: niks3-gc.service: Deactivated successfully.2537server # [ 30.026514] systemd[1]: Finished niks3 garbage collection.2538server # [ 30.030943] systemd[1]: niks3-gc.service: Consumed 37ms CPU time over 2.070s wall clock time, 2.7M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2539server: (finished: must succeed: systemctl start niks3-gc.service, in 2.11 seconds)2540builder: waiting for unit niks3-auto-upload.socket2541builder: waiting for the VM to finish booting2542builder: Guest shell says: b'Spawning backdoor root shell...\n'2543builder: connected to guest root shell2544builder: (connecting took 0.00 seconds)2545builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2546builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.05 seconds)2547builder: must succeed: test -S /run/niks3/upload-to-cache.sock2548builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2549builder: must succeed: grep post-build-hook /etc/nix/nix.conf2550builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.01 seconds)2551builder: must succeed: 2552cat > /tmp/test-drv.nix << 'EOF'2553derivation {2554 name = "post-build-hook-test";2555 system = builtins.currentSystem;2556 builder = "/bin/sh";2557 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2558}2559EOF25602561builder: (finished: must succeed: 2562cat > /tmp/test-drv.nix << 'EOF'2563derivation {2564 name = "post-build-hook-test";2565 system = builtins.currentSystem;2566 builder = "/bin/sh";2567 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2568}2569EOF2570, in 0.01 seconds)2571builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2572builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 65 ms (attempt 1/5)2573builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 78 ms (attempt 2/5)2574builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 122 ms (attempt 3/5)2575builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 176 ms (attempt 4/5)2576builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org2577builder # this derivation will be built:2578builder # /nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2579builder # building '/nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv'...2580builder # [ 30.819560] systemd[1]: Started niks3 auto-upload daemon.2581builder # [ 30.939529] niks3-hook[810]: time=2026-10-08T21:40:06.462Z 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=0s2582builder # [ 30.949875] niks3-hook[810]: time=2026-10-08T21:40:06.473Z level=INFO msg="Upload queue status" pending=12583builder # [ 30.951692] niks3-hook[810]: time=2026-10-08T21:40:06.473Z level=INFO msg="Uploading batch" count=12584builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 0.82 seconds)2585builder: waiting for unit niks3-auto-upload.service2586builder # [ 31.024738] systemd[1]: Started Nix Daemon.2587builder: (finished: waiting for unit niks3-auto-upload.service, in 0.06 seconds)2588??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2589 File "/nix/store/n24r8aim2h0f446mabs0hp8mivzn47d8-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392590builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2591??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2592 File "/nix/store/n24r8aim2h0f446mabs0hp8mivzn47d8-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392593builder # [ 31.079501] nix-daemon[829]: accepted connection from pid 824, user root (trusted)2594builder # [ 31.089565] nix-daemon[829]: reaped child process 836, status = succeeded2595server # [ 31.086784] niks3-server[959]: 2026/10/08 21:40:06 INFO Received push request method=POST path=/api/pushes2596builder # [ 31.105393] niks3-hook[810]: time=2026-10-08T21:40:06.628Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2597builder # [ 31.106845] niks3-hook[810]: time=2026-10-08T21:40:06.630Z level=INFO msg="Uploading ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test (144B)"2598server # [ 31.144229] niks3-server[959]: 2026/10/08 21:40:06 INFO Received sign narinfos request method=POST path=/api/pushes/7/sign2599server # [ 31.148842] niks3-server[959]: 2026/10/08 21:40:06 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.ls2600builder # [ 31.167438] niks3-hook[810]: time=2026-10-08T21:40:06.690Z level=INFO msg="Uploading 1 narinfos"2601server # [ 31.158779] niks3-server[959]: 2026/10/08 21:40:06 INFO Signed narinfos id=7 count=12602server # [ 31.166061] niks3-server[959]: 2026/10/08 21:40:06 INFO Registered completed upload object_key=log/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2603server # [ 31.171650] niks3-server[959]: 2026/10/08 21:40:06 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2604server # [ 31.178892] niks3-server[959]: 2026/10/08 21:40:06 INFO Received complete push request method=POST path=/api/pushes/7/complete2605server # [ 31.182533] niks3-server[959]: 2026/10/08 21:40:06 INFO Completed push id=7 objects=4 duration=5.081093ms2606builder # [ 31.192089] niks3-hook[810]: time=2026-10-08T21:40:06.715Z level=INFO msg="Upload complete. (241ms)"2607server # [ 31.186737] niks3-server[959]: 2026/10/08 21:40:06 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.narinfo2608builder # [ 35.948735] niks3-hook[810]: time=2026-10-08T21:40:11.471Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2609builder # [ 35.953729] niks3-hook[810]: time=2026-10-08T21:40:11.476Z level=INFO msg="niks3-hook serve stopped"2610builder # [ 35.967488] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2611builder # [ 35.970481] systemd[1]: niks3-auto-upload.service: Consumed 131ms CPU time over 5.146s wall clock time, 20.7M memory peak, 68K written to disk, 6K incoming IP traffic, 9K outgoing IP traffic.2612builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.23 seconds)2613server: must succeed: 2614 export AWS_ACCESS_KEY_ID=rustfsadmin2615export AWS_SECRET_ACCESS_KEY=rustfsadmin2616 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-test26172618server # copying 1 paths...2619server # copying path '/nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2620server: (finished: must succeed: 2621 export AWS_ACCESS_KEY_ID=rustfsadmin2622export AWS_SECRET_ACCESS_KEY=rustfsadmin2623 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-test2624, in 0.14 seconds)2625server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2626server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test, in 0.05 seconds)2627(finished: run the VM test script, in 37.33 seconds)2628test script finished in 37.43s2629cleanup2630kill QemuMachine (pid 47)2631builder # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2632builder # [2026-10-08T21:40:12Z INFO virtiofsd] Client disconnected, shutting down2633builder # [2026-10-08T21:40:12Z INFO virtiofsd] Client disconnected, shutting down2634builder # [2026-10-08T21:40:12Z INFO virtiofsd] Client disconnected, shutting down2635kill QemuMachine (pid 48)2636server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2637server # [2026-10-08T21:40:12Z INFO virtiofsd] Client disconnected, shutting down2638server # [2026-10-08T21:40:12Z INFO virtiofsd] Client disconnected, shutting down2639server # [2026-10-08T21:40:12Z INFO virtiofsd] Client disconnected, shutting down2640(finished: cleanup, in 0.50 seconds)2641additionally exposed symbols:2642 builder, server,2643 vlan1,2644 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh2645Hello store path: /nix/store/5z2yp3ysx8476c8g5w25b0smlgkjvaq3-hello-2.12.32646Test output path: /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2647CA derivation output path: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2648Realisation info in chroot store: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test26492650Symlink wrapper store path: /nix/store/jniavmcf0bw6qv82dk88mr7vy02xwyxp-symlink-wrapper2651Symlink wrapper points to: /nix/store/7nwsqp4js976gl0808sngrnshdk4cs7q-base-package/bin/test-program2652OIDC test store path: /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2653Valid OIDC token obtained (length=677)2654OIDC push with valid token: SUCCESS2655Invalid OIDC token obtained (wrong org)2656OIDC push with wrong org: correctly rejected2657Wrong audience OIDC token obtained2658OIDC push with wrong audience: correctly rejected2659OIDC push with malformed token: correctly rejected2660All OIDC tests passed!2661All pin tests passed!2662Built store path: /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2663Post-build-hook pipeline test passed!