nixbot

1this derivation will be built:2  /nix/store/2dpc2d0092py9z851qnjw1fr6bncmn49-vm-test-run-tribuchet.drv3building '/nix/store/2dpc2d0092py9z851qnjw1fr6bncmn49-vm-test-run-tribuchet.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/2dpc2d0092py9z851qnjw1fr6bncmn49-vm-test-run-tribuchet.drv'5vm-test-run-tribuchet> Machine state will be reset. To keep it, pass --keep-machine-state6vm-test-run-tribuchet> start all VLans7vm-test-run-tribuchet> (finished: start all VLans, in 0.00 seconds)8vm-test-run-tribuchet> Test will time out and terminate in 3600 seconds9vm-test-run-tribuchet> run the VM test script10vm-test-run-tribuchet> additionally exposed symbols:11vm-test-run-tribuchet>     hub, worker,12vm-test-run-tribuchet>     vlan1,13vm-test-run-tribuchet>     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_ssh14vm-test-run-tribuchet> start all VMs15vm-test-run-tribuchet> hub: starting vm16vm-test-run-tribuchet> worker: starting vm17vm-test-run-tribuchet> hub # Disk image does not exist, creating the virtualisation disk image...18vm-test-run-tribuchet> hub: QEMU running (pid 15)19vm-test-run-tribuchet> hub # Formatting '/build/vm-state-hub/tmp.FyrB8YnlU7', fmt=raw size=429496729620vm-test-run-tribuchet> hub # mke2fs 1.47.3 (8-Jul-2025)21vm-test-run-tribuchet> hub # Discarding device blocks:       0/1048576               done22vm-test-run-tribuchet> hub # Creating filesystem with 1048576 4k blocks and 262144 inodes23vm-test-run-tribuchet> hub # Filesystem UUID: b0ef88d5-f4c7-4e2a-82d4-b974f0f80d7724vm-test-run-tribuchet> hub # Superblock backups stored on blocks:25vm-test-run-tribuchet> hub # 	32768, 98304, 163840, 229376, 294912, 819200, 88473626vm-test-run-tribuchet> hub # 27vm-test-run-tribuchet> hub # Allocating group tables:  0/32     done28vm-test-run-tribuchet> hub # Writing inode tables:  0/32     done29vm-test-run-tribuchet> hub # Creating journal (16384 blocks): done30vm-test-run-tribuchet> hub # Writing superblocks and filesystem accounting information:  0/32     done31vm-test-run-tribuchet> hub # 32vm-test-run-tribuchet> hub # Virtualisation disk image created.33vm-test-run-tribuchet> hub # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)34vm-test-run-tribuchet> hub # 35vm-test-run-tribuchet> hub # 36vm-test-run-tribuchet> hub # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+BEFD1920+BEF31920 CA0037vm-test-run-tribuchet> hub # Press Ctrl-B to configure iPXE (PCI 00:03.0)...38vm-test-run-tribuchet> hub # 39vm-test-run-tribuchet> hub # 40vm-test-run-tribuchet> hub # 41vm-test-run-tribuchet> hub # 42vm-test-run-tribuchet> hub # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM BEFD1920 BEF31920 CB0043vm-test-run-tribuchet> hub # Press Ctrl-B to configure iPXE (PCI 00:09.0)...44vm-test-run-tribuchet> hub # 45vm-test-run-tribuchet> hub # 46vm-test-run-tribuchet> hub # Booting from ROM...47vm-test-run-tribuchet> hub # Probing EDD (edd=off to disable)... ok48vm-test-run-tribuchet> hub # [    0.000000] Linux version 6.18.35 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Tue Jun  9 10:28:53 UTC 202649vm-test-run-tribuchet> hub # [    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/w499hw6ndbm250mz0zpc4ay6jddg1ysr-nixos-system-hub-test/init regInfo=/nix/store/867f6w43qgssycky4knwn7i49cdmfhxq-closure-info/registration console=ttyS0,115200n8 console=tty050vm-test-run-tribuchet> hub # [    0.000000] BIOS-provided physical RAM map:51vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable52vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved53vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved54vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffdafff] usable55vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x00000000bffdb000-0x00000000bfffffff] reserved56vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved57vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved58vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable59vm-test-run-tribuchet> hub # [    0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved60vm-test-run-tribuchet> hub # [    0.000000] NX (Execute Disable) protection: active61vm-test-run-tribuchet> hub # [    0.000000] APIC: Static calls initialized62vm-test-run-tribuchet> hub # [    0.000000] SMBIOS 2.8 present.63vm-test-run-tribuchet> hub # [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201464vm-test-run-tribuchet> hub # [    0.000000] DMI: Memory slots populated: 1/165vm-test-run-tribuchet> hub # [    0.000000] Hypervisor detected: KVM66vm-test-run-tribuchet> hub # [    0.000000] last_pfn = 0xbffdb max_arch_pfn = 0x1000000000067vm-test-run-tribuchet> hub # [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0068vm-test-run-tribuchet> hub # [    0.000001] kvm-clock: using sched offset of 513819805 cycles69vm-test-run-tribuchet> hub # [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns70vm-test-run-tribuchet> hub # [    0.000005] tsc: Detected 2400.010 MHz processor71vm-test-run-tribuchet> hub # [    0.000811] last_pfn = 0x140000 max_arch_pfn = 0x1000000000072vm-test-run-tribuchet> hub # [    0.000848] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs73vm-test-run-tribuchet> hub # [    0.000851] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT74vm-test-run-tribuchet> hub # [    0.000903] last_pfn = 0xbffdb max_arch_pfn = 0x1000000000075vm-test-run-tribuchet> hub # [    0.002772] found SMP MP-table at [mem 0x000f5470-0x000f547f]76vm-test-run-tribuchet> hub # [    0.002783] Using GB pages for direct mapping77vm-test-run-tribuchet> hub # [    0.002886] RAMDISK: [mem 0xbe4ef000-0xbffcffff]78vm-test-run-tribuchet> hub # [    0.002895] ACPI: Early table checksum verification disabled79vm-test-run-tribuchet> hub # [    0.002898] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )80vm-test-run-tribuchet> hub # [    0.002902] ACPI: RSDT 0x00000000BFFE23CC 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)81vm-test-run-tribuchet> hub # [    0.002906] ACPI: FACP 0x00000000BFFE2280 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)82vm-test-run-tribuchet> hub # [    0.002914] ACPI: DSDT 0x00000000BFFE0040 002240 (v01 BOCHS  BXPC     00000001 BXPC 00000001)83vm-test-run-tribuchet> hub # [    0.002916] ACPI: FACS 0x00000000BFFE0000 00004084vm-test-run-tribuchet> hub # [    0.002918] ACPI: APIC 0x00000000BFFE22F4 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)85vm-test-run-tribuchet> hub # [    0.002919] ACPI: HPET 0x00000000BFFE236C 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)86vm-test-run-tribuchet> hub # [    0.002921] ACPI: WAET 0x00000000BFFE23A4 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)87vm-test-run-tribuchet> hub # [    0.002922] ACPI: Reserving FACP table memory at [mem 0xbffe2280-0xbffe22f3]88vm-test-run-tribuchet> hub # [    0.002923] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe227f]89vm-test-run-tribuchet> hub # [    0.002924] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f]90vm-test-run-tribuchet> hub # [    0.002924] ACPI: Reserving APIC table memory at [mem 0xbffe22f4-0xbffe236b]91vm-test-run-tribuchet> hub # [    0.002925] ACPI: Reserving HPET table memory at [mem 0xbffe236c-0xbffe23a3]92vm-test-run-tribuchet> hub # [    0.002925] ACPI: Reserving WAET table memory at [mem 0xbffe23a4-0xbffe23cb]93vm-test-run-tribuchet> hub # [    0.003414] No NUMA configuration found94vm-test-run-tribuchet> hub # [    0.003416] Faking a node at [mem 0x0000000000000000-0x000000013fffffff]95vm-test-run-tribuchet> hub # [    0.003419] NODE_DATA(0) allocated [mem 0x13fffa780-0x13ffffcff]96vm-test-run-tribuchet> hub # [    0.006607] Zone ranges:97vm-test-run-tribuchet> hub # [    0.006608]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]98vm-test-run-tribuchet> hub # [    0.006609]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]99vm-test-run-tribuchet> hub # [    0.006611]   Normal   [mem 0x0000000100000000-0x000000013fffffff]100vm-test-run-tribuchet> hub # [    0.006612]   Device   empty101vm-test-run-tribuchet> hub # [    0.006612] Movable zone start for each node102vm-test-run-tribuchet> hub # [    0.006613] Early memory node ranges103vm-test-run-tribuchet> hub # [    0.006614]   node   0: [mem 0x0000000000001000-0x000000000009efff]104vm-test-run-tribuchet> hub # [    0.006614]   node   0: [mem 0x0000000000100000-0x00000000bffdafff]105vm-test-run-tribuchet> hub # [    0.006615]   node   0: [mem 0x0000000100000000-0x000000013fffffff]106vm-test-run-tribuchet> hub # [    0.006616] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff]107vm-test-run-tribuchet> hub # [    0.006638] On node 0, zone DMA: 1 pages in unavailable ranges108vm-test-run-tribuchet> hub # [    0.006939] On node 0, zone DMA: 97 pages in unavailable ranges109vm-test-run-tribuchet> hub # [    0.088953] On node 0, zone Normal: 37 pages in unavailable ranges110vm-test-run-tribuchet> hub # [    0.089979] ACPI: PM-Timer IO Port: 0x608111vm-test-run-tribuchet> hub # [    0.089997] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])112vm-test-run-tribuchet> hub # [    0.090034] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23113vm-test-run-tribuchet> hub # [    0.090037] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)114vm-test-run-tribuchet> hub # [    0.090039] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)115vm-test-run-tribuchet> hub # [    0.090040] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)116vm-test-run-tribuchet> hub # [    0.090041] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)117vm-test-run-tribuchet> hub # [    0.090042] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)118vm-test-run-tribuchet> hub # [    0.090044] ACPI: Using ACPI (MADT) for SMP configuration information119vm-test-run-tribuchet> hub # [    0.090046] ACPI: HPET id: 0x8086a201 base: 0xfed00000120vm-test-run-tribuchet> hub # [    0.090052] TSC deadline timer available121vm-test-run-tribuchet> hub # [    0.090057] CPU topo: Max. logical packages:   1122vm-test-run-tribuchet> hub # [    0.090058] CPU topo: Max. logical dies:       1123vm-test-run-tribuchet> hub # [    0.090058] CPU topo: Max. dies per package:   1124vm-test-run-tribuchet> hub # [    0.090062] CPU topo: Max. threads per core:   1125vm-test-run-tribuchet> hub # [    0.090063] CPU topo: Num. cores per package:     1126vm-test-run-tribuchet> hub # [    0.090063] CPU topo: Num. threads per package:   1127vm-test-run-tribuchet> hub # [    0.090063] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs128vm-test-run-tribuchet> hub # [    0.090090] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()129vm-test-run-tribuchet> hub # [    0.090121] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]130vm-test-run-tribuchet> hub # [    0.090123] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]131vm-test-run-tribuchet> hub # [    0.090124] PM: hibernation: Registered nosave memory: [mem 0xbffdb000-0xffffffff]132vm-test-run-tribuchet> hub # [    0.090125] [mem 0xc0000000-0xfeffbfff] available for PCI devices133vm-test-run-tribuchet> hub # [    0.090127] Booting paravirtualized kernel on KVM134vm-test-run-tribuchet> hub # [    0.090131] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns135vm-test-run-tribuchet> hub # [    0.094599] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1136vm-test-run-tribuchet> hub # [    0.097081] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152137vm-test-run-tribuchet> hub # [    0.097136] kvm-guest: PV spinlocks disabled, single CPU138vm-test-run-tribuchet> hub # [    0.097138] 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/w499hw6ndbm250mz0zpc4ay6jddg1ysr-nixos-system-hub-test/init regInfo=/nix/store/867f6w43qgssycky4knwn7i49cdmfhxq-closure-info/registration console=ttyS0,115200n8 console=tty0139vm-test-run-tribuchet> hub # [    0.097238] Unknown kernel command line parameters "regInfo=/nix/store/867f6w43qgssycky4knwn7i49cdmfhxq-closure-info/registration", will be passed to user space.140vm-test-run-tribuchet> hub # [    0.097250] random: crng init done141vm-test-run-tribuchet> hub # [    0.097251] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes142vm-test-run-tribuchet> hub # [    0.102095] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)143vm-test-run-tribuchet> hub # [    0.104980] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)144vm-test-run-tribuchet> hub # [    0.105018] software IO TLB: area num 1.145vm-test-run-tribuchet> hub # [    0.190497] Fallback order for Node 0: 0146vm-test-run-tribuchet> hub # [    0.190505] Built 1 zonelists, mobility grouping on.  Total pages: 1048441147vm-test-run-tribuchet> hub # [    0.190507] Policy zone: Normal148vm-test-run-tribuchet> hub # [    0.193346] mem auto-init: stack:all(zero), heap alloc:on, heap free:off149vm-test-run-tribuchet> hub # [    0.200712] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1150vm-test-run-tribuchet> hub # [    0.211081] allocated 8388608 bytes of page_ext151vm-test-run-tribuchet> hub # [    0.221149] ftrace: allocating 48584 entries in 192 pages152vm-test-run-tribuchet> hub # [    0.221152] ftrace: allocated 192 pages with 2 groups153vm-test-run-tribuchet> hub # [    0.222088] Dynamic Preempt: lazy154vm-test-run-tribuchet> hub # [    0.222269] rcu: Preemptible hierarchical RCU implementation.155vm-test-run-tribuchet> hub # [    0.222270] rcu: 	RCU event tracing is enabled.156vm-test-run-tribuchet> hub # [    0.222270] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.157vm-test-run-tribuchet> hub # [    0.222272] 	Trampoline variant of Tasks RCU enabled.158vm-test-run-tribuchet> hub # [    0.222272] 	Rude variant of Tasks RCU enabled.159vm-test-run-tribuchet> hub # [    0.222273] 	Tracing variant of Tasks RCU enabled.160vm-test-run-tribuchet> hub # [    0.222274] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.161vm-test-run-tribuchet> hub # [    0.222274] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1162vm-test-run-tribuchet> hub # [    0.222297] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.163vm-test-run-tribuchet> hub # [    0.222299] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.164vm-test-run-tribuchet> hub # [    0.222300] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.165vm-test-run-tribuchet> hub # [    0.227317] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16166vm-test-run-tribuchet> hub # [    0.227645] rcu: srcu_init: Setting srcu_struct sizes based on contention.167vm-test-run-tribuchet> hub # [    0.227764] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)168vm-test-run-tribuchet> hub # [    0.235044] Console: colour VGA+ 80x25169vm-test-run-tribuchet> hub # [    0.235048] printk: legacy console [tty0] enabled170vm-test-run-tribuchet> hub # [    0.277301] printk: legacy console [ttyS0] enabled171vm-test-run-tribuchet> hub # [    0.473140] ACPI: Core revision 20250807172vm-test-run-tribuchet> hub # [    0.474692] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns173vm-test-run-tribuchet> hub # [    0.477533] APIC: Switch to symmetric I/O mode setup174vm-test-run-tribuchet> hub # [    0.479293] x2apic enabled175vm-test-run-tribuchet> hub # [    0.480521] APIC: Switched APIC routing to: physical x2apic176vm-test-run-tribuchet> hub # [    0.483344] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1177vm-test-run-tribuchet> hub # [    0.485159] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns178vm-test-run-tribuchet> hub # [    0.488229] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)179vm-test-run-tribuchet> hub # [    0.490351] x86/cpu: User Mode Instruction Prevention (UMIP) activated180vm-test-run-tribuchet> hub # [    0.491401] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127181vm-test-run-tribuchet> hub # [    0.492228] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0182vm-test-run-tribuchet> hub # [    0.493232] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto183vm-test-run-tribuchet> hub # [    0.494228] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl184vm-test-run-tribuchet> hub # [    0.495228] Transient Scheduler Attacks: Mitigation: Clear CPU buffers185vm-test-run-tribuchet> hub # [    0.497227] Spectre V2 : Mitigation: Enhanced / Automatic IBRS186vm-test-run-tribuchet> hub # [    0.499227] Speculative Return Stack Overflow: Mitigation: Safe RET187vm-test-run-tribuchet> hub # [    0.500227] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization188vm-test-run-tribuchet> hub # [    0.501232] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier189vm-test-run-tribuchet> hub # [    0.503229] active return thunk: srso_alias_return_thunk190vm-test-run-tribuchet> hub # [    0.504257] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'191vm-test-run-tribuchet> hub # [    0.506227] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'192vm-test-run-tribuchet> hub # [    0.507227] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'193vm-test-run-tribuchet> hub # [    0.508227] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'194vm-test-run-tribuchet> hub # [    0.510227] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'195vm-test-run-tribuchet> hub # [    0.512227] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'196vm-test-run-tribuchet> hub # [    0.514227] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'197vm-test-run-tribuchet> hub # [    0.517225] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'198vm-test-run-tribuchet> hub # [    0.518227] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'199vm-test-run-tribuchet> hub # [    0.519228] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256200vm-test-run-tribuchet> hub # [    0.521227] x86/fpu: xstate_offset[5]:  832, xstate_sizes[5]:   64201vm-test-run-tribuchet> hub # [    0.523227] x86/fpu: xstate_offset[6]:  896, xstate_sizes[6]:  512202vm-test-run-tribuchet> hub # [    0.524227] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024203vm-test-run-tribuchet> hub # [    0.525228] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]:    8204vm-test-run-tribuchet> hub # [    0.526227] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]:   16205vm-test-run-tribuchet> hub # [    0.527227] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]:   24206vm-test-run-tribuchet> hub # [    0.529227] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.207vm-test-run-tribuchet> hub # [    0.565265] Freeing SMP alternatives memory: 44K208vm-test-run-tribuchet> hub # [    0.566230] pid_max: default: 32768 minimum: 301209vm-test-run-tribuchet> hub # [    0.568334] LSM: initializing lsm=capability,landlock,yama,bpf,ima210vm-test-run-tribuchet> hub # [    0.570276] landlock: Up and running.211vm-test-run-tribuchet> hub # [    0.571224] Yama: becoming mindful.212vm-test-run-tribuchet> hub # [    0.571439] LSM support for eBPF active213vm-test-run-tribuchet> hub # [    0.573227] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)214vm-test-run-tribuchet> hub # [    0.574317] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)215vm-test-run-tribuchet> hub # [    0.577176] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)216vm-test-run-tribuchet> hub # [    0.577832] Performance Events: Fam17h+ core perfctr, AMD PMU driver.217vm-test-run-tribuchet> hub # [    0.578232] ... version:                   2218vm-test-run-tribuchet> hub # [    0.579229] ... bit width:                 48219vm-test-run-tribuchet> hub # [    0.580288] ... generic counters:          6220vm-test-run-tribuchet> hub # [    0.581229] ... generic bitmap:            000000000000003f221vm-test-run-tribuchet> hub # [    0.582229] ... fixed-purpose counters:    0222vm-test-run-tribuchet> hub # [    0.583229] ... fixed-purpose bitmap:      0000000000000000223vm-test-run-tribuchet> hub # [    0.584229] ... value mask:                0000ffffffffffff224vm-test-run-tribuchet> hub # [    0.585229] ... max period:                00007fffffffffff225vm-test-run-tribuchet> hub # [    0.586229] ... global_ctrl mask:          000000000000003f226vm-test-run-tribuchet> hub # [    0.587381] signal: max sigframe size: 3376227vm-test-run-tribuchet> hub # [    0.588327] rcu: Hierarchical SRCU implementation.228vm-test-run-tribuchet> hub # [    0.589233] rcu: 	Max phase no-delay instances is 400.229vm-test-run-tribuchet> hub # [    0.594953] smp: Bringing up secondary CPUs ...230vm-test-run-tribuchet> hub # [    0.596243] smp: Brought up 1 node, 1 CPU231vm-test-run-tribuchet> hub # [    0.597232] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)232vm-test-run-tribuchet> hub # [    0.598451] Memory: 3962004K/4193764K available (17155K kernel code, 2721K rwdata, 13560K rodata, 3640K init, 3016K bss, 218116K reserved, 0K cma-reserved)233vm-test-run-tribuchet> hub # [    0.599628] devtmpfs: initialized234vm-test-run-tribuchet> hub # [    0.600539] x86/mm: Memory block size: 128MB235vm-test-run-tribuchet> hub # [    0.602749] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns236vm-test-run-tribuchet> hub # [    0.604260] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)237vm-test-run-tribuchet> hub # [    0.605263] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).238vm-test-run-tribuchet> hub # [    0.606328] pinctrl core: initialized pinctrl subsystem239vm-test-run-tribuchet> hub # [    0.607587] PM: RTC time: 21:21:40, date: 2026-06-23240vm-test-run-tribuchet> hub # [    0.611303] NET: Registered PF_NETLINK/PF_ROUTE protocol family241vm-test-run-tribuchet> hub # [    0.613101] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations242vm-test-run-tribuchet> hub # [    0.614285] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations243vm-test-run-tribuchet> hub # [    0.615856] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations244vm-test-run-tribuchet> hub # [    0.617243] audit: initializing netlink subsys (disabled)245vm-test-run-tribuchet> hub # [    0.618524] thermal_sys: Registered thermal governor 'fair_share'246vm-test-run-tribuchet> hub # [    0.618527] thermal_sys: Registered thermal governor 'bang_bang'247vm-test-run-tribuchet> hub # [    0.619233] audit: type=2000 audit(1782249700.398:1): state=initialized audit_enabled=0 res=1248vm-test-run-tribuchet> hub # [    0.621235] thermal_sys: Registered thermal governor 'step_wise'249vm-test-run-tribuchet> hub # [    0.621237] thermal_sys: Registered thermal governor 'user_space'250vm-test-run-tribuchet> hub # [    0.622229] thermal_sys: Registered thermal governor 'power_allocator'251vm-test-run-tribuchet> hub # [    0.623248] cpuidle: using governor menu252vm-test-run-tribuchet> hub # [    0.626437] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5253vm-test-run-tribuchet> hub # [    0.627530] PCI: Using configuration type 1 for base access254vm-test-run-tribuchet> hub # [    0.628229] PCI: Using configuration type 1 for extended access255vm-test-run-tribuchet> hub # [    0.629455] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.256vm-test-run-tribuchet> hub # [    0.636368] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages257vm-test-run-tribuchet> hub # [    0.637230] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page258vm-test-run-tribuchet> hub # [    0.642229] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages259vm-test-run-tribuchet> hub # [    0.643230] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page260vm-test-run-tribuchet> hub # [    0.653688] ACPI: Added _OSI(Module Device)261vm-test-run-tribuchet> hub # [    0.654230] ACPI: Added _OSI(Processor Device)262vm-test-run-tribuchet> hub # [    0.657881] ACPI: Added _OSI(Processor Aggregator Device)263vm-test-run-tribuchet> hub # [    0.660070] ACPI: 1 ACPI AML tables successfully acquired and loaded264vm-test-run-tribuchet> hub # [    0.666083] ACPI: Interpreter enabled265vm-test-run-tribuchet> hub # [    0.667153] ACPI: PM: (supports S0 S3 S4 S5)266vm-test-run-tribuchet> hub # [    0.670229] ACPI: Using IOAPIC for interrupt routing267vm-test-run-tribuchet> hub # [    0.671252] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug268vm-test-run-tribuchet> hub # [    0.674228] PCI: Using E820 reservations for host bridge windows269vm-test-run-tribuchet> hub # [    0.675383] ACPI: Enabled 2 GPEs in block 00 to 0F270vm-test-run-tribuchet> hub # [    0.682965] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])271vm-test-run-tribuchet> hub # [    0.684236] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]272vm-test-run-tribuchet> hub # [    0.685633] acpiphp: Slot [3] registered273vm-test-run-tribuchet> hub # [    0.686271] acpiphp: Slot [4] registered274vm-test-run-tribuchet> hub # [    0.687270] acpiphp: Slot [5] registered275vm-test-run-tribuchet> hub # [    0.688284] acpiphp: Slot [6] registered276vm-test-run-tribuchet> hub # [    0.689299] acpiphp: Slot [7] registered277vm-test-run-tribuchet> hub # [    0.690269] acpiphp: Slot [8] registered278vm-test-run-tribuchet> hub # [    0.691271] acpiphp: Slot [9] registered279vm-test-run-tribuchet> hub # [    0.692308] acpiphp: Slot [10] registered280vm-test-run-tribuchet> hub # [    0.693273] acpiphp: Slot [11] registered281vm-test-run-tribuchet> hub # [    0.694271] acpiphp: Slot [12] registered282vm-test-run-tribuchet> hub # [    0.695295] acpiphp: Slot [13] registered283vm-test-run-tribuchet> hub # [    0.696262] acpiphp: Slot [14] registered284vm-test-run-tribuchet> hub # [    0.697262] acpiphp: Slot [15] registered285vm-test-run-tribuchet> hub # [    0.698262] acpiphp: Slot [16] registered286vm-test-run-tribuchet> hub # [    0.699288] acpiphp: Slot [17] registered287vm-test-run-tribuchet> hub # [    0.700263] acpiphp: Slot [18] registered288vm-test-run-tribuchet> hub # [    0.701261] acpiphp: Slot [19] registered289vm-test-run-tribuchet> hub # [    0.702271] acpiphp: Slot [20] registered290vm-test-run-tribuchet> hub # [    0.703277] acpiphp: Slot [21] registered291vm-test-run-tribuchet> hub # [    0.704262] acpiphp: Slot [22] registered292vm-test-run-tribuchet> hub # [    0.705262] acpiphp: Slot [23] registered293vm-test-run-tribuchet> hub # [    0.706262] acpiphp: Slot [24] registered294vm-test-run-tribuchet> hub # [    0.707281] acpiphp: Slot [25] registered295vm-test-run-tribuchet> hub # [    0.708262] acpiphp: Slot [26] registered296vm-test-run-tribuchet> hub # [    0.709262] acpiphp: Slot [27] registered297vm-test-run-tribuchet> hub # [    0.710278] acpiphp: Slot [28] registered298vm-test-run-tribuchet> hub # [    0.711262] acpiphp: Slot [29] registered299vm-test-run-tribuchet> hub # [    0.712262] acpiphp: Slot [30] registered300vm-test-run-tribuchet> hub # [    0.713262] acpiphp: Slot [31] registered301vm-test-run-tribuchet> hub # [    0.714250] PCI host bridge to bus 0000:00302vm-test-run-tribuchet> hub # [    0.715236] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]303vm-test-run-tribuchet> hub # [    0.716230] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]304vm-test-run-tribuchet> hub # [    0.717230] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]305vm-test-run-tribuchet> hub # [    0.718230] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]306vm-test-run-tribuchet> hub # [    0.719230] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window]307vm-test-run-tribuchet> hub # [    0.720231] pci_bus 0000:00: root bus resource [bus 00-ff]308vm-test-run-tribuchet> hub # [    0.721623] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint309vm-test-run-tribuchet> hub # [    0.723136] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint310vm-test-run-tribuchet> hub # [    0.725165] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint311vm-test-run-tribuchet> hub # [    0.728296] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef]312vm-test-run-tribuchet> hub # [    0.729295] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk313vm-test-run-tribuchet> hub # [    0.730230] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk314vm-test-run-tribuchet> hub # [    0.731230] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk315vm-test-run-tribuchet> hub # [    0.732230] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk316vm-test-run-tribuchet> hub # [    0.733568] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint317vm-test-run-tribuchet> hub # [    0.735321] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f]318vm-test-run-tribuchet> hub # [    0.737469] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint319vm-test-run-tribuchet> hub # [    0.738988] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI320vm-test-run-tribuchet> hub # [    0.740244] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB321vm-test-run-tribuchet> hub # [    0.741675] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint322vm-test-run-tribuchet> hub # [    0.744338] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]323vm-test-run-tribuchet> hub # [    0.745257] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]324vm-test-run-tribuchet> hub # [    0.746285] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]325vm-test-run-tribuchet> hub # [    0.747473] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]326vm-test-run-tribuchet> hub # [    0.749430] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint327vm-test-run-tribuchet> hub # [    0.752270] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f]328vm-test-run-tribuchet> hub # [    0.753244] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]329vm-test-run-tribuchet> hub # [    0.754774] pci 0000:00:03.0: BAR 4 [mem 0xe0000000000-0xe0000003fff 64bit pref]330vm-test-run-tribuchet> hub # [    0.755244] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]331vm-test-run-tribuchet> hub # [    0.758567] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint332vm-test-run-tribuchet> hub # [    0.761267] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f]333vm-test-run-tribuchet> hub # [    0.762244] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]334vm-test-run-tribuchet> hub # [    0.763282] pci 0000:00:04.0: BAR 4 [mem 0xe0000004000-0xe0000007fff 64bit pref]335vm-test-run-tribuchet> hub # [    0.767355] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint336vm-test-run-tribuchet> hub # [    0.770274] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf]337vm-test-run-tribuchet> hub # [    0.771244] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]338vm-test-run-tribuchet> hub # [    0.772287] pci 0000:00:05.0: BAR 4 [mem 0xe0000008000-0xe000000bfff 64bit pref]339vm-test-run-tribuchet> hub # [    0.775659] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint340vm-test-run-tribuchet> hub # [    0.778266] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f]341vm-test-run-tribuchet> hub # [    0.779244] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]342vm-test-run-tribuchet> hub # [    0.780282] pci 0000:00:06.0: BAR 4 [mem 0xe000000c000-0xe000000ffff 64bit pref]343vm-test-run-tribuchet> hub # [    0.783255] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint344vm-test-run-tribuchet> hub # [    0.786328] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f]345vm-test-run-tribuchet> hub # [    0.787243] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]346vm-test-run-tribuchet> hub # [    0.788283] pci 0000:00:07.0: BAR 4 [mem 0xe0000010000-0xe0000013fff 64bit pref]347vm-test-run-tribuchet> hub # [    0.791430] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint348vm-test-run-tribuchet> hub # [    0.794508] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f]349vm-test-run-tribuchet> hub # [    0.795245] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]350vm-test-run-tribuchet> hub # [    0.796282] pci 0000:00:08.0: BAR 4 [mem 0xe0000014000-0xe0000017fff 64bit pref]351vm-test-run-tribuchet> hub # [    0.799230] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint352vm-test-run-tribuchet> hub # [    0.802461] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf]353vm-test-run-tribuchet> hub # [    0.803244] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]354vm-test-run-tribuchet> hub # [    0.804282] pci 0000:00:09.0: BAR 4 [mem 0xe0000018000-0xe000001bfff 64bit pref]355vm-test-run-tribuchet> hub # [    0.805244] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]356vm-test-run-tribuchet> hub # [    0.808230] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint357vm-test-run-tribuchet> hub # [    0.811283] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]358vm-test-run-tribuchet> hub # [    0.812282] pci 0000:00:0a.0: BAR 4 [mem 0xe000001c000-0xe000001ffff 64bit pref]359vm-test-run-tribuchet> hub # [    0.815243] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint360vm-test-run-tribuchet> hub # [    0.818281] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff]361vm-test-run-tribuchet> hub # [    0.819244] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]362vm-test-run-tribuchet> hub # [    0.820282] pci 0000:00:0b.0: BAR 4 [mem 0xe0000020000-0xe0000023fff 64bit pref]363vm-test-run-tribuchet> hub # [    0.823706] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint364vm-test-run-tribuchet> hub # [    0.826268] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df]365vm-test-run-tribuchet> hub # [    0.827244] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]366vm-test-run-tribuchet> hub # [    0.828282] pci 0000:00:0c.0: BAR 4 [mem 0xe0000024000-0xe0000027fff 64bit pref]367vm-test-run-tribuchet> hub # [    0.836544] ACPI: PCI: Interrupt link LNKA configured for IRQ 10368vm-test-run-tribuchet> hub # [    0.837446] ACPI: PCI: Interrupt link LNKB configured for IRQ 10369vm-test-run-tribuchet> hub # [    0.838435] ACPI: PCI: Interrupt link LNKC configured for IRQ 11370vm-test-run-tribuchet> hub # [    0.839425] ACPI: PCI: Interrupt link LNKD configured for IRQ 11371vm-test-run-tribuchet> hub # [    0.840342] ACPI: PCI: Interrupt link LNKS configured for IRQ 9372vm-test-run-tribuchet> hub # [    0.842440] iommu: Default domain type: Translated373vm-test-run-tribuchet> hub # [    0.843239] iommu: DMA domain TLB invalidation policy: lazy mode374vm-test-run-tribuchet> hub # [    0.844527] ACPI: bus type USB registered375vm-test-run-tribuchet> hub # [    0.845306] usbcore: registered new interface driver usbfs376vm-test-run-tribuchet> hub # [    0.846254] usbcore: registered new interface driver hub377vm-test-run-tribuchet> hub # [    0.847239] usbcore: registered new device driver usb378vm-test-run-tribuchet> hub # [    0.849215] NetLabel: Initializing379vm-test-run-tribuchet> hub # [    0.850081] NetLabel:  domain hash size = 128380vm-test-run-tribuchet> hub # [    0.851229] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO381vm-test-run-tribuchet> hub # [    0.852275] NetLabel:  unlabeled traffic allowed by default382vm-test-run-tribuchet> hub # [    0.853245] PCI: Using ACPI for IRQ routing383vm-test-run-tribuchet> hub # [    0.854920] pci 0000:00:02.0: vgaarb: setting as boot VGA device384vm-test-run-tribuchet> hub # [    0.855224] pci 0000:00:02.0: vgaarb: bridge control possible385vm-test-run-tribuchet> hub # [    0.855224] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none386vm-test-run-tribuchet> hub # [    0.855231] vgaarb: loaded387vm-test-run-tribuchet> hub # [    0.856409] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0388vm-test-run-tribuchet> hub # [    0.857229] hpet0: 3 comparators, 64-bit 100.000000 MHz counter389vm-test-run-tribuchet> hub # [    0.861313] clocksource: Switched to clocksource kvm-clock390vm-test-run-tribuchet> hub # [    0.865587] VFS: Disk quotas dquot_6.6.0391vm-test-run-tribuchet> hub # [    0.866860] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)392vm-test-run-tribuchet> hub # [    0.869139] pnp: PnP ACPI init393vm-test-run-tribuchet> hub # [    0.870833] pnp: PnP ACPI: found 6 devices394vm-test-run-tribuchet> hub # [    0.879218] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns395vm-test-run-tribuchet> hub # [    0.881823] clocksource: Switched to clocksource acpi_pm396vm-test-run-tribuchet> hub # [    0.883634] NET: Registered PF_INET protocol family397vm-test-run-tribuchet> hub # [    0.885928] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)398vm-test-run-tribuchet> hub # [    0.904807] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)399vm-test-run-tribuchet> hub # [    0.907362] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)400vm-test-run-tribuchet> hub # [    0.909724] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)401vm-test-run-tribuchet> hub # [    0.913427] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)402vm-test-run-tribuchet> hub # [    0.915740] TCP: Hash tables configured (established 32768 bind 32768)403vm-test-run-tribuchet> hub # [    0.917788] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)404vm-test-run-tribuchet> hub # [    0.920047] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)405vm-test-run-tribuchet> hub # [    0.922071] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)406vm-test-run-tribuchet> hub # [    0.924340] NET: Registered PF_UNIX/PF_LOCAL protocol family407vm-test-run-tribuchet> hub # [    0.926153] NET: Registered PF_XDP protocol family408vm-test-run-tribuchet> hub # [    0.927676] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]409vm-test-run-tribuchet> hub # [    0.929534] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]410vm-test-run-tribuchet> hub # [    0.931470] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]411vm-test-run-tribuchet> hub # [    0.933550] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window]412vm-test-run-tribuchet> hub # [    0.935632] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window]413vm-test-run-tribuchet> hub # [    0.937950] pci 0000:00:01.0: PIIX3: Enabling Passive Release414vm-test-run-tribuchet> hub # [    0.939717] pci 0000:00:00.0: Limiting direct PCI/PCI transfers415vm-test-run-tribuchet> hub # [    0.943134] ACPI: \_SB_.LNKD: Enabled at IRQ 11416vm-test-run-tribuchet> hub # [    0.946559] PCI: CLS 0 bytes, default 64417vm-test-run-tribuchet> hub # [    0.948022] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)418vm-test-run-tribuchet> hub # [    0.950072] Trying to unpack rootfs image as initramfs...419vm-test-run-tribuchet> hub # [    0.957036] software IO TLB: mapped [mem 0x00000000ba4ef000-0x00000000be4ef000] (64MB)420vm-test-run-tribuchet> hub # [    0.964624] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns421vm-test-run-tribuchet> hub # [    1.011066] Initialise system trusted keyrings422vm-test-run-tribuchet> hub # [    1.015677] workingset: timestamp_bits=40 max_order=20 bucket_order=0423vm-test-run-tribuchet> hub # [    1.039949] Key type asymmetric registered424vm-test-run-tribuchet> hub # [    1.041295] Asymmetric key parser 'x509' registered425vm-test-run-tribuchet> hub # [    1.046682] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)426vm-test-run-tribuchet> hub # [    1.051670] io scheduler mq-deadline registered427vm-test-run-tribuchet> hub # [    1.054627] io scheduler kyber registered428vm-test-run-tribuchet> hub # [    1.059811] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled429vm-test-run-tribuchet> hub # [    1.062175] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A430vm-test-run-tribuchet> hub # [    1.072697] Linux agpgart interface v0.103431vm-test-run-tribuchet> hub # [    1.074077] ACPI: bus type drm_connector registered432vm-test-run-tribuchet> hub # [    1.078144] usbcore: registered new interface driver usbserial_generic433vm-test-run-tribuchet> hub # [    1.080076] usbserial: USB Serial support registered for generic434vm-test-run-tribuchet> hub # [    1.086656] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.435vm-test-run-tribuchet> hub # [    1.086656] Please enable it if your BIOS has the CPPC option.436vm-test-run-tribuchet> hub # [    1.090653] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled437vm-test-run-tribuchet> hub # [    1.097826] drop_monitor: Initializing network drop monitor service438vm-test-run-tribuchet> hub # [    1.099909] NET: Registered PF_INET6 protocol family439vm-test-run-tribuchet> hub # [    1.106216] Segment Routing with IPv6440vm-test-run-tribuchet> hub # [    1.109641] In-situ OAM (IOAM) with IPv6441vm-test-run-tribuchet> hub # [    1.112956] IPI shorthand broadcast: enabled442vm-test-run-tribuchet> hub # [    1.122092] sched_clock: Marking stable (868029728, 253571206)->(1333056530, -211455596)443vm-test-run-tribuchet> hub # [    1.130941] registered taskstats version 1444vm-test-run-tribuchet> hub # [    1.132531] Loading compiled-in X.509 certificates445vm-test-run-tribuchet> hub # [    1.156624] Demotion targets for Node 0: null446vm-test-run-tribuchet> hub # [    1.158211] Key type .fscrypt registered447vm-test-run-tribuchet> hub # [    1.161610] Key type fscrypt-provisioning registered448vm-test-run-tribuchet> hub # [    1.163327] ima: No TPM chip found, activating TPM-bypass!449vm-test-run-tribuchet> hub # [    1.169969] ima: Allocated hash algorithm: sha1450vm-test-run-tribuchet> hub # [    1.171449] ima: No architecture policies found451vm-test-run-tribuchet> hub # [    1.175830] PM:   Magic number: 14:779:399452vm-test-run-tribuchet> hub # [    1.178198] RAS: Correctable Errors collector initialized.453vm-test-run-tribuchet> hub # [    1.189717] clk: Disabling unused clocks454vm-test-run-tribuchet> hub # [    1.192635] PM: genpd: Disabling unused power domains455vm-test-run-tribuchet> hub # [    1.333479] Freeing initrd memory: 27524K456vm-test-run-tribuchet> hub # [    1.337630] Freeing unused decrypted memory: 2028K457vm-test-run-tribuchet> hub # [    1.341300] Freeing unused kernel image (initmem) memory: 3640K458vm-test-run-tribuchet> hub # [    1.343279] Write protecting the kernel read-only data: 32768k459vm-test-run-tribuchet> hub # [    1.346199] Freeing unused kernel image (text/rodata gap) memory: 1276K460vm-test-run-tribuchet> hub # [    1.348755] Freeing unused kernel image (rodata/data gap) memory: 776K461vm-test-run-tribuchet> hub # [    1.401956] x86/mm: Checked W+X mappings: passed, no W+X pages found.462vm-test-run-tribuchet> hub # [    1.403892] Run /init as init process463vm-test-run-tribuchet> hub # [    1.417005] systemd[1]: Inserted module 'autofs4'464vm-test-run-tribuchet> hub # [    1.434314] fuse: init (API version 7.45)465vm-test-run-tribuchet> hub # [    1.441941] ACPI: \_SB_.LNKC: Enabled at IRQ 10466vm-test-run-tribuchet> hub # [    1.450925] ACPI: \_SB_.LNKA: Enabled at IRQ 10467vm-test-run-tribuchet> hub # [    1.455763] ACPI: \_SB_.LNKB: Enabled at IRQ 11468vm-test-run-tribuchet> hub # [    1.497731] systemd[1]: Successfully made /usr/ read-only.469vm-test-run-tribuchet> hub # [    1.838226] systemd[1]: systemd 260.1 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)470vm-test-run-tribuchet> hub # [    1.859968] systemd[1]: Detected virtualization kvm.471vm-test-run-tribuchet> hub # [    1.864004] systemd[1]: Detected architecture x86-64.472vm-test-run-tribuchet> hub # [    1.868053] systemd[1]: Running in initrd.473vm-test-run-tribuchet> hub # [    1.872402] systemd[1]: Initializing machine ID from random generator.474vm-test-run-tribuchet> hub # [    1.877754] systemd[1]: Hostname set to <hub>.475vm-test-run-tribuchet> hub # [    1.944182] systemd[1]: Queued start job for default target Initrd Default Target.476vm-test-run-tribuchet> hub # [    2.003359] systemd[1]: Created slice Slice /system/modprobe.477vm-test-run-tribuchet> hub # [    2.017829] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.478vm-test-run-tribuchet> hub # [    2.020311] systemd[1]: Expecting device /dev/disk/by-label/nixos...479vm-test-run-tribuchet> hub # [    2.022370] systemd[1]: Reached target Path Units.480vm-test-run-tribuchet> hub # [    2.023999] systemd[1]: Reached target Slice Units.481vm-test-run-tribuchet> hub # [    2.025678] systemd[1]: Reached target Swaps.482vm-test-run-tribuchet> hub # [    2.027154] systemd[1]: Reached target Timer Units.483vm-test-run-tribuchet> hub # [    2.028930] systemd[1]: Listening on D-Bus System Message Bus Socket.484vm-test-run-tribuchet> hub # [    2.031072] systemd[1]: Listening on Journal Socket (/dev/log).485vm-test-run-tribuchet> hub # [    2.033141] systemd[1]: Listening on Journal Sockets.486vm-test-run-tribuchet> hub # [    2.034964] systemd[1]: Listening on udev Control Socket.487vm-test-run-tribuchet> hub # [    2.036789] systemd[1]: Listening on udev Kernel Socket.488vm-test-run-tribuchet> hub # [    2.038535] systemd[1]: Reached target Socket Units.489vm-test-run-tribuchet> hub # [    2.042649] systemd[1]: Starting Create List of Static Device Nodes...490vm-test-run-tribuchet> hub # [    2.052862] systemd[1]: Starting Load Kernel Module 9pnet_virtio...491vm-test-run-tribuchet> hub # [    2.064846] systemd[1]: Starting Load Kernel Module configfs...492vm-test-run-tribuchet> hub # [    2.081863] systemd[1]: Starting Journal Service...493vm-test-run-tribuchet> hub # [    2.099716] systemd[1]: Starting Load Kernel Modules...494vm-test-run-tribuchet> hub # [    2.103777] netfs: FS-Cache loaded495vm-test-run-tribuchet> hub # [    2.107747] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki496vm-test-run-tribuchet> hub # [    2.120014] 9pnet: Installing 9P2000 support497vm-test-run-tribuchet> hub # [    2.132138] systemd[1]: Starting Coldplug All udev Devices...498vm-test-run-tribuchet> hub # [    2.141156] systemd-journald[125]: Collecting audit messages is disabled.499vm-test-run-tribuchet> hub # [    2.166120] systemd[1]: Finished Create List of Static Device Nodes.500vm-test-run-tribuchet> hub # [    2.174519] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.501vm-test-run-tribuchet> hub # [    2.178950] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.502vm-test-run-tribuchet> hub # [    2.193188] systemd[1]: Finished Load Kernel Module 9pnet_virtio.503vm-test-run-tribuchet> hub # [    2.195230] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev504vm-test-run-tribuchet> hub # [    2.208631] systemd[1]: modprobe@configfs.service: Deactivated successfully.505vm-test-run-tribuchet> hub # [    2.221360] systemd[1]: Finished Load Kernel Module configfs.506vm-test-run-tribuchet> hub # [    2.238501] systemd[1]: Finished Load Kernel Modules.507vm-test-run-tribuchet> hub # [    2.245482] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config508vm-test-run-tribuchet> hub # [    2.261708] systemd[1]: Starting Apply Kernel Variables...509vm-test-run-tribuchet> hub # [    2.283107] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...510vm-test-run-tribuchet> hub # [    2.313166] systemd[1]: Finished Apply Kernel Variables.511vm-test-run-tribuchet> hub # [    2.331240] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.512vm-test-run-tribuchet> hub # [    2.344140] systemd[1]: Starting Create Static Device Nodes in /dev...513vm-test-run-tribuchet> hub # [    2.381832] systemd[1]: Finished Create Static Device Nodes in /dev.514vm-test-run-tribuchet> hub # [    2.389028] systemd[1]: Reached target Preparation for Local File Systems.515vm-test-run-tribuchet> hub # [    2.139970] systemd-modules-load[127]: Inserted module 'dm_mod'516vm-test-run-tribuchet> hub # [    2.143877] systemd-modules-load[127]: Inserted module 'virtio_balloon'517vm-test-run-tribuchet> hub # [    2.399104] systemd[1]: Reached target Local File Systems.518vm-test-run-tribuchet> hub # [    2.147470] systemd-modules-load[127]: Inserted module 'virtio_gpu'519vm-test-run-tribuchet> hub # [    2.410288] systemd[1]: Starting Rule-based Manager for Device Events and Files...520vm-test-run-tribuchet> hub # [    2.418853] systemd[1]: Started Journal Service.521vm-test-run-tribuchet> hub # [    2.193110] systemd[1]: Starting Create System Files and Directories...522vm-test-run-tribuchet> hub # [    2.222928] systemd-udevd[151]: Using default interface naming scheme 'v260'.523vm-test-run-tribuchet> hub # [    2.234142] systemd[1]: Finished Create System Files and Directories.524vm-test-run-tribuchet> hub # [    2.268259] systemd[1]: Started Rule-based Manager for Device Events and Files.525vm-test-run-tribuchet> hub # [    2.354299] systemd[1]: Finished Coldplug All udev Devices.526vm-test-run-tribuchet> hub # [    2.357825] systemd[1]: Reached target System Initialization.527vm-test-run-tribuchet> hub # [    2.359467] systemd[1]: Reached target Basic System.528vm-test-run-tribuchet> hub # [    2.947325] virtio_blk virtio5: 1/0/0 default/read/poll queues529vm-test-run-tribuchet> hub # [    2.954146] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12530vm-test-run-tribuchet> hub # [    2.998400] uhci_hcd 0000:00:01.2: UHCI Host Controller531vm-test-run-tribuchet> hub # [    3.000140] virtio_blk virtio5: [vda] 8388608 512-byte logical blocks (4.29 GB/4.00 GiB)532vm-test-run-tribuchet> hub # [    3.005051] serio: i8042 KBD port at 0x60,0x64 irq 1533vm-test-run-tribuchet> hub # [    3.006328] serio: i8042 AUX port at 0x60,0x64 irq 12534vm-test-run-tribuchet> hub # [    3.018242] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1535vm-test-run-tribuchet> hub # [    3.037195] uhci_hcd 0000:00:01.2: detected 2 ports536vm-test-run-tribuchet> hub # [    3.048804] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100537vm-test-run-tribuchet> hub # [    3.058662] SCSI subsystem initialized538vm-test-run-tribuchet> hub # [    3.060425] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18539vm-test-run-tribuchet> hub # [    3.075093] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1540vm-test-run-tribuchet> hub # [    3.095681] usb usb1: Product: UHCI Host Controller541vm-test-run-tribuchet> hub # [    2.854221] (udev-worker)[177]: Network interface NamePolicy= disabled on kernel command line.542vm-test-run-tribuchet> hub # [    3.110138] usb usb1: Manufacturer: Linux 6.18.35 uhci_hcd543vm-test-run-tribuchet> hub # [    2.865113] systemd[1]: Starting Virtual Console Setup...544vm-test-run-tribuchet> hub # [    2.867338] (udev-worker)[165]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.545vm-test-run-tribuchet> hub # [    2.873592] (udev-worker)[165]: Network interface NamePolicy= disabled on kernel command line.546vm-test-run-tribuchet> hub # [    3.142921] usb usb1: SerialNumber: 0000:00:01.2547vm-test-run-tribuchet> hub # [    3.150251] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0548vm-test-run-tribuchet> hub # [    3.167740] hub 1-0:1.0: USB hub found549vm-test-run-tribuchet> hub # [    2.918571] systemd-vconsole-setup[188]: Configuration of first virtual console was skipped, ignoring remaining ones.550vm-test-run-tribuchet> hub # [    2.926387] systemd[1]: Finished Virtual Console Setup.551vm-test-run-tribuchet> hub # [    3.191455] hub 1-0:1.0: 2 ports detected552vm-test-run-tribuchet> hub # [    2.953654] systemd[1]: Found device /dev/disk/by-label/nixos.553vm-test-run-tribuchet> hub # [    2.955352] systemd[1]: Reached target Initrd Root Device.554vm-test-run-tribuchet> hub # [    2.962116] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...555vm-test-run-tribuchet> hub # [    3.001364] systemd-fsck[198]: nixos: clean, 12/262144 files, 37198/1048576 blocks556vm-test-run-tribuchet> hub # [    3.262706] scsi host0: ata_piix557vm-test-run-tribuchet> hub # [    3.012531] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.558vm-test-run-tribuchet> hub # [    3.019161] systemd[1]: Mounting /sysroot...559vm-test-run-tribuchet> hub # [    3.275659] scsi host1: ata_piix560vm-test-run-tribuchet> hub # [    3.280170] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0561vm-test-run-tribuchet> hub # [    3.285176] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0562vm-test-run-tribuchet> hub # [    3.425866] usb 1-1: new full-speed USB device number 2 using uhci_hcd563vm-test-run-tribuchet> hub # [    3.450359] ata2: found unknown device (class 0)564vm-test-run-tribuchet> hub # [    3.456023] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100565vm-test-run-tribuchet> hub # [    3.465086] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5566vm-test-run-tribuchet> hub # [    3.556613] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray567vm-test-run-tribuchet> hub # [    3.571142] cdrom: Uniform CD-ROM driver Revision: 3.20568vm-test-run-tribuchet> hub # [    3.599539] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00569vm-test-run-tribuchet> hub # [    3.601638] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10570vm-test-run-tribuchet> hub # [    3.604106] usb 1-1: Product: QEMU USB Tablet571vm-test-run-tribuchet> hub # [    3.605496] usb 1-1: Manufacturer: QEMU572vm-test-run-tribuchet> hub # [    3.606915] usb 1-1: SerialNumber: 28754-0000:00:01.2-1573vm-test-run-tribuchet> hub # [    3.644797] EXT4-fs (vda): mounted filesystem b0ef88d5-f4c7-4e2a-82d4-b974f0f80d77 r/w with ordered data mode. Quota mode: none.574vm-test-run-tribuchet> hub # [    3.399073] systemd[1]: Mounted /sysroot.575vm-test-run-tribuchet> hub # [    3.403127] systemd[1]: Reached target Initrd Root File System.576vm-test-run-tribuchet> hub # [    3.660937] hid: raw HID events driver (C) Jiri Kosina577vm-test-run-tribuchet> hub # [    3.409638] systemd[1]: Starting Mountpoints Configured in the Real Root...578vm-test-run-tribuchet> hub # [    3.428412] systemd-sysroot-fstab-check[218]: /sysroot should be mounted in the initrd, will request daemon-reload.579vm-test-run-tribuchet> hub # [    3.686869] usbcore: registered new interface driver usbhid580vm-test-run-tribuchet> hub # [    3.436139] systemd[1]: Reload requested from client PID 218 ('systemd-sysroot') (unit initrd-parse-etc.service)...581vm-test-run-tribuchet> hub # [    3.438915] systemd[1]: Reloading...582vm-test-run-tribuchet> hub # [    3.702955] usbhid: USB HID core driver583vm-test-run-tribuchet> hub # [    3.722516] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2584vm-test-run-tribuchet> hub # [    3.729693] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0585vm-test-run-tribuchet> hub # [    3.700327] systemd[1]: Reloading finished in 264 ms.586vm-test-run-tribuchet> hub # [    3.716615] systemd-sysroot-fstab-check[218]: Requesting initrd-fs.target/start/replace...587vm-test-run-tribuchet> hub # [    3.771324] systemd-sysroot-fstab-check[218]: Requesting swap.target/start/replace...588vm-test-run-tribuchet> hub # [    3.779769] systemd[1]: initrd-parse-etc.service: Deactivated successfully.589vm-test-run-tribuchet> hub # [    3.783377] systemd[1]: Finished Mountpoints Configured in the Real Root.590vm-test-run-tribuchet> hub # [    3.786264] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.591vm-test-run-tribuchet> hub # [    3.791774] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio592vm-test-run-tribuchet> hub # [    3.910282] systemd[1]: Mounting /sysroot/nix/.ro-store...593vm-test-run-tribuchet> hub # [    3.926711] systemd[1]: Mounting /sysroot/nix/.rw-store...594vm-test-run-tribuchet> hub # [    3.941401] systemd[1]: Mounting /sysroot/run...595vm-test-run-tribuchet> hub # [    3.961219] systemd[1]: Mounting /sysroot/tmp/shared...596vm-test-run-tribuchet> hub # [    3.971907] systemd[1]: Mounting /sysroot/tmp/xchg...597vm-test-run-tribuchet> hub # [    4.230307] 9p: Installing v9fs 9p2000 file system support598vm-test-run-tribuchet> hub # [    3.985703] systemd[1]: Mounted /sysroot/nix/.rw-store.599vm-test-run-tribuchet> hub # [    3.992328] systemd[1]: Mounted /sysroot/nix/.ro-store.600vm-test-run-tribuchet> hub # [    4.002830] systemd[1]: Mounted /sysroot/run.601vm-test-run-tribuchet> hub # [    4.009622] systemd[1]: Mounted /sysroot/tmp/shared.602vm-test-run-tribuchet> hub # [    4.014515] systemd[1]: Mounted /sysroot/tmp/xchg.603vm-test-run-tribuchet> hub # [    4.019688] systemd[1]: Starting rw-sysroot-nix-store.service...604vm-test-run-tribuchet> hub # [    4.030877] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.605vm-test-run-tribuchet> hub # [    4.034564] systemd[1]: Finished rw-sysroot-nix-store.service.606vm-test-run-tribuchet> hub # [    4.909303] systemd[1]: Mounting /sysroot/nix/store...607vm-test-run-tribuchet> hub # [    4.959635] systemd[1]: Mounted /sysroot/nix/store.608vm-test-run-tribuchet> hub # [    4.961838] systemd[1]: Reached target Initrd File Systems.609vm-test-run-tribuchet> hub # [    4.968360] systemd[1]: Starting Find NixOS closure...610vm-test-run-tribuchet> hub # [    4.974591] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...611vm-test-run-tribuchet> hub # [    5.001252] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.612vm-test-run-tribuchet> hub # [    5.004186] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.613vm-test-run-tribuchet> hub # [    5.018190] systemd[1]: Finished Find NixOS closure.614vm-test-run-tribuchet> hub # [    5.021115] systemd[1]: Reached target Initrd Default Target.615vm-test-run-tribuchet> hub # [    5.024475] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...616vm-test-run-tribuchet> hub # [    5.040705] systemd[1]: Stopped target Initrd Default Target.617vm-test-run-tribuchet> hub # [    5.042730] systemd[1]: Stopped target Basic System.618vm-test-run-tribuchet> hub # [    5.045213] systemd[1]: Stopped target Initrd Root Device.619vm-test-run-tribuchet> hub # [    5.046874] systemd[1]: Stopped target Path Units.620vm-test-run-tribuchet> hub # [    5.048285] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.621vm-test-run-tribuchet> hub # [    5.050840] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.622vm-test-run-tribuchet> hub # [    5.053754] systemd[1]: Stopped target Slice Units.623vm-test-run-tribuchet> hub # [    5.056279] systemd[1]: Stopped target Socket Units.624vm-test-run-tribuchet> hub # [    5.058102] systemd[1]: Stopped target System Initialization.625vm-test-run-tribuchet> hub # [    5.060240] systemd[1]: Stopped target Swaps.626vm-test-run-tribuchet> hub # [    5.062076] systemd[1]: Stopped target Timer Units.627vm-test-run-tribuchet> hub # [    5.063469] systemd[1]: dbus.socket: Deactivated successfully.628vm-test-run-tribuchet> hub # [    5.066215] systemd[1]: Closed D-Bus System Message Bus Socket.629vm-test-run-tribuchet> hub # [    5.068244] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.630vm-test-run-tribuchet> hub # [    5.070280] systemd[1]: Stopped Find NixOS closure.631vm-test-run-tribuchet> hub # [    5.073294] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio632vm-test-run-tribuchet> hub # [    5.079302] systemd[1]: Starting rw-sysroot-nix-store.service...633vm-test-run-tribuchet> hub # [    5.081250] systemd[1]: systemd-sysctl.service: Deactivated successfully.634vm-test-run-tribuchet> hub # [    5.084222] systemd[1]: Stopped Apply Kernel Variables.635vm-test-run-tribuchet> hub # [    5.089179] systemd[1]: systemd-modules-load.service: Deactivated successfully.636vm-test-run-tribuchet> hub # [    5.091949] systemd[1]: Stopped Load Kernel Modules.637vm-test-run-tribuchet> hub # [    5.098222] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.638vm-test-run-tribuchet> hub # [    5.101190] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.639vm-test-run-tribuchet> hub # [    5.106391] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.640vm-test-run-tribuchet> hub # [    5.108709] systemd[1]: Stopped Create System Files and Directories.641vm-test-run-tribuchet> hub # [    5.112883] systemd[1]: Stopped target Local File Systems.642vm-test-run-tribuchet> hub # [    5.114605] systemd[1]: Stopped target Preparation for Local File Systems.643vm-test-run-tribuchet> hub # [    5.116966] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.644vm-test-run-tribuchet> hub # [    5.119373] systemd[1]: Stopped Coldplug All udev Devices.645vm-test-run-tribuchet> hub # [    5.124197] systemd[1]: Stopping Rule-based Manager for Device Events and Files...646vm-test-run-tribuchet> hub # [    5.126287] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.647vm-test-run-tribuchet> hub # [    5.129289] systemd[1]: Stopped Virtual Console Setup.648vm-test-run-tribuchet> hub # [    5.139101] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.649vm-test-run-tribuchet> hub # [    5.144166] systemd[1]: Finished rw-sysroot-nix-store.service.650vm-test-run-tribuchet> hub # [    5.153397] systemd[1]: systemd-udevd.service: Deactivated successfully.651vm-test-run-tribuchet> hub # [    5.158151] systemd[1]: Stopped Rule-based Manager for Device Events and Files.652vm-test-run-tribuchet> hub # [    5.162066] systemd[1]: initrd-cleanup.service: Deactivated successfully.653vm-test-run-tribuchet> hub # [    5.166127] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.654vm-test-run-tribuchet> hub # [    5.173228] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.655vm-test-run-tribuchet> hub # [    5.175385] systemd[1]: Closed udev Control Socket.656vm-test-run-tribuchet> hub # [    5.181108] systemd[1]: Starting Cleanup udev Database...657vm-test-run-tribuchet> hub # [    5.182650] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.658vm-test-run-tribuchet> hub # [    5.185222] systemd[1]: Stopped Create Static Device Nodes in /dev.659vm-test-run-tribuchet> hub # [    5.192756] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.660vm-test-run-tribuchet> hub # [    5.194852] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.661vm-test-run-tribuchet> hub # [    5.198642] systemd[1]: kmod-static-nodes.service: Deactivated successfully.662vm-test-run-tribuchet> hub # [    5.200437] systemd[1]: Stopped Create List of Static Device Nodes.663vm-test-run-tribuchet> hub # [    5.216559] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.664vm-test-run-tribuchet> hub # [    5.221107] systemd[1]: Finished Cleanup udev Database.665vm-test-run-tribuchet> hub # [    5.224643] systemd[1]: Reached target Switch Root.666vm-test-run-tribuchet> hub # [    5.228227] systemd[1]: Starting NixOS Activation...667vm-test-run-tribuchet> hub # [    5.416964] initrd-nixos-activation-start[511]: booting system configuration /nix/store/w499hw6ndbm250mz0zpc4ay6jddg1ysr-nixos-system-hub-test668vm-test-run-tribuchet> hub # [    5.492740] initrd-nixos-activation-start[511]: running activation script...669vm-test-run-tribuchet> hub # [    5.978903] initrd-nixos-activation-start[534]: setting up /etc...670vm-test-run-tribuchet> hub # [    6.290629] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.671vm-test-run-tribuchet> hub # [    6.295251] systemd[1]: Finished NixOS Activation.672vm-test-run-tribuchet> hub # [    6.301409] systemd[1]: Starting Switch Root...673vm-test-run-tribuchet> hub # [    6.317388] systemd[1]: Switching root.674vm-test-run-tribuchet> hub # [    6.721660] systemd-journald[125]: Received SIGTERM from PID 1 (systemd).675vm-test-run-tribuchet> worker: QEMU running (pid 14)676vm-test-run-tribuchet> worker # Disk image does not exist, creating the virtualisation disk image...677vm-test-run-tribuchet> worker # Formatting '/build/vm-state-worker/tmp.ilOVPW4bJY', fmt=raw size=4294967296678vm-test-run-tribuchet> worker # mke2fs 1.47.3 (8-Jul-2025)679vm-test-run-tribuchet> worker # Discarding device blocks:       0/1048576               done680vm-test-run-tribuchet> worker # Creating filesystem with 1048576 4k blocks and 262144 inodes681vm-test-run-tribuchet> worker # Filesystem UUID: 963ef745-fcc1-4f9e-90d6-1b0503f497fd682vm-test-run-tribuchet> worker # Superblock backups stored on blocks:683vm-test-run-tribuchet> worker # 	32768, 98304, 163840, 229376, 294912, 819200, 884736684vm-test-run-tribuchet> worker # 685vm-test-run-tribuchet> worker # Allocating group tables:  0/32     done686vm-test-run-tribuchet> worker # Writing inode tables:  0/32     done687vm-test-run-tribuchet> worker # Creating journal (16384 blocks): done688vm-test-run-tribuchet> worker # Writing superblocks and filesystem accounting information:  0/32     done689vm-test-run-tribuchet> worker # 690vm-test-run-tribuchet> worker # Virtualisation disk image created.691vm-test-run-tribuchet> worker # Creating Nix store image...692vm-test-run-tribuchet> worker # Created Nix store image.693vm-test-run-tribuchet> (finished: start all VMs, in 7.80 seconds)694vm-test-run-tribuchet> hub: waiting for unit multi-user.target695vm-test-run-tribuchet> hub: waiting for the VM to finish booting696vm-test-run-tribuchet> hub # [    6.887547] NET: Registered PF_VSOCK protocol family697vm-test-run-tribuchet> worker # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)698vm-test-run-tribuchet> worker # 699vm-test-run-tribuchet> worker # 700vm-test-run-tribuchet> worker # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+BEFD1900+BEF31900 CA00701vm-test-run-tribuchet> worker # Press Ctrl-B to configure iPXE (PCI 00:03.0)...702vm-test-run-tribuchet> worker # 703vm-test-run-tribuchet> worker # 704vm-test-run-tribuchet> worker # 705vm-test-run-tribuchet> worker # 706vm-test-run-tribuchet> worker # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM BEFD1900 BEF31900 CB00707vm-test-run-tribuchet> worker # Press Ctrl-B to configure iPXE (PCI 00:09.0)...708vm-test-run-tribuchet> worker # 709vm-test-run-tribuchet> worker # 710vm-test-run-tribuchet> worker # Booting from ROM...711vm-test-run-tribuchet> hub # [    7.280548] systemd[1]: systemd 260.1 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)712vm-test-run-tribuchet> hub # [    7.296994] systemd[1]: Detected virtualization kvm.713vm-test-run-tribuchet> hub # [    7.300281] systemd[1]: Detected architecture x86-64.714vm-test-run-tribuchet> hub # [    7.303763] systemd[1]: Detected first boot.715vm-test-run-tribuchet> hub # [    7.313971] systemd[1]: Initializing machine ID from random generator.716vm-test-run-tribuchet> hub # [    7.579156] systemd[1]: bpf-restrict-fs: LSM BPF program attached717vm-test-run-tribuchet> worker # Probing EDD (edd=off to disable)... ok[    0.000000] Linux version 6.18.35 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Tue Jun  9 10:28:53 UTC 2026718vm-test-run-tribuchet> worker # [    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/1mrbkbyl80wk15h6h6hrfv4zv4qflb88-nixos-system-worker-test/init regInfo=/nix/store/4fnf03dz4gbil887m26rgb85i9dg8x20-closure-info/registration console=ttyS0,115200n8 console=tty0719vm-test-run-tribuchet> worker # [    0.000000] BIOS-provided physical RAM map:720vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable721vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved722vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved723vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd7fff] usable724vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x00000000bffd8000-0x00000000bfffffff] reserved725vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved726vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved727vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable728vm-test-run-tribuchet> worker # [    0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved729vm-test-run-tribuchet> worker # [    0.000000] NX (Execute Disable) protection: active730vm-test-run-tribuchet> worker # [    0.000000] APIC: Static calls initialized731vm-test-run-tribuchet> worker # [    0.000000] SMBIOS 2.8 present.732vm-test-run-tribuchet> worker # [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014733vm-test-run-tribuchet> worker # [    0.000000] DMI: Memory slots populated: 1/1734vm-test-run-tribuchet> worker # [    0.000000] Hypervisor detected: KVM735vm-test-run-tribuchet> worker # [    0.000000] last_pfn = 0xbffd8 max_arch_pfn = 0x10000000000736vm-test-run-tribuchet> worker # [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00737vm-test-run-tribuchet> worker # [    0.000000] kvm-clock: using sched offset of 501775500 cycles738vm-test-run-tribuchet> worker # [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns739vm-test-run-tribuchet> worker # [    0.000005] tsc: Detected 2400.010 MHz processor740vm-test-run-tribuchet> worker # [    0.000811] last_pfn = 0x140000 max_arch_pfn = 0x10000000000741vm-test-run-tribuchet> worker # [    0.000848] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs742vm-test-run-tribuchet> worker # [    0.000850] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT743vm-test-run-tribuchet> worker # [    0.000902] last_pfn = 0xbffd8 max_arch_pfn = 0x10000000000744vm-test-run-tribuchet> worker # [    0.002765] found SMP MP-table at [mem 0x000f5470-0x000f547f]745vm-test-run-tribuchet> worker # [    0.002776] Using GB pages for direct mapping746vm-test-run-tribuchet> worker # [    0.002874] RAMDISK: [mem 0xbe4df000-0xbffcffff]747vm-test-run-tribuchet> worker # [    0.002881] ACPI: Early table checksum verification disabled748vm-test-run-tribuchet> worker # [    0.002884] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )749vm-test-run-tribuchet> worker # [    0.002887] ACPI: RSDT 0x00000000BFFE23CC 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)750vm-test-run-tribuchet> worker # [    0.002892] ACPI: FACP 0x00000000BFFE2280 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)751vm-test-run-tribuchet> worker # [    0.002898] ACPI: DSDT 0x00000000BFFE0040 002240 (v01 BOCHS  BXPC     00000001 BXPC 00000001)752vm-test-run-tribuchet> worker # [    0.002900] ACPI: FACS 0x00000000BFFE0000 000040753vm-test-run-tribuchet> worker # [    0.002902] ACPI: APIC 0x00000000BFFE22F4 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)754vm-test-run-tribuchet> worker # [    0.002904] ACPI: HPET 0x00000000BFFE236C 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)755vm-test-run-tribuchet> worker # [    0.002905] ACPI: WAET 0x00000000BFFE23A4 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)756vm-test-run-tribuchet> worker # [    0.002907] ACPI: Reserving FACP table memory at [mem 0xbffe2280-0xbffe22f3]757vm-test-run-tribuchet> worker # [    0.002908] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe227f]758vm-test-run-tribuchet> worker # [    0.002908] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f]759vm-test-run-tribuchet> worker # [    0.002909] ACPI: Reserving APIC table memory at [mem 0xbffe22f4-0xbffe236b]760vm-test-run-tribuchet> worker # [    0.002909] ACPI: Reserving HPET table memory at [mem 0xbffe236c-0xbffe23a3]761vm-test-run-tribuchet> worker # [    0.002910] ACPI: Reserving WAET table memory at [mem 0xbffe23a4-0xbffe23cb]762vm-test-run-tribuchet> worker # [    0.003391] No NUMA configuration found763vm-test-run-tribuchet> worker # [    0.003392] Faking a node at [mem 0x0000000000000000-0x000000013fffffff]764vm-test-run-tribuchet> worker # [    0.003396] NODE_DATA(0) allocated [mem 0x13fffa780-0x13ffffcff]765vm-test-run-tribuchet> worker # [    0.005841] Zone ranges:766vm-test-run-tribuchet> worker # [    0.005842]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]767vm-test-run-tribuchet> worker # [    0.005843]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]768vm-test-run-tribuchet> worker # [    0.005845]   Normal   [mem 0x0000000100000000-0x000000013fffffff]769vm-test-run-tribuchet> worker # [    0.005846]   Device   empty770vm-test-run-tribuchet> worker # [    0.005846] Movable zone start for each node771vm-test-run-tribuchet> worker # [    0.005847] Early memory node ranges772vm-test-run-tribuchet> worker # [    0.005847]   node   0: [mem 0x0000000000001000-0x000000000009efff]773vm-test-run-tribuchet> worker # [    0.005848]   node   0: [mem 0x0000000000100000-0x00000000bffd7fff]774vm-test-run-tribuchet> worker # [    0.005849]   node   0: [mem 0x0000000100000000-0x000000013fffffff]775vm-test-run-tribuchet> worker # [    0.005850] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff]776vm-test-run-tribuchet> worker # [    0.005872] On node 0, zone DMA: 1 pages in unavailable ranges777vm-test-run-tribuchet> worker # [    0.006154] On node 0, zone DMA: 97 pages in unavailable ranges778vm-test-run-tribuchet> worker # [    0.082931] On node 0, zone Normal: 40 pages in unavailable ranges779vm-test-run-tribuchet> worker # [    0.083952] ACPI: PM-Timer IO Port: 0x608780vm-test-run-tribuchet> worker # [    0.083969] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])781vm-test-run-tribuchet> worker # [    0.084007] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23782vm-test-run-tribuchet> worker # [    0.084010] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)783vm-test-run-tribuchet> worker # [    0.084011] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)784vm-test-run-tribuchet> worker # [    0.084013] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)785vm-test-run-tribuchet> worker # [    0.084014] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)786vm-test-run-tribuchet> worker # [    0.084014] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)787vm-test-run-tribuchet> worker # [    0.084017] ACPI: Using ACPI (MADT) for SMP configuration information788vm-test-run-tribuchet> worker # [    0.084018] ACPI: HPET id: 0x8086a201 base: 0xfed00000789vm-test-run-tribuchet> worker # [    0.084024] TSC deadline timer available790vm-test-run-tribuchet> worker # [    0.084028] CPU topo: Max. logical packages:   1791vm-test-run-tribuchet> hub # [    7.732092] systemd[1]: Applying preset policy.792vm-test-run-tribuchet> worker # [    0.084029] CPU topo: Max. logical dies:       1793vm-test-run-tribuchet> worker # [    0.084029] CPU topo: Max. dies per package:   1794vm-test-run-tribuchet> worker # [    0.084033] CPU topo: Max. threads per core:   1795vm-test-run-tribuchet> worker # [    0.084033] CPU topo: Num. cores per package:     1796vm-test-run-tribuchet> worker # [    0.084034] CPU topo: Num. threads per package:   1797vm-test-run-tribuchet> worker # [    0.084034] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs798vm-test-run-tribuchet> worker # [    0.084059] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()799vm-test-run-tribuchet> worker # [    0.084089] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]800vm-test-run-tribuchet> worker # [    0.084090] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]801vm-test-run-tribuchet> worker # [    0.084091] PM: hibernation: Registered nosave memory: [mem 0xbffd8000-0xffffffff]802vm-test-run-tribuchet> worker # [    0.084093] [mem 0xc0000000-0xfeffbfff] available for PCI devices803vm-test-run-tribuchet> worker # [    0.084094] Booting paravirtualized kernel on KVM804vm-test-run-tribuchet> worker # [    0.084098] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns805vm-test-run-tribuchet> worker # [    0.088561] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1806vm-test-run-tribuchet> worker # [    0.091210] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152807vm-test-run-tribuchet> worker # [    0.091264] kvm-guest: PV spinlocks disabled, single CPU808vm-test-run-tribuchet> worker # [    0.091266] 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/1mrbkbyl80wk15h6h6hrfv4zv4qflb88-nixos-system-worker-test/init regInfo=/nix/store/4fnf03dz4gbil887m26rgb85i9dg8x20-closure-info/registration console=ttyS0,115200n8 console=tty0809vm-test-run-tribuchet> worker # [    0.091359] Unknown kernel command line parameters "regInfo=/nix/store/4fnf03dz4gbil887m26rgb85i9dg8x20-closure-info/registration", will be passed to user space.810vm-test-run-tribuchet> worker # [    0.091372] random: crng init done811vm-test-run-tribuchet> worker # [    0.091373] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes812vm-test-run-tribuchet> worker # [    0.095957] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)813vm-test-run-tribuchet> worker # [    0.098235] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)814vm-test-run-tribuchet> worker # [    0.098269] software IO TLB: area num 1.815vm-test-run-tribuchet> worker # [    0.177916] Fallback order for Node 0: 0816vm-test-run-tribuchet> worker # [    0.177923] Built 1 zonelists, mobility grouping on.  Total pages: 1048438817vm-test-run-tribuchet> worker # [    0.177925] Policy zone: Normal818vm-test-run-tribuchet> worker # [    0.180583] mem auto-init: stack:all(zero), heap alloc:on, heap free:off819vm-test-run-tribuchet> worker # [    0.187287] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1820vm-test-run-tribuchet> worker # [    0.196935] allocated 8388608 bytes of page_ext821vm-test-run-tribuchet> worker # [    0.206867] ftrace: allocating 48584 entries in 192 pages822vm-test-run-tribuchet> worker # [    0.206868] ftrace: allocated 192 pages with 2 groups823vm-test-run-tribuchet> worker # [    0.207722] Dynamic Preempt: lazy824vm-test-run-tribuchet> worker # [    0.207853] rcu: Preemptible hierarchical RCU implementation.825vm-test-run-tribuchet> worker # [    0.207854] rcu: 	RCU event tracing is enabled.826vm-test-run-tribuchet> worker # [    0.207854] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.827vm-test-run-tribuchet> worker # [    0.207856] 	Trampoline variant of Tasks RCU enabled.828vm-test-run-tribuchet> worker # [    0.207856] 	Rude variant of Tasks RCU enabled.829vm-test-run-tribuchet> worker # [    0.207857] 	Tracing variant of Tasks RCU enabled.830vm-test-run-tribuchet> worker # [    0.207857] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.831vm-test-run-tribuchet> worker # [    0.207858] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1832vm-test-run-tribuchet> worker # [    0.207916] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.833vm-test-run-tribuchet> worker # [    0.207918] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.834vm-test-run-tribuchet> worker # [    0.207918] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.835vm-test-run-tribuchet> worker # [    0.212282] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16836vm-test-run-tribuchet> worker # [    0.212577] rcu: srcu_init: Setting srcu_struct sizes based on contention.837vm-test-run-tribuchet> worker # [    0.212686] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)838vm-test-run-tribuchet> worker # [    0.219956] Console: colour VGA+ 80x25839vm-test-run-tribuchet> worker # [    0.219960] printk: legacy console [tty0] enabled840vm-test-run-tribuchet> worker # [    0.262605] printk: legacy console [ttyS0] enabled841vm-test-run-tribuchet> worker # [    0.458341] ACPI: Core revision 20250807842vm-test-run-tribuchet> worker # [    0.459918] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns843vm-test-run-tribuchet> worker # [    0.462676] APIC: Switch to symmetric I/O mode setup844vm-test-run-tribuchet> worker # [    0.464436] x2apic enabled845vm-test-run-tribuchet> worker # [    0.465661] APIC: Switched APIC routing to: physical x2apic846vm-test-run-tribuchet> worker # [    0.468527] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1847vm-test-run-tribuchet> worker # [    0.470348] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns848vm-test-run-tribuchet> worker # [    0.473418] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)849vm-test-run-tribuchet> worker # [    0.475534] x86/cpu: User Mode Instruction Prevention (UMIP) activated850vm-test-run-tribuchet> worker # [    0.476616] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127851vm-test-run-tribuchet> worker # [    0.477418] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0852vm-test-run-tribuchet> worker # [    0.478422] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto853vm-test-run-tribuchet> worker # [    0.479418] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl854vm-test-run-tribuchet> worker # [    0.480418] Transient Scheduler Attacks: Mitigation: Clear CPU buffers855vm-test-run-tribuchet> worker # [    0.482417] Spectre V2 : Mitigation: Enhanced / Automatic IBRS856vm-test-run-tribuchet> worker # [    0.484417] Speculative Return Stack Overflow: Mitigation: Safe RET857vm-test-run-tribuchet> worker # [    0.486417] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization858vm-test-run-tribuchet> worker # [    0.488423] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier859vm-test-run-tribuchet> worker # [    0.490418] active return thunk: srso_alias_return_thunk860vm-test-run-tribuchet> worker # [    0.492446] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'861vm-test-run-tribuchet> worker # [    0.494417] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'862vm-test-run-tribuchet> worker # [    0.496417] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'863vm-test-run-tribuchet> worker # [    0.498417] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'864vm-test-run-tribuchet> worker # [    0.500417] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'865vm-test-run-tribuchet> worker # [    0.501417] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'866vm-test-run-tribuchet> worker # [    0.502417] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'867vm-test-run-tribuchet> worker # [    0.503417] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'868vm-test-run-tribuchet> worker # [    0.505417] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'869vm-test-run-tribuchet> worker # [    0.506419] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256870vm-test-run-tribuchet> worker # [    0.507417] x86/fpu: xstate_offset[5]:  832, xstate_sizes[5]:   64871vm-test-run-tribuchet> worker # [    0.508417] x86/fpu: xstate_offset[6]:  896, xstate_sizes[6]:  512872vm-test-run-tribuchet> worker # [    0.509417] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024873vm-test-run-tribuchet> worker # [    0.510417] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]:    8874vm-test-run-tribuchet> worker # [    0.512417] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]:   16875vm-test-run-tribuchet> worker # [    0.514417] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]:   24876vm-test-run-tribuchet> worker # [    0.515417] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.877vm-test-run-tribuchet> worker # [    0.549354] Freeing SMP alternatives memory: 44K878vm-test-run-tribuchet> worker # [    0.550419] pid_max: default: 32768 minimum: 301879vm-test-run-tribuchet> worker # [    0.551526] LSM: initializing lsm=capability,landlock,yama,bpf,ima880vm-test-run-tribuchet> worker # [    0.553514] landlock: Up and running.881vm-test-run-tribuchet> worker # [    0.555417] Yama: becoming mindful.882vm-test-run-tribuchet> worker # [    0.556629] LSM support for eBPF active883vm-test-run-tribuchet> worker # [    0.557585] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)884vm-test-run-tribuchet> worker # [    0.559511] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)885vm-test-run-tribuchet> worker # [    0.562220] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)886vm-test-run-tribuchet> worker # [    0.562988] Performance Events: Fam17h+ core perfctr, AMD PMU driver.887vm-test-run-tribuchet> worker # [    0.563422] ... version:                   2888vm-test-run-tribuchet> worker # [    0.564419] ... bit width:                 48889vm-test-run-tribuchet> worker # [    0.565476] ... generic counters:          6890vm-test-run-tribuchet> worker # [    0.566419] ... generic bitmap:            000000000000003f891vm-test-run-tribuchet> worker # [    0.567419] ... fixed-purpose counters:    0892vm-test-run-tribuchet> worker # [    0.568419] ... fixed-purpose bitmap:      0000000000000000893vm-test-run-tribuchet> worker # [    0.569419] ... value mask:                0000ffffffffffff894vm-test-run-tribuchet> worker # [    0.570419] ... max period:                00007fffffffffff895vm-test-run-tribuchet> worker # [    0.571419] ... global_ctrl mask:          000000000000003f896vm-test-run-tribuchet> worker # [    0.572534] signal: max sigframe size: 3376897vm-test-run-tribuchet> worker # [    0.573521] rcu: Hierarchical SRCU implementation.898vm-test-run-tribuchet> worker # [    0.574424] rcu: 	Max phase no-delay instances is 400.899vm-test-run-tribuchet> worker # [    0.579941] smp: Bringing up secondary CPUs ...900vm-test-run-tribuchet> worker # [    0.580435] smp: Brought up 1 node, 1 CPU901vm-test-run-tribuchet> worker # [    0.581421] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)902vm-test-run-tribuchet> worker # [    0.582634] Memory: 3961936K/4193752K available (17155K kernel code, 2721K rwdata, 13560K rodata, 3640K init, 3016K bss, 218180K reserved, 0K cma-reserved)903vm-test-run-tribuchet> worker # [    0.583787] devtmpfs: initialized904vm-test-run-tribuchet> worker # [    0.584659] x86/mm: Memory block size: 128MB905vm-test-run-tribuchet> worker # [    0.586903] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns906vm-test-run-tribuchet> worker # [    0.587453] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)907vm-test-run-tribuchet> worker # [    0.588451] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).908vm-test-run-tribuchet> worker # [    0.589525] pinctrl core: initialized pinctrl subsystem909vm-test-run-tribuchet> worker # [    0.590762] PM: RTC time: 21:21:48, date: 2026-06-23910vm-test-run-tribuchet> worker # [    0.594480] NET: Registered PF_NETLINK/PF_ROUTE protocol family911vm-test-run-tribuchet> worker # [    0.596252] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations912vm-test-run-tribuchet> worker # [    0.597457] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations913vm-test-run-tribuchet> worker # [    0.599009] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations914vm-test-run-tribuchet> worker # [    0.600431] audit: initializing netlink subsys (disabled)915vm-test-run-tribuchet> worker # [    0.601737] thermal_sys: Registered thermal governor 'fair_share'916vm-test-run-tribuchet> worker # [    0.601739] thermal_sys: Registered thermal governor 'bang_bang'917vm-test-run-tribuchet> worker # [    0.602423] audit: type=2000 audit(1782249707.946:1): state=initialized audit_enabled=0 res=1918vm-test-run-tribuchet> worker # [    0.604424] thermal_sys: Registered thermal governor 'step_wise'919vm-test-run-tribuchet> worker # [    0.604426] thermal_sys: Registered thermal governor 'user_space'920vm-test-run-tribuchet> worker # [    0.605419] thermal_sys: Registered thermal governor 'power_allocator'921vm-test-run-tribuchet> worker # [    0.606453] cpuidle: using governor menu922vm-test-run-tribuchet> worker # [    0.609618] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5923vm-test-run-tribuchet> worker # [    0.610730] PCI: Using configuration type 1 for base access924vm-test-run-tribuchet> worker # [    0.611419] PCI: Using configuration type 1 for extended access925vm-test-run-tribuchet> worker # [    0.612637] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.926vm-test-run-tribuchet> worker # [    0.619561] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages927vm-test-run-tribuchet> worker # [    0.620420] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page928vm-test-run-tribuchet> worker # [    0.625418] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages929vm-test-run-tribuchet> worker # [    0.626420] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page930vm-test-run-tribuchet> worker # [    0.636768] ACPI: Added _OSI(Module Device)931vm-test-run-tribuchet> worker # [    0.637420] ACPI: Added _OSI(Processor Device)932vm-test-run-tribuchet> worker # [    0.641118] ACPI: Added _OSI(Processor Aggregator Device)933vm-test-run-tribuchet> worker # [    0.643291] ACPI: 1 ACPI AML tables successfully acquired and loaded934vm-test-run-tribuchet> worker # [    0.649254] ACPI: Interpreter enabled935vm-test-run-tribuchet> worker # [    0.650332] ACPI: PM: (supports S0 S3 S4 S5)936vm-test-run-tribuchet> worker # [    0.653419] ACPI: Using IOAPIC for interrupt routing937vm-test-run-tribuchet> worker # [    0.654441] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug938vm-test-run-tribuchet> worker # [    0.657418] PCI: Using E820 reservations for host bridge windows939vm-test-run-tribuchet> worker # [    0.658571] ACPI: Enabled 2 GPEs in block 00 to 0F940vm-test-run-tribuchet> worker # [    0.665919] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])941vm-test-run-tribuchet> worker # [    0.666430] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]942vm-test-run-tribuchet> worker # [    0.667841] acpiphp: Slot [3] registered943vm-test-run-tribuchet> worker # [    0.668461] acpiphp: Slot [4] registered944vm-test-run-tribuchet> worker # [    0.669460] acpiphp: Slot [5] registered945vm-test-run-tribuchet> worker # [    0.670475] acpiphp: Slot [6] registered946vm-test-run-tribuchet> worker # [    0.671459] acpiphp: Slot [7] registered947vm-test-run-tribuchet> worker # [    0.672460] acpiphp: Slot [8] registered948vm-test-run-tribuchet> worker # [    0.673458] acpiphp: Slot [9] registered949vm-test-run-tribuchet> worker # [    0.674473] acpiphp: Slot [10] registered950vm-test-run-tribuchet> worker # [    0.675474] acpiphp: Slot [11] registered951vm-test-run-tribuchet> worker # [    0.676460] acpiphp: Slot [12] registered952vm-test-run-tribuchet> worker # [    0.677487] acpiphp: Slot [13] registered953vm-test-run-tribuchet> worker # [    0.678452] acpiphp: Slot [14] registered954vm-test-run-tribuchet> worker # [    0.679451] acpiphp: Slot [15] registered955vm-test-run-tribuchet> worker # [    0.680452] acpiphp: Slot [16] registered956vm-test-run-tribuchet> worker # [    0.681468] acpiphp: Slot [17] registered957vm-test-run-tribuchet> worker # [    0.682452] acpiphp: Slot [18] registered958vm-test-run-tribuchet> worker # [    0.683452] acpiphp: Slot [19] registered959vm-test-run-tribuchet> worker # [    0.684452] acpiphp: Slot [20] registered960vm-test-run-tribuchet> worker # [    0.685465] acpiphp: Slot [21] registered961vm-test-run-tribuchet> worker # [    0.686452] acpiphp: Slot [22] registered962vm-test-run-tribuchet> worker # [    0.687452] acpiphp: Slot [23] registered963vm-test-run-tribuchet> worker # [    0.688451] acpiphp: Slot [24] registered964vm-test-run-tribuchet> worker # [    0.689466] acpiphp: Slot [25] registered965vm-test-run-tribuchet> worker # [    0.690452] acpiphp: Slot [26] registered966vm-test-run-tribuchet> worker # [    0.691454] acpiphp: Slot [27] registered967vm-test-run-tribuchet> worker # [    0.692466] acpiphp: Slot [28] registered968vm-test-run-tribuchet> worker # [    0.693475] acpiphp: Slot [29] registered969vm-test-run-tribuchet> worker # [    0.694480] acpiphp: Slot [30] registered970vm-test-run-tribuchet> worker # [    0.695452] acpiphp: Slot [31] registered971vm-test-run-tribuchet> worker # [    0.696441] PCI host bridge to bus 0000:00972vm-test-run-tribuchet> worker # [    0.697426] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]973vm-test-run-tribuchet> worker # [    0.698420] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]974vm-test-run-tribuchet> worker # [    0.699420] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]975vm-test-run-tribuchet> worker # [    0.700420] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]976vm-test-run-tribuchet> worker # [    0.701420] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window]977vm-test-run-tribuchet> worker # [    0.702421] pci_bus 0000:00: root bus resource [bus 00-ff]978vm-test-run-tribuchet> worker # [    0.703794] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint979vm-test-run-tribuchet> worker # [    0.705332] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint980vm-test-run-tribuchet> worker # [    0.707354] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint981vm-test-run-tribuchet> worker # [    0.710241] pci 0000:00:01.1: BAR 4 [io  0xc220-0xc22f]982vm-test-run-tribuchet> worker # [    0.711485] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk983vm-test-run-tribuchet> worker # [    0.712420] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk984vm-test-run-tribuchet> worker # [    0.713420] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk985vm-test-run-tribuchet> worker # [    0.714419] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk986vm-test-run-tribuchet> worker # [    0.715822] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint987vm-test-run-tribuchet> worker # [    0.717485] pci 0000:00:01.2: BAR 4 [io  0xc140-0xc15f]988vm-test-run-tribuchet> worker # [    0.719612] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint989vm-test-run-tribuchet> worker # [    0.721088] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI990vm-test-run-tribuchet> worker # [    0.722434] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB991vm-test-run-tribuchet> worker # [    0.723861] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint992vm-test-run-tribuchet> worker # [    0.726510] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]993vm-test-run-tribuchet> worker # [    0.727447] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]994vm-test-run-tribuchet> worker # [    0.728472] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]995vm-test-run-tribuchet> worker # [    0.729657] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]996vm-test-run-tribuchet> worker # [    0.731528] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint997vm-test-run-tribuchet> worker # [    0.734455] pci 0000:00:03.0: BAR 0 [io  0xc160-0xc17f]998vm-test-run-tribuchet> worker # [    0.735434] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]999vm-test-run-tribuchet> worker # [    0.736473] pci 0000:00:03.0: BAR 4 [mem 0xe0000000000-0xe0000003fff 64bit pref]1000vm-test-run-tribuchet> worker # [    0.737435] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]1001vm-test-run-tribuchet> worker # [    0.740615] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint1002vm-test-run-tribuchet> worker # [    0.743729] pci 0000:00:04.0: BAR 0 [io  0xc180-0xc19f]1003vm-test-run-tribuchet> worker # [    0.744435] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]1004vm-test-run-tribuchet> worker # [    0.745497] pci 0000:00:04.0: BAR 4 [mem 0xe0000004000-0xe0000007fff 64bit pref]1005vm-test-run-tribuchet> worker # [    0.749523] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint1006vm-test-run-tribuchet> worker # [    0.752454] pci 0000:00:05.0: BAR 0 [io  0xc1a0-0xc1bf]1007vm-test-run-tribuchet> worker # [    0.753434] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]1008vm-test-run-tribuchet> worker # [    0.754472] pci 0000:00:05.0: BAR 4 [mem 0xe0000008000-0xe000000bfff 64bit pref]1009vm-test-run-tribuchet> worker # [    0.757792] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint1010vm-test-run-tribuchet> worker # [    0.760458] pci 0000:00:06.0: BAR 0 [io  0xc1c0-0xc1df]1011vm-test-run-tribuchet> worker # [    0.761434] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]1012vm-test-run-tribuchet> worker # [    0.762473] pci 0000:00:06.0: BAR 4 [mem 0xe000000c000-0xe000000ffff 64bit pref]1013vm-test-run-tribuchet> hub # [    8.273551] systemd[1]: Populated /etc with preset unit settings.1014vm-test-run-tribuchet> worker # [    0.765419] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint1015vm-test-run-tribuchet> worker # [    0.768459] pci 0000:00:07.0: BAR 0 [io  0xc000-0xc07f]1016vm-test-run-tribuchet> worker # [    0.769434] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]1017vm-test-run-tribuchet> worker # [    0.770543] pci 0000:00:07.0: BAR 4 [mem 0xe0000010000-0xe0000013fff 64bit pref]1018vm-test-run-tribuchet> worker # [    0.773733] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint1019vm-test-run-tribuchet> worker # [    0.776722] pci 0000:00:08.0: BAR 0 [io  0xc080-0xc0ff]1020vm-test-run-tribuchet> worker # [    0.777434] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]1021vm-test-run-tribuchet> worker # [    0.778476] pci 0000:00:08.0: BAR 4 [mem 0xe0000014000-0xe0000017fff 64bit pref]1022vm-test-run-tribuchet> worker # [    0.781446] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint1023vm-test-run-tribuchet> worker # [    0.784656] pci 0000:00:09.0: BAR 0 [io  0xc1e0-0xc1ff]1024vm-test-run-tribuchet> worker # [    0.785435] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]1025vm-test-run-tribuchet> worker # [    0.786474] pci 0000:00:09.0: BAR 4 [mem 0xe0000018000-0xe000001bfff 64bit pref]1026vm-test-run-tribuchet> worker # [    0.787434] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]1027vm-test-run-tribuchet> worker # [    0.790433] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint1028vm-test-run-tribuchet> worker # [    0.793468] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]1029vm-test-run-tribuchet> worker # [    0.794472] pci 0000:00:0a.0: BAR 4 [mem 0xe000001c000-0xe000001ffff 64bit pref]1030vm-test-run-tribuchet> worker # [    0.797420] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint1031vm-test-run-tribuchet> worker # [    0.800468] pci 0000:00:0b.0: BAR 0 [io  0xc100-0xc13f]1032vm-test-run-tribuchet> worker # [    0.801434] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]1033vm-test-run-tribuchet> worker # [    0.802472] pci 0000:00:0b.0: BAR 4 [mem 0xe0000020000-0xe0000023fff 64bit pref]1034vm-test-run-tribuchet> worker # [    0.805931] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint1035vm-test-run-tribuchet> worker # [    0.808435] pci 0000:00:0c.0: BAR 0 [io  0xc200-0xc21f]1036vm-test-run-tribuchet> worker # [    0.809434] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]1037vm-test-run-tribuchet> worker # [    0.810473] pci 0000:00:0c.0: BAR 4 [mem 0xe0000024000-0xe0000027fff 64bit pref]1038vm-test-run-tribuchet> worker # [    0.818650] ACPI: PCI: Interrupt link LNKA configured for IRQ 101039vm-test-run-tribuchet> worker # [    0.819821] ACPI: PCI: Interrupt link LNKB configured for IRQ 101040vm-test-run-tribuchet> worker # [    0.820622] ACPI: PCI: Interrupt link LNKC configured for IRQ 111041vm-test-run-tribuchet> worker # [    0.821612] ACPI: PCI: Interrupt link LNKD configured for IRQ 111042vm-test-run-tribuchet> worker # [    0.822529] ACPI: PCI: Interrupt link LNKS configured for IRQ 91043vm-test-run-tribuchet> worker # [    0.824646] iommu: Default domain type: Translated1044vm-test-run-tribuchet> worker # [    0.825429] iommu: DMA domain TLB invalidation policy: lazy mode1045vm-test-run-tribuchet> worker # [    0.826713] ACPI: bus type USB registered1046vm-test-run-tribuchet> worker # [    0.827485] usbcore: registered new interface driver usbfs1047vm-test-run-tribuchet> worker # [    0.828442] usbcore: registered new interface driver hub1048vm-test-run-tribuchet> worker # [    0.829429] usbcore: registered new device driver usb1049vm-test-run-tribuchet> worker # [    0.831275] NetLabel: Initializing1050vm-test-run-tribuchet> worker # [    0.832271] NetLabel:  domain hash size = 1281051vm-test-run-tribuchet> worker # [    0.833419] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO1052vm-test-run-tribuchet> worker # [    0.834527] NetLabel:  unlabeled traffic allowed by default1053vm-test-run-tribuchet> worker # [    0.835434] PCI: Using ACPI for IRQ routing1054vm-test-run-tribuchet> worker # [    0.837075] pci 0000:00:02.0: vgaarb: setting as boot VGA device1055vm-test-run-tribuchet> worker # [    0.837414] pci 0000:00:02.0: vgaarb: bridge control possible1056vm-test-run-tribuchet> worker # [    0.837414] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none1057vm-test-run-tribuchet> worker # [    0.837421] vgaarb: loaded1058vm-test-run-tribuchet> worker # [    0.838590] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 01059vm-test-run-tribuchet> worker # [    0.839419] hpet0: 3 comparators, 64-bit 100.000000 MHz counter1060vm-test-run-tribuchet> worker # [    0.844504] clocksource: Switched to clocksource kvm-clock1061vm-test-run-tribuchet> worker # [    0.846761] VFS: Disk quotas dquot_6.6.01062vm-test-run-tribuchet> worker # [    0.849927] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)1063vm-test-run-tribuchet> worker # [    0.852220] pnp: PnP ACPI init1064vm-test-run-tribuchet> worker # [    0.853908] pnp: PnP ACPI: found 6 devices1065vm-test-run-tribuchet> worker # [    0.862591] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns1066vm-test-run-tribuchet> worker # [    0.865209] clocksource: Switched to clocksource acpi_pm1067vm-test-run-tribuchet> worker # [    0.867001] NET: Registered PF_INET protocol family1068vm-test-run-tribuchet> worker # [    0.869232] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)1069vm-test-run-tribuchet> worker # [    0.887406] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)1070vm-test-run-tribuchet> worker # [    0.890139] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)1071vm-test-run-tribuchet> worker # [    0.892479] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)1072vm-test-run-tribuchet> worker # [    0.896142] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)1073vm-test-run-tribuchet> worker # [    0.898418] TCP: Hash tables configured (established 32768 bind 32768)1074vm-test-run-tribuchet> worker # [    0.900430] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)1075vm-test-run-tribuchet> worker # [    0.902732] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)1076vm-test-run-tribuchet> worker # [    0.904820] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)1077vm-test-run-tribuchet> worker # [    0.907122] NET: Registered PF_UNIX/PF_LOCAL protocol family1078vm-test-run-tribuchet> worker # [    0.908903] NET: Registered PF_XDP protocol family1079vm-test-run-tribuchet> worker # [    0.910430] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]1080vm-test-run-tribuchet> worker # [    0.912270] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]1081vm-test-run-tribuchet> worker # [    0.914144] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]1082vm-test-run-tribuchet> worker # [    0.916185] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window]1083vm-test-run-tribuchet> worker # [    0.918227] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window]1084vm-test-run-tribuchet> worker # [    0.920554] pci 0000:00:01.0: PIIX3: Enabling Passive Release1085vm-test-run-tribuchet> worker # [    0.922391] pci 0000:00:00.0: Limiting direct PCI/PCI transfers1086vm-test-run-tribuchet> worker # [    0.925705] ACPI: \_SB_.LNKD: Enabled at IRQ 111087vm-test-run-tribuchet> worker # [    0.929146] PCI: CLS 0 bytes, default 641088vm-test-run-tribuchet> worker # [    0.930544] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)1089vm-test-run-tribuchet> worker # [    0.932635] Trying to unpack rootfs image as initramfs...1090vm-test-run-tribuchet> worker # [    0.939217] software IO TLB: mapped [mem 0x00000000ba4df000-0x00000000be4df000] (64MB)1091vm-test-run-tribuchet> worker # [    0.941718] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns1092vm-test-run-tribuchet> worker # [    0.988177] Initialise system trusted keyrings1093vm-test-run-tribuchet> worker # [    0.994050] workingset: timestamp_bits=40 max_order=20 bucket_order=01094vm-test-run-tribuchet> worker # [    1.020134] Key type asymmetric registered1095vm-test-run-tribuchet> worker # [    1.021473] Asymmetric key parser 'x509' registered1096vm-test-run-tribuchet> worker # [    1.024934] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)1097vm-test-run-tribuchet> worker # [    1.030924] io scheduler mq-deadline registered1098vm-test-run-tribuchet> worker # [    1.035888] io scheduler kyber registered1099vm-test-run-tribuchet> worker # [    1.037795] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1100vm-test-run-tribuchet> worker # [    1.044191] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A1101vm-test-run-tribuchet> worker # [    1.050769] Linux agpgart interface v0.1031102vm-test-run-tribuchet> worker # [    1.053954] ACPI: bus type drm_connector registered1103vm-test-run-tribuchet> worker # [    1.059110] usbcore: registered new interface driver usbserial_generic1104vm-test-run-tribuchet> worker # [    1.061096] usbserial: USB Serial support registered for generic1105vm-test-run-tribuchet> worker # [    1.064889] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.1106vm-test-run-tribuchet> worker # [    1.064889] Please enable it if your BIOS has the CPPC option.1107vm-test-run-tribuchet> worker # [    1.068908] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled1108vm-test-run-tribuchet> worker # [    1.078024] drop_monitor: Initializing network drop monitor service1109vm-test-run-tribuchet> worker # [    1.080068] NET: Registered PF_INET6 protocol family1110vm-test-run-tribuchet> worker # [    1.086438] Segment Routing with IPv61111vm-test-run-tribuchet> worker # [    1.089901] In-situ OAM (IOAM) with IPv61112vm-test-run-tribuchet> worker # [    1.093166] IPI shorthand broadcast: enabled1113vm-test-run-tribuchet> worker # [    1.102262] sched_clock: Marking stable (848029301, 253827365)->(1308951171, -207094505)1114vm-test-run-tribuchet> worker # [    1.110147] registered taskstats version 11115vm-test-run-tribuchet> worker # [    1.111762] Loading compiled-in X.509 certificates1116vm-test-run-tribuchet> worker # [    1.134874] Demotion targets for Node 0: null1117vm-test-run-tribuchet> worker # [    1.138927] Key type .fscrypt registered1118vm-test-run-tribuchet> worker # [    1.140197] Key type fscrypt-provisioning registered1119vm-test-run-tribuchet> worker # [    1.143995] ima: No TPM chip found, activating TPM-bypass!1120vm-test-run-tribuchet> worker # [    1.145708] ima: Allocated hash algorithm: sha11121vm-test-run-tribuchet> worker # [    1.149899] ima: No architecture policies found1122vm-test-run-tribuchet> worker # [    1.154876] PM:   Magic number: 14:779:3991123vm-test-run-tribuchet> worker # [    1.159161] RAS: Correctable Errors collector initialized.1124vm-test-run-tribuchet> worker # [    1.168716] clk: Disabling unused clocks1125vm-test-run-tribuchet> worker # [    1.171884] PM: genpd: Disabling unused power domains1126vm-test-run-tribuchet> hub # [    8.808001] systemd[1]: initrd-switch-root.service: Deactivated successfully.1127vm-test-run-tribuchet> hub # [    8.810492] systemd[1]: Stopped initrd-switch-root.service.1128vm-test-run-tribuchet> hub # [    8.814212] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1129vm-test-run-tribuchet> hub # [    8.817428] systemd[1]: Created slice Slice /system/getty.1130vm-test-run-tribuchet> hub # [    8.819604] systemd[1]: Created slice User and Session Slice.1131vm-test-run-tribuchet> hub # [    8.821186] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1132vm-test-run-tribuchet> hub # [    8.823322] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1133vm-test-run-tribuchet> hub # [    8.825398] systemd[1]: Expecting device /dev/hvc0...1134vm-test-run-tribuchet> hub # [    8.826733] systemd[1]: Expecting device /dev/ttyS0...1135vm-test-run-tribuchet> hub # [    8.828091] systemd[1]: Reached target Local Encrypted Volumes.1136vm-test-run-tribuchet> hub # [    8.829622] systemd[1]: Stopped target initrd-fs.target.1137vm-test-run-tribuchet> hub # [    8.830948] systemd[1]: Stopped target initrd-root-fs.target.1138vm-test-run-tribuchet> hub # [    8.832386] systemd[1]: Stopped target initrd-switch-root.target.1139vm-test-run-tribuchet> hub # [    8.834003] systemd[1]: Reached target Virtual Machines and Containers.1140vm-test-run-tribuchet> hub # [    8.835670] systemd[1]: Reached target Path Units.1141vm-test-run-tribuchet> hub # [    8.836930] systemd[1]: Reached target Remote File Systems.1142vm-test-run-tribuchet> hub # [    8.838338] systemd[1]: Reached target Slice Units.1143vm-test-run-tribuchet> hub # [    8.839670] systemd[1]: Reached target Swaps.1144vm-test-run-tribuchet> hub # [    8.845844] systemd[1]: Listening on Process Core Dump Socket.1145vm-test-run-tribuchet> hub # [    8.850253] systemd[1]: Listening on Credential Encryption/Decryption.1146vm-test-run-tribuchet> hub # [    8.856178] systemd[1]: Starting Journal Log Access Socket...1147vm-test-run-tribuchet> hub # [    8.858439] systemd[1]: Listening on Journal Audit Socket.1148vm-test-run-tribuchet> hub # [    8.860166] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1149vm-test-run-tribuchet> hub # [    8.862388] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1150vm-test-run-tribuchet> hub # [    8.864968] systemd[1]: Listening on udev Control Socket.1151vm-test-run-tribuchet> worker # [    1.305677] Freeing initrd memory: 27588K1152vm-test-run-tribuchet> worker # [    1.309638] Freeing unused decrypted memory: 2028K1153vm-test-run-tribuchet> hub # [    8.871239] systemd[1]: Mounting Huge Pages File System...1154vm-test-run-tribuchet> worker # [    1.313189] Freeing unused kernel image (initmem) memory: 3640K1155vm-test-run-tribuchet> worker # [    1.315115] Write protecting the kernel read-only data: 32768k1156vm-test-run-tribuchet> hub # [    8.875932] systemd[1]: Mounting POSIX Message Queue File System...1157vm-test-run-tribuchet> worker # [    1.317959] Freeing unused kernel image (text/rodata gap) memory: 1276K1158vm-test-run-tribuchet> worker # [    1.320423] Freeing unused kernel image (rodata/data gap) memory: 776K1159vm-test-run-tribuchet> hub # [    8.881703] systemd[1]: Mounting Kernel Debug File System...1160vm-test-run-tribuchet> hub # [    8.890952] systemd[1]: Mounting Kernel Trace File System...1161vm-test-run-tribuchet> hub # [    8.900699] systemd[1]: Starting Create List of Static Device Nodes...1162vm-test-run-tribuchet> hub # [    8.904447] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1163vm-test-run-tribuchet> hub # [    8.918304] systemd[1]: Starting Load Kernel Module configfs...1164vm-test-run-tribuchet> hub # [    8.920330] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1165vm-test-run-tribuchet> hub # [    8.923044] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1166vm-test-run-tribuchet> hub # [    8.926120] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1167vm-test-run-tribuchet> worker # [    1.373589] x86/mm: Checked W+X mappings: passed, no W+X pages found.1168vm-test-run-tribuchet> hub # [    8.935722] systemd[1]: Mounting FUSE Control File System...1169vm-test-run-tribuchet> worker # [    1.375585] Run /init as init process1170vm-test-run-tribuchet> hub # [    8.938181] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671171vm-test-run-tribuchet> worker # [    1.388045] systemd[1]: Inserted module 'autofs4'1172vm-test-run-tribuchet> hub # [    8.965364] systemd[1]: Starting Journal Service...1173vm-test-run-tribuchet> worker # [    1.405225] fuse: init (API version 7.45)1174vm-test-run-tribuchet> worker # [    1.412673] ACPI: \_SB_.LNKC: Enabled at IRQ 101175vm-test-run-tribuchet> hub # [    8.980909] systemd[1]: Starting Load Kernel Modules...1176vm-test-run-tribuchet> worker # [    1.421508] ACPI: \_SB_.LNKA: Enabled at IRQ 101177vm-test-run-tribuchet> worker # [    1.426149] ACPI: \_SB_.LNKB: Enabled at IRQ 111178vm-test-run-tribuchet> hub # [    9.003881] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1179vm-test-run-tribuchet> hub # [    9.011306] systemd[1]: Starting Remount Root and Kernel File Systems...1180vm-test-run-tribuchet> hub # [    9.013076] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1181vm-test-run-tribuchet> worker # [    1.466936] systemd[1]: Successfully made /usr/ read-only.1182vm-test-run-tribuchet> hub # [    9.035499] systemd[1]: Starting Coldplug All udev Devices...1183vm-test-run-tribuchet> hub # [    9.067494] systemd[1]: Listening on Journal Log Access Socket.1184vm-test-run-tribuchet> hub # [    9.073895] systemd-journald[744]: Collecting audit messages is enabled.1185vm-test-run-tribuchet> hub # [    9.077844] systemd[1]: Mounted Huge Pages File System.1186vm-test-run-tribuchet> hub # [    9.082519] systemd[1]: Mounted POSIX Message Queue File System.1187vm-test-run-tribuchet> hub # [    9.087313] loop: module loaded1188vm-test-run-tribuchet> hub # [    9.092685] systemd[1]: Mounted Kernel Debug File System.1189vm-test-run-tribuchet> hub # [    9.095172] EXT4-fs (vda): re-mounted b0ef88d5-f4c7-4e2a-82d4-b974f0f80d77.1190vm-test-run-tribuchet> hub # [    9.112873] systemd[1]: Mounted Kernel Trace File System.1191vm-test-run-tribuchet> hub # [    9.119753] systemd[1]: Finished Create List of Static Device Nodes.1192vm-test-run-tribuchet> hub # [    9.126226] systemd[1]: modprobe@configfs.service: Deactivated successfully.1193vm-test-run-tribuchet> hub # [    9.132410] systemd[1]: Finished Load Kernel Module configfs.1194vm-test-run-tribuchet> hub # [    9.136194] systemd[1]: Mounted FUSE Control File System.1195vm-test-run-tribuchet> hub # [    9.150143] systemd[1]: Mounting Kernel Configuration File System...1196vm-test-run-tribuchet> hub # [    9.164515] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1197vm-test-run-tribuchet> hub # [    8.913163] systemd[1]: Queued start job for default target Multi-User System.1198vm-test-run-tribuchet> hub # [    8.916630] systemd[1]: systemd-journald.service: Deactivated successfully.1199vm-test-run-tribuchet> hub # [    8.921096] systemd-modules-load[745]: Inserted module 'loop'1200vm-test-run-tribuchet> hub # [    8.932559] systemd-oomd[746]: No swap; memory pressure usage will be degraded1201vm-test-run-tribuchet> hub # [    9.199143] systemd[1]: Started Journal Service.1202vm-test-run-tribuchet> hub # [    8.957308] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1203vm-test-run-tribuchet> hub # [    8.963618] systemd[1]: Finished Load Kernel Modules.1204vm-test-run-tribuchet> hub # [    8.970812] systemd[1]: Finished Remount Root and Kernel File Systems.1205vm-test-run-tribuchet> hub # [    9.005513] systemd[1]: Starting Firewall...1206vm-test-run-tribuchet> hub # [    9.010875] systemd[1]: Starting Flush Journal to Persistent Storage...1207vm-test-run-tribuchet> hub # [    9.015239] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1208vm-test-run-tribuchet> hub # [    9.026873] systemd[1]: Starting Load/Save OS Random Seed...1209vm-test-run-tribuchet> hub # [    9.048890] systemd[1]: Starting Apply Kernel Variables...1210vm-test-run-tribuchet> hub # [    9.053102] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1211vm-test-run-tribuchet> hub # [    9.064924] systemd[1]: Mounted Kernel Configuration File System.1212vm-test-run-tribuchet> hub # [    9.102115] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1213vm-test-run-tribuchet> hub # [    9.118245] systemd[1]: Starting Create Static Device Nodes in /dev...1214vm-test-run-tribuchet> worker # [    1.804461] systemd[1]: systemd 260.1 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)1215vm-test-run-tribuchet> worker # [    1.813278] systemd[1]: Detected virtualization kvm.1216vm-test-run-tribuchet> worker # [    1.814928] systemd[1]: Detected architecture x86-64.1217vm-test-run-tribuchet> worker # [    1.816499] systemd[1]: Running in initrd.1218vm-test-run-tribuchet> worker # [    1.818248] systemd[1]: Initializing machine ID from random generator.1219vm-test-run-tribuchet> worker # [    1.820333] systemd[1]: Hostname set to <worker>.1220vm-test-run-tribuchet> hub # [    9.385533] systemd-journald[744]: Received client request to flush runtime journal.1221vm-test-run-tribuchet> worker # [    1.875202] systemd[1]: Queued start job for default target Initrd Default Target.1222vm-test-run-tribuchet> worker # [    1.935527] systemd[1]: Created slice Slice /system/modprobe.1223vm-test-run-tribuchet> worker # [    1.950080] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1224vm-test-run-tribuchet> worker # [    1.952566] systemd[1]: Expecting device /dev/disk/by-label/nix-store...1225vm-test-run-tribuchet> worker # [    1.954664] systemd[1]: Expecting device /dev/disk/by-label/nixos...1226vm-test-run-tribuchet> worker # [    1.956740] systemd[1]: Reached target Path Units.1227vm-test-run-tribuchet> worker # [    1.958373] systemd[1]: Reached target Slice Units.1228vm-test-run-tribuchet> worker # [    1.960036] systemd[1]: Reached target Swaps.1229vm-test-run-tribuchet> worker # [    1.961515] systemd[1]: Reached target Timer Units.1230vm-test-run-tribuchet> worker # [    1.963315] systemd[1]: Listening on D-Bus System Message Bus Socket.1231vm-test-run-tribuchet> worker # [    1.965489] systemd[1]: Listening on Journal Socket (/dev/log).1232vm-test-run-tribuchet> worker # [    1.967554] systemd[1]: Listening on Journal Sockets.1233vm-test-run-tribuchet> worker # [    1.969398] systemd[1]: Listening on udev Control Socket.1234vm-test-run-tribuchet> worker # [    1.971277] systemd[1]: Listening on udev Kernel Socket.1235vm-test-run-tribuchet> worker # [    1.973031] systemd[1]: Reached target Socket Units.1236vm-test-run-tribuchet> worker # [    1.976930] systemd[1]: Starting Create List of Static Device Nodes...1237vm-test-run-tribuchet> worker # [    1.987131] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1238vm-test-run-tribuchet> worker # [    1.999138] systemd[1]: Starting Load Kernel Module configfs...1239vm-test-run-tribuchet> worker # [    2.019477] systemd[1]: Starting Journal Service...1240vm-test-run-tribuchet> worker # [    2.034377] systemd[1]: Starting Load Kernel Modules...1241vm-test-run-tribuchet> worker # [    2.036257] netfs: FS-Cache loaded1242vm-test-run-tribuchet> worker # [    2.046007] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1243vm-test-run-tribuchet> worker # [    2.050135] 9pnet: Installing 9P2000 support1244vm-test-run-tribuchet> hub # [    9.367558] systemd[1]: Finished Load/Save OS Random Seed.1245vm-test-run-tribuchet> hub # [    9.371465] systemd[1]: Reached target First Boot Complete.1246vm-test-run-tribuchet> worker # [    2.064381] systemd[1]: Starting Coldplug All udev Devices...1247vm-test-run-tribuchet> hub # [    9.374574] systemd[1]: Finished Apply Kernel Variables.1248vm-test-run-tribuchet> hub # [    9.378862] systemd[1]: Finished Create Static Device Nodes in /dev.1249vm-test-run-tribuchet> worker # [    2.073247] systemd-journald[127]: Collecting audit messages is disabled.1250vm-test-run-tribuchet> hub # [    9.381725] systemd[1]: Reached target Preparation for Local File Systems.1251vm-test-run-tribuchet> hub # [    9.385803] systemd[1]: Starting Rule-based Manager for Device Events and Files...1252vm-test-run-tribuchet> hub # [    9.388389] systemd[1]: Finished Flush Journal to Persistent Storage.1253vm-test-run-tribuchet> worker # [    2.099372] systemd[1]: Finished Create List of Static Device Nodes.1254vm-test-run-tribuchet> hub # [    9.411439] systemd-udevd[775]: Using default interface naming scheme 'v260'.1255vm-test-run-tribuchet> worker # [    2.108678] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1256vm-test-run-tribuchet> worker # [    2.112562] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1257vm-test-run-tribuchet> worker # [    2.126809] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1258vm-test-run-tribuchet> worker # [    2.131060] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1259vm-test-run-tribuchet> worker # [    2.143786] systemd[1]: modprobe@configfs.service: Deactivated successfully.1260vm-test-run-tribuchet> worker # [    2.157561] systemd[1]: Finished Load Kernel Module configfs.1261vm-test-run-tribuchet> worker # [    2.173007] systemd[1]: Finished Load Kernel Modules.1262vm-test-run-tribuchet> worker # [    2.179682] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config1263vm-test-run-tribuchet> worker # [    2.194374] systemd[1]: Starting Apply Kernel Variables...1264vm-test-run-tribuchet> worker # [    2.215332] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1265vm-test-run-tribuchet> hub # [    9.535383] systemd[1]: Finished Coldplug All udev Devices.1266vm-test-run-tribuchet> worker # [    2.245464] systemd[1]: Finished Apply Kernel Variables.1267vm-test-run-tribuchet> hub # [    9.558456] systemd[1]: Mounting /run/wrappers...1268vm-test-run-tribuchet> worker # [    2.264890] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1269vm-test-run-tribuchet> hub # [    9.579215] systemd[1]: Started Rule-based Manager for Device Events and Files.1270vm-test-run-tribuchet> worker # [    2.277374] systemd[1]: Starting Create Static Device Nodes in /dev...1271vm-test-run-tribuchet> hub # [    9.605402] systemd[1]: Mounted /run/wrappers.1272vm-test-run-tribuchet> hub # [    9.608436] systemd[1]: Reached target Local File Systems.1273vm-test-run-tribuchet> hub # [    9.615105] systemd[1]: Listening on Boot Loader Control Service Socket.1274vm-test-run-tribuchet> hub # [    9.622118] systemd[1]: Starting register-nix-paths.service...1275vm-test-run-tribuchet> worker # [    2.314884] systemd[1]: Finished Create Static Device Nodes in /dev.1276vm-test-run-tribuchet> worker # [    2.063489] systemd-modules-load[129]: Inserted module 'dm_mod'1277vm-test-run-tribuchet> hub # [    9.627113] systemd[1]: Starting Create SUID/SGID Wrappers...1278vm-test-run-tribuchet> worker # [    2.066985] systemd-modules-load[129]: Inserted module 'virtio_balloon'1279vm-test-run-tribuchet> hub # [    9.629148] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1280vm-test-run-tribuchet> worker # [    2.324204] systemd[1]: Started Journal Service.1281vm-test-run-tribuchet> worker # [    2.071576] systemd-modules-load[129]: Inserted module 'virtio_gpu'1282vm-test-run-tribuchet> hub # [    9.641446] systemd[1]: Starting Save Transient machine-id to Disk...1283vm-test-run-tribuchet> worker # [    2.087104] systemd[1]: Reached target Preparation for Local File Systems.1284vm-test-run-tribuchet> worker # [    2.091102] systemd[1]: Reached target Local File Systems.1285vm-test-run-tribuchet> worker # [    2.095926] systemd[1]: Starting Create System Files and Directories...1286vm-test-run-tribuchet> hub # [    9.656756] systemd[1]: Starting Create System Files and Directories...1287vm-test-run-tribuchet> worker # [    2.104106] systemd[1]: Starting Rule-based Manager for Device Events and Files...1288vm-test-run-tribuchet> worker # [    2.137279] systemd[1]: Finished Create System Files and Directories.1289vm-test-run-tribuchet> worker # [    2.160325] systemd-udevd[162]: Using default interface naming scheme 'v260'.1290vm-test-run-tribuchet> hub # [    9.750238] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1291vm-test-run-tribuchet> worker # [    2.197474] systemd[1]: Started Rule-based Manager for Device Events and Files.1292vm-test-run-tribuchet> hub # [    9.763758] systemd[1]: Finished Save Transient machine-id to Disk.1293vm-test-run-tribuchet> hub # [    9.831437] systemd[1]: Finished Create System Files and Directories.1294vm-test-run-tribuchet> worker # [    2.272326] systemd[1]: Finished Coldplug All udev Devices.1295vm-test-run-tribuchet> worker # [    2.275777] systemd[1]: Reached target System Initialization.1296vm-test-run-tribuchet> worker # [    2.277415] systemd[1]: Reached target Basic System.1297vm-test-run-tribuchet> hub # [    9.846258] systemd[1]: Starting Rebuild Journal Catalog...1298vm-test-run-tribuchet> hub # [    9.856143] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1299vm-test-run-tribuchet> hub # [    9.948466] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1300vm-test-run-tribuchet> hub # [   10.006524] systemd[1]: Finished Rebuild Journal Catalog.1301vm-test-run-tribuchet> hub # [   10.018503] systemd[1]: Starting Update is Completed...1302vm-test-run-tribuchet> hub # [   10.083730] systemd[1]: Finished Update is Completed.1303vm-test-run-tribuchet> worker # [    2.813074] virtio_blk virtio4: 1/0/0 default/read/poll queues1304vm-test-run-tribuchet> worker # [    2.852364] virtio_blk virtio4: [vda] 8388608 512-byte logical blocks (4.29 GB/4.00 GiB)1305vm-test-run-tribuchet> worker # [    2.864481] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121306vm-test-run-tribuchet> hub # [   10.190963] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1307vm-test-run-tribuchet> worker # [    2.905917] uhci_hcd 0000:00:01.2: UHCI Host Controller1308vm-test-run-tribuchet> worker # [    2.907224] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 11309vm-test-run-tribuchet> worker # [    2.917593] virtio_blk virtio5: 1/0/0 default/read/poll queues1310vm-test-run-tribuchet> worker # [    2.925879] serio: i8042 KBD port at 0x60,0x64 irq 11311vm-test-run-tribuchet> worker # [    2.927149] serio: i8042 AUX port at 0x60,0x64 irq 121312vm-test-run-tribuchet> worker # [    2.937731] virtio_blk virtio5: [vdb] 2656944 512-byte logical blocks (1.36 GB/1.27 GiB)1313vm-test-run-tribuchet> worker # [    2.946170] uhci_hcd 0000:00:01.2: detected 2 ports1314vm-test-run-tribuchet> worker # [    2.950343] SCSI subsystem initialized1315vm-test-run-tribuchet> worker # [    2.954584] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c1401316vm-test-run-tribuchet> worker # [    2.969289] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181317vm-test-run-tribuchet> worker # [    2.971269] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11318vm-test-run-tribuchet> worker # [    3.009572] usb usb1: Product: UHCI Host Controller1319vm-test-run-tribuchet> worker # [    3.023880] usb usb1: Manufacturer: Linux 6.18.35 uhci_hcd1320vm-test-run-tribuchet> worker # [    3.038117] usb usb1: SerialNumber: 0000:00:01.21321vm-test-run-tribuchet> worker # [    3.041132] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01322vm-test-run-tribuchet> worker # [    2.791209] (udev-worker)[175]: Network interface NamePolicy= disabled on kernel command line.1323vm-test-run-tribuchet> worker # [    2.795946] (udev-worker)[168]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1324vm-test-run-tribuchet> worker # [    2.800724] (udev-worker)[168]: Network interface NamePolicy= disabled on kernel command line.1325vm-test-run-tribuchet> worker # [    2.815105] systemd[1]: Starting Virtual Console Setup...1326vm-test-run-tribuchet> worker # [    3.070924] hub 1-0:1.0: USB hub found1327vm-test-run-tribuchet> worker # [    3.089770] hub 1-0:1.0: 2 ports detected1328vm-test-run-tribuchet> worker # [    2.860219] systemd-vconsole-setup[191]: Configuration of first virtual console was skipped, ignoring remaining ones.1329vm-test-run-tribuchet> worker # [    2.867287] systemd[1]: Finished Virtual Console Setup.1330vm-test-run-tribuchet> hub # [   10.442108] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1331vm-test-run-tribuchet> worker # [    2.889728] systemd[1]: Found device /dev/disk/by-label/nixos.1332vm-test-run-tribuchet> worker # [    2.891383] systemd[1]: Reached target Initrd Root Device.1333vm-test-run-tribuchet> worker # [    2.897756] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1334vm-test-run-tribuchet> hub # [   10.466764] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1335vm-test-run-tribuchet> hub # [   10.470637] systemd[1]: Finished Create SUID/SGID Wrappers.1336vm-test-run-tribuchet> worker # [    2.911430] systemd[1]: Found device /dev/disk/by-label/nix-store.1337vm-test-run-tribuchet> worker # [    3.192361] scsi host0: ata_piix1338vm-test-run-tribuchet> worker # [    3.197429] scsi host1: ata_piix1339vm-test-run-tribuchet> worker # [    3.200335] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc220 irq 14 lpm-pol 01340vm-test-run-tribuchet> worker # [    2.949385] systemd-fsck[202]: nixos: clean, 12/262144 files, 37198/1048576 blocks1341vm-test-run-tribuchet> worker # [    3.205723] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc228 irq 15 lpm-pol 01342vm-test-run-tribuchet> worker # [    2.959114] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1343vm-test-run-tribuchet> worker # [    2.965937] systemd[1]: Mounting /sysroot...1344vm-test-run-tribuchet> hub # [   10.585207] (udev-worker)[868]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1345vm-test-run-tribuchet> hub # [   10.605078] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1346vm-test-run-tribuchet> hub # [   10.609308] (udev-worker)[868]: Network interface NamePolicy= disabled on kernel command line.1347vm-test-run-tribuchet> hub # [   10.611701] (udev-worker)[881]: Network interface NamePolicy= disabled on kernel command line.1348vm-test-run-tribuchet> worker # [    3.338955] usb 1-1: new full-speed USB device number 2 using uhci_hcd1349vm-test-run-tribuchet> worker # [    3.372343] ata2: found unknown device (class 0)1350vm-test-run-tribuchet> worker # [    3.377628] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001351vm-test-run-tribuchet> worker # [    3.386461] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 51352vm-test-run-tribuchet> hub # [   10.744943] systemd[1]: Finished register-nix-paths.service.1353vm-test-run-tribuchet> hub # [   10.749693] systemd[1]: Reached target System Initialization.1354vm-test-run-tribuchet> hub # [   10.753125] systemd[1]: Started Discard unused filesystem blocks once a week.1355vm-test-run-tribuchet> hub # [   10.755246] systemd[1]: Started Daily Cleanup of Temporary Directories.1356vm-test-run-tribuchet> hub # [   10.757289] systemd[1]: Reached target Timer Units.1357vm-test-run-tribuchet> hub # [   10.759650] systemd[1]: Listening on D-Bus System Message Bus Socket.1358vm-test-run-tribuchet> hub # [   10.762770] systemd[1]: Listening on Nix Daemon Socket.1359vm-test-run-tribuchet> hub # [   10.764758] systemd[1]: Listening on Hostname Service Socket.1360vm-test-run-tribuchet> hub # [   10.767150] systemd[1]: Reached target Socket Units.1361vm-test-run-tribuchet> hub # [   10.768638] systemd[1]: Reached target Basic System.1362vm-test-run-tribuchet> hub # [   10.772184] systemd[1]: Started backdoor.service.1363vm-test-run-tribuchet> hub # [   10.778691] systemd[1]: Starting Import lastlog data into lastlog2 database...1364vm-test-run-tribuchet> hub # [   10.789097] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1365vm-test-run-tribuchet> hub # [   10.799592] systemd[1]: Starting Post-Boot Actions...1366vm-test-run-tribuchet> worker # [    3.493686] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1367vm-test-run-tribuchet> worker # [    3.503418] cdrom: Uniform CD-ROM driver Revision: 3.201368vm-test-run-tribuchet> hub # [   10.817984] systemd[1]: Started Reset console on configuration changes.1369vm-test-run-tribuchet> worker # [    3.514532] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001370vm-test-run-tribuchet> worker # [    3.519242] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101371vm-test-run-tribuchet> worker # [    3.523746] usb 1-1: Product: QEMU USB Tablet1372vm-test-run-tribuchet> worker # [    3.526934] usb 1-1: Manufacturer: QEMU1373vm-test-run-tribuchet> worker # [    3.528218] usb 1-1: SerialNumber: 28754-0000:00:01.2-11374vm-test-run-tribuchet> hub # [   10.842958] systemd[1]: Starting resolvconf update...1375vm-test-run-tribuchet> worker # [    3.540297] EXT4-fs (vda): mounted filesystem 963ef745-fcc1-4f9e-90d6-1b0503f497fd r/w with ordered data mode. Quota mode: none.1376vm-test-run-tribuchet> worker # [    3.294374] systemd[1]: Mounted /sysroot.1377vm-test-run-tribuchet> worker # [    3.297347] systemd[1]: Reached target Initrd Root File System.1378vm-test-run-tribuchet> hub # [   10.864238] systemd[1]: Finished Firewall.1379vm-test-run-tribuchet> worker # [    3.304649] systemd[1]: Starting Mountpoints Configured in the Real Root...1380vm-test-run-tribuchet> worker # [    3.577773] hid: raw HID events driver (C) Jiri Kosina1381vm-test-run-tribuchet> worker # [    3.327173] systemd-sysroot-fstab-check[219]: /sysroot should be mounted in the initrd, will request daemon-reload.1382vm-test-run-tribuchet> worker # [    3.331685] systemd[1]: Reload requested from client PID 219 ('systemd-sysroot') (unit initrd-parse-etc.service)...1383vm-test-run-tribuchet> worker # [    3.334352] systemd[1]: Reloading...1384vm-test-run-tribuchet> hub # connecting to host...1385vm-test-run-tribuchet> hub # [   10.916956] systemd[1]: Finished Post-Boot Actions.1386vm-test-run-tribuchet> worker # [    3.613009] usbcore: registered new interface driver usbhid1387vm-test-run-tribuchet> worker # [    3.620160] usbhid: USB HID core driver1388vm-test-run-tribuchet> hub: Guest shell says: b'Spawning backdoor root shell...\n'1389vm-test-run-tribuchet> hub: connected to guest root shell1390vm-test-run-tribuchet> hub: (connecting took 4.34 seconds)1391vm-test-run-tribuchet> hub: (finished: waiting for the VM to finish booting, in 4.34 seconds)1392vm-test-run-tribuchet> worker # [    3.640559] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21393vm-test-run-tribuchet> worker # [    3.650538] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input01394vm-test-run-tribuchet> hub # [   10.960097] nsncd[985]: Jun 23 21:21:50.987 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1395vm-test-run-tribuchet> hub # [   10.966369] systemd[1]: Starting D-Bus System Message Bus...1396vm-test-run-tribuchet> hub # [   10.969219] systemd[1]: Started Name Service Cache Daemon (nsncd).1397vm-test-run-tribuchet> hub # [   10.972751] systemd[1]: Reached target Host and Network Name Lookups.1398vm-test-run-tribuchet> hub # [   10.975749] systemd[1]: Reached target User and Group Name Lookups.1399vm-test-run-tribuchet> hub # [   11.004102] systemd[1]: Starting User Login Management...1400vm-test-run-tribuchet> hub # [   11.011984] systemd[1]: Finished Import lastlog data into lastlog2 database.1401vm-test-run-tribuchet> hub # [   11.020159] systemd[1]: Condition check resulted in Virtio network device being skipped.1402vm-test-run-tribuchet> hub # [   11.145138] dbus-broker-launch[1008]: Looking up NSS user entry for 'systemd-timesync'...1403vm-test-run-tribuchet> worker # [    3.610284] systemd[1]: Reloading finished in 274 ms.1404vm-test-run-tribuchet> hub # [   11.174235] dbus-broker-launch[1008]: NSS returned no entry for 'systemd-timesync'1405vm-test-run-tribuchet> hub # [   11.177268] dbus-broker-launch[1008]: Invalid user-name in /nix/store/sf3jpf49wl3lv8cxjx220nra4dr96fz2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1406vm-test-run-tribuchet> worker # [    3.628152] systemd-sysroot-fstab-check[219]: Requesting initrd-fs.target/start/replace...1407vm-test-run-tribuchet> hub # [   11.200198] systemd[1]: Stopped target Host and Network Name Lookups.1408vm-test-run-tribuchet> hub # [   11.201938] systemd[1]: Stopping Host and Network Name Lookups...1409vm-test-run-tribuchet> hub # [   11.203536] systemd[1]: Stopped target User and Group Name Lookups.1410vm-test-run-tribuchet> hub # [   11.208446] systemd[1]: Stopping User and Group Name Lookups...1411vm-test-run-tribuchet> hub # [   11.212452] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1412vm-test-run-tribuchet> hub # [   11.217699] systemd[1]: nscd.service: Deactivated successfully.1413vm-test-run-tribuchet> hub # [   11.223461] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1414vm-test-run-tribuchet> hub # [   11.227626] systemd-logind[1014]: New seat seat0.1415vm-test-run-tribuchet> hub # [   11.238284] systemd[1]: Started D-Bus System Message Bus.1416vm-test-run-tribuchet> worker # [    3.683928] systemd-sysroot-fstab-check[219]: Requesting swap.target/start/replace...1417vm-test-run-tribuchet> hub # [   11.499498] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31418vm-test-run-tribuchet> worker # [    3.690404] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1419vm-test-run-tribuchet> worker # [    3.693836] systemd[1]: Finished Mountpoints Configured in the Real Root.1420vm-test-run-tribuchet> worker # [    3.695755] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1421vm-test-run-tribuchet> worker # [    3.703589] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1422vm-test-run-tribuchet> hub # [   11.522704] ACPI: button: Power Button [PWRF]1423vm-test-run-tribuchet> hub # [   11.275489] dbus-broker-launch[1008]: Ready1424vm-test-run-tribuchet> hub # [   11.280286] systemd[1]: Started User Login Management.1425vm-test-run-tribuchet> hub # [   11.319614] systemd[1]: Starting linger-users.service...1426vm-test-run-tribuchet> hub # [   11.329067] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1427vm-test-run-tribuchet> hub # [   11.393400] systemd[1]: linger-users.service: Deactivated successfully.1428vm-test-run-tribuchet> hub # [   11.398343] systemd[1]: Finished linger-users.service.1429vm-test-run-tribuchet> hub # [   11.405279] nsncd[1067]: Jun 23 21:21:51.437 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1430vm-test-run-tribuchet> worker # [    3.847277] systemd[1]: Mounting /sysroot/nix/.ro-store...1431vm-test-run-tribuchet> hub # [   11.409975] systemd[1]: Finished resolvconf update.1432vm-test-run-tribuchet> hub # [   11.412370] systemd[1]: Started Name Service Cache Daemon (nsncd).1433vm-test-run-tribuchet> hub # [   11.416646] systemd[1]: Reached target Preparation for Network.1434vm-test-run-tribuchet> hub # [   11.418755] systemd[1]: Reached target Host and Network Name Lookups.1435vm-test-run-tribuchet> hub # [   11.421336] systemd[1]: Reached target User and Group Name Lookups.1436vm-test-run-tribuchet> worker # [    3.861252] systemd[1]: Mounting /sysroot/nix/.rw-store...1437vm-test-run-tribuchet> hub # [   11.429727] systemd[1]: Starting DHCP Client...1438vm-test-run-tribuchet> hub # [   11.436474] systemd[1]: Starting Address configuration of eth1...1439vm-test-run-tribuchet> worker # [    3.877793] systemd[1]: Mounting /sysroot/run...1440vm-test-run-tribuchet> hub # [   11.443342] systemd[1]: Starting Extra networking commands....1441vm-test-run-tribuchet> worker # [    3.891205] systemd[1]: Mounting /sysroot/tmp/shared...1442vm-test-run-tribuchet> worker # [    3.900583] systemd[1]: Mounting /sysroot/tmp/xchg...1443vm-test-run-tribuchet> worker # [    3.923104] systemd[1]: Mounted /sysroot/nix/.rw-store.1444vm-test-run-tribuchet> worker # [    4.180884] erofs (device vdb): mounted with root inode @ nid 128.1445vm-test-run-tribuchet> worker # [    3.943442] systemd[1]: Mounted /sysroot/nix/.ro-store.1446vm-test-run-tribuchet> worker # [    4.198967] 9p: Installing v9fs 9p2000 file system support1447vm-test-run-tribuchet> worker # [    3.948098] systemd[1]: Mounted /sysroot/run.1448vm-test-run-tribuchet> worker # [    3.958217] systemd[1]: Starting rw-sysroot-nix-store.service...1449vm-test-run-tribuchet> worker # [    3.965105] systemd[1]: Mounted /sysroot/tmp/shared.1450vm-test-run-tribuchet> worker # [    3.966753] systemd[1]: Mounted /sysroot/tmp/xchg.1451vm-test-run-tribuchet> worker # [    3.976233] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1452vm-test-run-tribuchet> hub # [   11.794493] rtc_cmos 00:05: RTC can wake from S41453vm-test-run-tribuchet> worker # [    3.980936] systemd[1]: Finished rw-sysroot-nix-store.service.1454vm-test-run-tribuchet> hub # [   11.582790] network-addresses-eth1-start[1090]: adding address 192.168.1.1/24... done1455vm-test-run-tribuchet> hub # [   11.849825] parport_pc 00:03: reported by Plug and Play ACPI1456vm-test-run-tribuchet> hub # [   11.607528] network-addresses-eth1-start[1090]: adding address 2001:db8:1::1/64... done1457vm-test-run-tribuchet> hub # [   11.640255] systemd[1]: Finished Address configuration of eth1.1458vm-test-run-tribuchet> hub # [   11.898842] rtc_cmos 00:05: registered as rtc01459vm-test-run-tribuchet> hub # [   11.911853] Floppy drive(s): fd0 is 2.88M AMI BIOS1460vm-test-run-tribuchet> hub # [   11.931740] mousedev: PS/2 mouse device common for all mice1461vm-test-run-tribuchet> hub # [   11.934914] rtc_cmos 00:05: setting system clock to 2026-06-23T21:21:51 UTC (1782249711)1462vm-test-run-tribuchet> hub # [   11.938049] systemd-journald[744]: Time jumped backwards, rotating.1463vm-test-run-tribuchet> hub # [   11.961944] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1464vm-test-run-tribuchet> hub # [   11.979750] FDC 0 is a S82078B1465vm-test-run-tribuchet> hub # [   11.732337] dhcpcd[1100]: dhcpcd-10.3.2 starting1466vm-test-run-tribuchet> hub # [   11.994752] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console1467vm-test-run-tribuchet> hub # [   11.745376] dhcpcd[1148]: dev: loaded udev1468vm-test-run-tribuchet> hub # [   12.005053] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 01469vm-test-run-tribuchet> hub # [   11.753492] systemd[1]: Finished Extra networking commands..1470vm-test-run-tribuchet> hub # [   11.756787] systemd[1]: Reached target Network.1471vm-test-run-tribuchet> hub # [   11.759393] systemd[1]: Starting Permit User Sessions...1472vm-test-run-tribuchet> hub # [   12.016746] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1473vm-test-run-tribuchet> hub # [   12.038233] 8021q: 802.1Q VLAN Support v1.81474vm-test-run-tribuchet> hub # [   12.042263] 8021q: adding VLAN 0 to HW filter on device eth11475vm-test-run-tribuchet> hub # [   11.815271] systemd[1]: Finished Permit User Sessions.1476vm-test-run-tribuchet> hub # [   12.077168] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1477vm-test-run-tribuchet> hub # [   12.085886] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input41478vm-test-run-tribuchet> hub # [   12.121529] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61479vm-test-run-tribuchet> hub # [   12.121952] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51480vm-test-run-tribuchet> hub # [   12.200163] cfg80211: Loading compiled-in X.509 certificates for regulatory database1481vm-test-run-tribuchet> hub # [   12.214450] Console: switching to colour dummy device 80x251482vm-test-run-tribuchet> hub # [   12.237081] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1483vm-test-run-tribuchet> hub # [   12.237208] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1484vm-test-run-tribuchet> hub # [   12.239256] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21485vm-test-run-tribuchet> hub # [   12.239264] cfg80211: failed to load regulatory.db1486vm-test-run-tribuchet> hub # [   12.318659] 8021q: adding VLAN 0 to HW filter on device eth01487vm-test-run-tribuchet> hub # [   12.402926] [drm] Found bochs VGA, ID 0xb0c5.1488vm-test-run-tribuchet> hub # [   12.402928] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1489vm-test-run-tribuchet> hub # [   12.065836] dhcpcd[1148]: eth0: waiting for carrier1490vm-test-run-tribuchet> hub # [   12.154294] dhcpcd[1148]: eth0: carrier acquired1491vm-test-run-tribuchet> hub # [   12.155844] systemd[1]: Started Getty on tty1.1492vm-test-run-tribuchet> hub # [   12.158694] dhcpcd[1148]: DUID 00:01:00:01:31:cd:b5:6f:52:54:00:12:34:561493vm-test-run-tribuchet> hub # [   12.160526] dhcpcd[1148]: eth0: IAID 00:12:34:561494vm-test-run-tribuchet> hub # [   12.163847] systemd[1]: Reached target Login Prompts.1495vm-test-run-tribuchet> hub # [   12.166367] dhcpcd[1148]: eth0: adding address fe80::5054:ff:fe12:34561496vm-test-run-tribuchet> hub # [   12.177957] systemd[1]: Starting Virtual Console Setup...1497vm-test-run-tribuchet> hub # [   12.436864] ppdev: user-space parallel port driver1498vm-test-run-tribuchet> hub # [   12.193347] systemd-logind[1014]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1499vm-test-run-tribuchet> hub # [   12.453431] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic1500vm-test-run-tribuchet> hub # [   12.454721] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 01501vm-test-run-tribuchet> hub # [   12.208594] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1502vm-test-run-tribuchet> hub # [   12.220341] systemd-logind[1014]: Watching system buttons on /dev/input/event2 (Power Button)1503vm-test-run-tribuchet> hub # [   12.274449] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1504vm-test-run-tribuchet> hub # [   12.278788] systemd[1]: Stopped Virtual Console Setup.1505vm-test-run-tribuchet> hub # [   12.287873] systemd[1]: Starting Virtual Console Setup...1506vm-test-run-tribuchet> hub # [   12.372226] systemd-logind[1014]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1507vm-test-run-tribuchet> worker # [    4.846825] systemd[1]: Mounting /sysroot/nix/store...1508vm-test-run-tribuchet> hub # [   12.612261] fbcon: bochs-drmdrmfb (fb0) is primary device1509vm-test-run-tribuchet> hub # [   12.665327] Console: switching to colour frame buffer device 160x501510vm-test-run-tribuchet> hub # [   12.688760] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device1511vm-test-run-tribuchet> hub # [   12.442134] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1512vm-test-run-tribuchet> hub # [   12.445279] systemd[1]: Stopped Virtual Console Setup.1513vm-test-run-tribuchet> worker # [    4.896133] systemd[1]: Mounted /sysroot/nix/store.1514vm-test-run-tribuchet> worker # [    4.900085] systemd[1]: Reached target Initrd File Systems.1515vm-test-run-tribuchet> hub # [   12.462260] systemd[1]: Starting Virtual Console Setup...1516vm-test-run-tribuchet> worker # [    4.902196] systemd[1]: Starting Find NixOS closure...1517vm-test-run-tribuchet> worker # [    4.907582] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1518vm-test-run-tribuchet> worker # [    4.935226] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1519vm-test-run-tribuchet> worker # [    4.940308] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1520vm-test-run-tribuchet> worker # [    4.949290] systemd[1]: Finished Find NixOS closure.1521vm-test-run-tribuchet> worker # [    4.952115] systemd[1]: Reached target Initrd Default Target.1522vm-test-run-tribuchet> worker # [    4.956057] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1523vm-test-run-tribuchet> worker # [    4.974092] systemd[1]: initrd-cleanup.service: Deactivated successfully.1524vm-test-run-tribuchet> worker # [    4.977533] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1525vm-test-run-tribuchet> worker # [    4.984123] systemd[1]: Stopped target Initrd Default Target.1526vm-test-run-tribuchet> worker # [    4.985692] systemd[1]: Stopped target Basic System.1527vm-test-run-tribuchet> worker # [    4.987122] systemd[1]: Stopped target Initrd Root Device.1528vm-test-run-tribuchet> worker # [    4.988602] systemd[1]: Stopped target Path Units.1529vm-test-run-tribuchet> worker # [    4.991408] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1530vm-test-run-tribuchet> worker # [    4.994274] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1531vm-test-run-tribuchet> worker # [    4.996433] systemd[1]: Stopped target Slice Units.1532vm-test-run-tribuchet> worker # [    4.999112] systemd[1]: Stopped target Socket Units.1533vm-test-run-tribuchet> worker # [    5.000533] systemd[1]: Stopped target System Initialization.1534vm-test-run-tribuchet> hub # [   12.816400] kvm_amd: TSC scaling supported1535vm-test-run-tribuchet> hub # [   12.817397] kvm_amd: Nested Virtualization enabled1536vm-test-run-tribuchet> worker # [    5.003116] systemd[1]: Stopped target Swaps.1537vm-test-run-tribuchet> worker # [    5.004383] systemd[1]: Stopped target Timer Units.1538vm-test-run-tribuchet> hub # [   12.819079] kvm_amd: Nested Paging enabled1539vm-test-run-tribuchet> hub # [   12.820270] kvm_amd: LBR virtualization supported1540vm-test-run-tribuchet> worker # [    5.006258] systemd[1]: dbus.socket: Deactivated successfully.1541vm-test-run-tribuchet> hub # [   12.821794] kvm_amd: Virtual VMLOAD VMSAVE supported1542vm-test-run-tribuchet> hub # [   12.823032] kvm_amd: Virtual GIF supported1543vm-test-run-tribuchet> worker # [    5.009115] systemd[1]: Closed D-Bus System Message Bus Socket.1544vm-test-run-tribuchet> hub # [   12.824114] kvm_amd: Virtual NMI enabled1545vm-test-run-tribuchet> worker # [    5.010698] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1546vm-test-run-tribuchet> worker # [    5.012595] systemd[1]: Stopped Find NixOS closure.1547vm-test-run-tribuchet> worker # [    5.014361] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1548vm-test-run-tribuchet> worker # [    5.017500] systemd[1]: Starting rw-sysroot-nix-store.service...1549vm-test-run-tribuchet> worker # [    5.021321] systemd[1]: systemd-sysctl.service: Deactivated successfully.1550vm-test-run-tribuchet> worker # [    5.023053] systemd[1]: Stopped Apply Kernel Variables.1551vm-test-run-tribuchet> worker # [    5.028690] systemd[1]: systemd-modules-load.service: Deactivated successfully.1552vm-test-run-tribuchet> worker # [    5.030497] systemd[1]: Stopped Load Kernel Modules.1553vm-test-run-tribuchet> worker # [    5.035185] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1554vm-test-run-tribuchet> worker # [    5.037353] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1555vm-test-run-tribuchet> worker # [    5.043365] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1556vm-test-run-tribuchet> worker # [    5.045380] systemd[1]: Stopped Create System Files and Directories.1557vm-test-run-tribuchet> worker # [    5.049172] systemd[1]: Stopped target Local File Systems.1558vm-test-run-tribuchet> worker # [    5.050738] systemd[1]: Stopped target Preparation for Local File Systems.1559vm-test-run-tribuchet> worker # [    5.052705] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1560vm-test-run-tribuchet> worker # [    5.055218] systemd[1]: Stopped Coldplug All udev Devices.1561vm-test-run-tribuchet> worker # [    5.059480] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1562vm-test-run-tribuchet> worker # [    5.061397] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1563vm-test-run-tribuchet> worker # [    5.063769] systemd[1]: Stopped Virtual Console Setup.1564vm-test-run-tribuchet> hub # [   12.885312] EDAC MC: Ver: 3.0.01565vm-test-run-tribuchet> worker # [    5.071400] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1566vm-test-run-tribuchet> worker # [    5.077418] systemd[1]: Finished rw-sysroot-nix-store.service.1567vm-test-run-tribuchet> worker # [    5.085919] systemd[1]: systemd-udevd.service: Deactivated successfully.1568vm-test-run-tribuchet> worker # [    5.089487] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1569vm-test-run-tribuchet> worker # [    5.094351] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1570vm-test-run-tribuchet> worker # [    5.097294] systemd[1]: Closed udev Control Socket.1571vm-test-run-tribuchet> worker # [    5.101813] systemd[1]: Starting Cleanup udev Database...1572vm-test-run-tribuchet> worker # [    5.103536] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1573vm-test-run-tribuchet> worker # [    5.106247] systemd[1]: Stopped Create Static Device Nodes in /dev.1574vm-test-run-tribuchet> worker # [    5.112164] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1575vm-test-run-tribuchet> worker # [    5.115589] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1576vm-test-run-tribuchet> worker # [    5.120113] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1577vm-test-run-tribuchet> worker # [    5.122377] systemd[1]: Stopped Create List of Static Device Nodes.1578vm-test-run-tribuchet> worker # [    5.136995] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1579vm-test-run-tribuchet> worker # [    5.141095] systemd[1]: Finished Cleanup udev Database.1580vm-test-run-tribuchet> worker # [    5.145316] systemd[1]: Reached target Switch Root.1581vm-test-run-tribuchet> worker # [    5.148860] systemd[1]: Starting NixOS Activation...1582vm-test-run-tribuchet> worker # [    5.204681] initrd-nixos-activation-start[517]: booting system configuration /nix/store/1mrbkbyl80wk15h6h6hrfv4zv4qflb88-nixos-system-worker-test1583vm-test-run-tribuchet> worker # [    5.225054] initrd-nixos-activation-start[517]: running activation script...1584vm-test-run-tribuchet> worker # [    5.383977] initrd-nixos-activation-start[540]: setting up /etc...1585vm-test-run-tribuchet> worker # [    5.465901] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1586vm-test-run-tribuchet> worker # [    5.470189] systemd[1]: Finished NixOS Activation.1587vm-test-run-tribuchet> worker # [    5.476166] systemd[1]: Starting Switch Root...1588vm-test-run-tribuchet> worker # [    5.491309] systemd[1]: Switching root.1589vm-test-run-tribuchet> hub # [   13.070151] systemd-vconsole-setup[1186]: Configuration of first virtual console was skipped, ignoring remaining ones.1590vm-test-run-tribuchet> hub # [   13.076252] systemd[1]: Finished Virtual Console Setup.1591vm-test-run-tribuchet> worker # [    5.883309] systemd-journald[127]: Received SIGTERM from PID 1 (systemd).1592vm-test-run-tribuchet> worker # [    5.964895] NET: Registered PF_VSOCK protocol family1593vm-test-run-tribuchet> hub # [   13.496467] dhcpcd[1148]: eth0: soliciting a DHCP lease1594vm-test-run-tribuchet> hub # [   13.785422] NET: Registered PF_PACKET protocol family1595vm-test-run-tribuchet> hub # [   13.538971] dhcpcd[1148]: eth0: offered 10.0.2.15 from 10.0.2.21596vm-test-run-tribuchet> hub # [   13.542242] dhcpcd[1148]: eth0: probing address 10.0.2.15/241597vm-test-run-tribuchet> worker # [    6.324585] systemd[1]: systemd 260.1 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)1598vm-test-run-tribuchet> worker # [    6.341102] systemd[1]: Detected virtualization kvm.1599vm-test-run-tribuchet> worker # [    6.344272] systemd[1]: Detected architecture x86-64.1600vm-test-run-tribuchet> worker # [    6.347632] systemd[1]: Detected first boot.1601vm-test-run-tribuchet> worker # [    6.351630] systemd[1]: Initializing machine ID from random generator.1602vm-test-run-tribuchet> worker # [    6.596069] systemd[1]: bpf-restrict-fs: LSM BPF program attached1603vm-test-run-tribuchet> worker # [    6.699483] systemd[1]: Applying preset policy.1604vm-test-run-tribuchet> worker # [    6.844177] systemd[1]: Populated /etc with preset unit settings.1605vm-test-run-tribuchet> worker # [    6.950666] systemd[1]: initrd-switch-root.service: Deactivated successfully.1606vm-test-run-tribuchet> worker # [    6.953108] systemd[1]: Stopped initrd-switch-root.service.1607vm-test-run-tribuchet> worker # [    6.956335] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1608vm-test-run-tribuchet> worker # [    6.959520] systemd[1]: Created slice Slice /system/getty.1609vm-test-run-tribuchet> worker # [    6.961647] systemd[1]: Created slice User and Session Slice.1610vm-test-run-tribuchet> worker # [    6.963329] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1611vm-test-run-tribuchet> worker # [    6.965471] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1612vm-test-run-tribuchet> worker # [    6.967413] systemd[1]: Expecting device /dev/hvc0...1613vm-test-run-tribuchet> worker # [    6.968730] systemd[1]: Expecting device /dev/ttyS0...1614vm-test-run-tribuchet> worker # [    6.970126] systemd[1]: Reached target Local Encrypted Volumes.1615vm-test-run-tribuchet> worker # [    6.971627] systemd[1]: Stopped target initrd-fs.target.1616vm-test-run-tribuchet> worker # [    6.973010] systemd[1]: Stopped target initrd-root-fs.target.1617vm-test-run-tribuchet> worker # [    6.974431] systemd[1]: Stopped target initrd-switch-root.target.1618vm-test-run-tribuchet> worker # [    6.976012] systemd[1]: Reached target Virtual Machines and Containers.1619vm-test-run-tribuchet> worker # [    6.977643] systemd[1]: Reached target Path Units.1620vm-test-run-tribuchet> worker # [    6.978968] systemd[1]: Reached target Remote File Systems.1621vm-test-run-tribuchet> worker # [    6.980380] systemd[1]: Reached target Slice Units.1622vm-test-run-tribuchet> worker # [    6.981734] systemd[1]: Reached target Swaps.1623vm-test-run-tribuchet> worker # [    6.984034] systemd[1]: Listening on Process Core Dump Socket.1624vm-test-run-tribuchet> worker # [    6.986166] systemd[1]: Listening on Credential Encryption/Decryption.1625vm-test-run-tribuchet> worker # [    6.989446] systemd[1]: Starting Journal Log Access Socket...1626vm-test-run-tribuchet> worker # [    6.991567] systemd[1]: Listening on Journal Audit Socket.1627vm-test-run-tribuchet> worker # [    6.994047] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1628vm-test-run-tribuchet> worker # [    6.995943] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1629vm-test-run-tribuchet> worker # [    6.998351] systemd[1]: Listening on udev Control Socket.1630vm-test-run-tribuchet> worker # [    7.001428] systemd[1]: Mounting Huge Pages File System...1631vm-test-run-tribuchet> worker # [    7.007951] systemd[1]: Mounting POSIX Message Queue File System...1632vm-test-run-tribuchet> worker # [    7.014168] systemd[1]: Mounting Kernel Debug File System...1633vm-test-run-tribuchet> worker # [    7.023148] systemd[1]: Mounting Kernel Trace File System...1634vm-test-run-tribuchet> worker # [    7.036956] systemd[1]: Starting Create List of Static Device Nodes...1635vm-test-run-tribuchet> worker # [    7.044988] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio1636vm-test-run-tribuchet> worker # [    7.054958] systemd[1]: Starting Load Kernel Module configfs...1637vm-test-run-tribuchet> worker # [    7.059974] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1638vm-test-run-tribuchet> worker # [    7.063406] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1639vm-test-run-tribuchet> worker # [    7.066069] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1640vm-test-run-tribuchet> worker # [    7.074142] systemd[1]: Mounting FUSE Control File System...1641vm-test-run-tribuchet> worker # [    7.080977] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671642vm-test-run-tribuchet> worker # [    7.090633] systemd[1]: Starting Journal Service...1643vm-test-run-tribuchet> worker # [    7.097277] systemd[1]: Starting Load Kernel Modules...1644vm-test-run-tribuchet> worker # [    7.108081] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1645vm-test-run-tribuchet> worker # [    7.126949] systemd[1]: Starting Remount Root and Kernel File Systems...1646vm-test-run-tribuchet> worker # [    7.137024] loop: module loaded1647vm-test-run-tribuchet> worker # [    7.139233] systemd-journald[736]: Collecting audit messages is enabled.1648vm-test-run-tribuchet> worker # [    7.141584] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1649vm-test-run-tribuchet> worker # [    7.156230] systemd[1]: Starting Coldplug All udev Devices...1650vm-test-run-tribuchet> worker # [    7.183914] EXT4-fs (vda): re-mounted 963ef745-fcc1-4f9e-90d6-1b0503f497fd.1651vm-test-run-tribuchet> worker # [    7.188551] systemd[1]: Listening on Journal Log Access Socket.1652vm-test-run-tribuchet> worker # [    7.198630] systemd[1]: Mounted Huge Pages File System.1653vm-test-run-tribuchet> worker # [    7.206423] systemd[1]: Mounted POSIX Message Queue File System.1654vm-test-run-tribuchet> worker # [    6.970397] systemd[1]: Queued start job for default target Multi-User System.1655vm-test-run-tribuchet> worker # [    6.973585] systemd[1]: Unnecessary job was removed for /dev/vdb.1656vm-test-run-tribuchet> worker # [    6.976669] systemd[1]: systemd-journald.service: Deactivated successfully.1657vm-test-run-tribuchet> worker # [    6.978988] systemd-modules-load[737]: Inserted module 'loop'1658vm-test-run-tribuchet> worker # [    7.257266] systemd[1]: Started Journal Service.1659vm-test-run-tribuchet> worker # [    7.005464] systemd-oomd[739]: No swap; memory pressure usage will be degraded1660vm-test-run-tribuchet> worker # [    7.014107] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1661vm-test-run-tribuchet> worker # [    7.015905] systemd[1]: Mounted Kernel Debug File System.1662vm-test-run-tribuchet> worker # [    7.017551] systemd[1]: Mounted Kernel Trace File System.1663vm-test-run-tribuchet> worker # [    7.022454] systemd[1]: Finished Create List of Static Device Nodes.1664vm-test-run-tribuchet> worker # [    7.024251] systemd[1]: modprobe@configfs.service: Deactivated successfully.1665vm-test-run-tribuchet> worker # [    7.027341] systemd[1]: Finished Load Kernel Module configfs.1666vm-test-run-tribuchet> worker # [    7.031220] systemd[1]: Mounted FUSE Control File System.1667vm-test-run-tribuchet> worker # [    7.035140] systemd[1]: Finished Load Kernel Modules.1668vm-test-run-tribuchet> worker # [    7.037134] systemd[1]: Finished Remount Root and Kernel File Systems.1669vm-test-run-tribuchet> worker # [    7.057115] systemd[1]: Mounting Kernel Configuration File System...1670vm-test-run-tribuchet> worker # [    7.067103] systemd[1]: Starting Firewall...1671vm-test-run-tribuchet> worker # [    7.074113] systemd[1]: Starting Flush Journal to Persistent Storage...1672vm-test-run-tribuchet> worker # [    7.075850] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1673vm-test-run-tribuchet> worker # [    7.089114] systemd[1]: Starting Load/Save OS Random Seed...1674vm-test-run-tribuchet> worker # [    7.102511] systemd[1]: Starting Apply Kernel Variables...1675vm-test-run-tribuchet> worker # [    7.121640] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1676vm-test-run-tribuchet> worker # [    7.123621] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1677vm-test-run-tribuchet> hub # [   14.710266] dhcpcd[1148]: eth0: soliciting an IPv6 router1678vm-test-run-tribuchet> hub # [   14.712638] dhcpcd[1148]: eth0: Router Advertisement from fe80::21679vm-test-run-tribuchet> hub # [   14.714827] dhcpcd[1148]: eth0: adding address fec0::5054:ff:fe12:3456/641680vm-test-run-tribuchet> hub # [   14.716612] dhcpcd[1148]: eth0: adding route to fec0::/641681vm-test-run-tribuchet> hub # [   14.718321] dhcpcd[1148]: eth0: adding default route via fe80::21682vm-test-run-tribuchet> worker # [    7.170317] systemd[1]: Mounted Kernel Configuration File System.1683vm-test-run-tribuchet> worker # [    7.426331] systemd-journald[736]: Received client request to flush runtime journal.1684vm-test-run-tribuchet> worker # [    7.436990] systemd[1]: Finished Load/Save OS Random Seed.1685vm-test-run-tribuchet> worker # [    7.443923] systemd[1]: Reached target First Boot Complete.1686vm-test-run-tribuchet> worker # [    7.449549] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1687vm-test-run-tribuchet> worker # [    7.452500] systemd[1]: Starting Create Static Device Nodes in /dev...1688vm-test-run-tribuchet> worker # [    7.458534] systemd[1]: Finished Apply Kernel Variables.1689vm-test-run-tribuchet> worker # [    7.462171] systemd[1]: Finished Create Static Device Nodes in /dev.1690vm-test-run-tribuchet> worker # [    7.466237] systemd[1]: Reached target Preparation for Local File Systems.1691vm-test-run-tribuchet> worker # [    7.469263] systemd[1]: Starting Rule-based Manager for Device Events and Files...1692vm-test-run-tribuchet> worker # [    7.473309] systemd-udevd[776]: Using default interface naming scheme 'v260'.1693vm-test-run-tribuchet> worker # [    7.476593] systemd[1]: Finished Flush Journal to Persistent Storage.1694vm-test-run-tribuchet> worker # [    7.538917] systemd[1]: Started Rule-based Manager for Device Events and Files.1695vm-test-run-tribuchet> worker # [    7.635753] systemd[1]: Finished Coldplug All udev Devices.1696vm-test-run-tribuchet> worker # [    7.707134] systemd[1]: Mounting /run/wrappers...1697vm-test-run-tribuchet> worker # [    7.732628] systemd[1]: Mounted /run/wrappers.1698vm-test-run-tribuchet> worker # [    7.735130] systemd[1]: Reached target Local File Systems.1699vm-test-run-tribuchet> worker # [    7.742109] systemd[1]: Listening on Boot Loader Control Service Socket.1700vm-test-run-tribuchet> worker # [    7.748103] systemd[1]: Starting register-nix-paths.service...1701vm-test-run-tribuchet> worker # [    7.756204] systemd[1]: Starting Create SUID/SGID Wrappers...1702vm-test-run-tribuchet> worker # [    7.759104] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1703vm-test-run-tribuchet> worker # [    7.776107] systemd[1]: Starting Save Transient machine-id to Disk...1704vm-test-run-tribuchet> worker # [    7.794211] systemd[1]: Starting Create System Files and Directories...1705vm-test-run-tribuchet> worker # [    7.868931] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1706vm-test-run-tribuchet> worker # [    7.880339] systemd[1]: Finished Save Transient machine-id to Disk.1707vm-test-run-tribuchet> worker # [    7.933336] systemd[1]: Finished Firewall.1708vm-test-run-tribuchet> worker # [    7.943829] systemd[1]: Finished Create System Files and Directories.1709vm-test-run-tribuchet> worker # [    7.961226] systemd[1]: Starting Rebuild Journal Catalog...1710vm-test-run-tribuchet> worker # [    7.977663] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1711vm-test-run-tribuchet> worker # [    8.063479] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1712vm-test-run-tribuchet> worker # [    8.114848] systemd[1]: Finished Rebuild Journal Catalog.1713vm-test-run-tribuchet> worker # [    8.128602] systemd[1]: Starting Update is Completed...1714vm-test-run-tribuchet> worker # [    8.152638] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1715vm-test-run-tribuchet> worker # [    8.181822] systemd[1]: Finished Update is Completed.1716vm-test-run-tribuchet> worker # [    8.301931] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1717vm-test-run-tribuchet> worker # [    8.308156] systemd[1]: Finished Create SUID/SGID Wrappers.1718vm-test-run-tribuchet> worker # [    8.309766] systemd[1]: suid-sgid-wrappers.service: Consumed 109ms CPU time over 544ms wall clock time, 3.5M memory peak, 1.1M read from disk.1719vm-test-run-tribuchet> worker # [    8.343118] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1720vm-test-run-tribuchet> worker # [    8.452352] (udev-worker)[877]: Network interface NamePolicy= disabled on kernel command line.1721vm-test-run-tribuchet> worker # [    8.455112] systemd[1]: Finished register-nix-paths.service.1722vm-test-run-tribuchet> worker # [    8.459102] systemd[1]: Reached target System Initialization.1723vm-test-run-tribuchet> worker # [    8.460682] systemd[1]: Started Discard unused filesystem blocks once a week.1724vm-test-run-tribuchet> worker # [    8.462538] systemd[1]: Started Daily Cleanup of Temporary Directories.1725vm-test-run-tribuchet> worker # [    8.465881] systemd[1]: Reached target Timer Units.1726vm-test-run-tribuchet> worker # [    8.468540] systemd[1]: Listening on D-Bus System Message Bus Socket.1727vm-test-run-tribuchet> worker # [    8.471273] systemd[1]: Listening on Nix Daemon Socket.1728vm-test-run-tribuchet> worker # [    8.473263] systemd[1]: Listening on Hostname Service Socket.1729vm-test-run-tribuchet> worker # [    8.475593] systemd[1]: Reached target Socket Units.1730vm-test-run-tribuchet> worker # [    8.477261] systemd[1]: Reached target Basic System.1731vm-test-run-tribuchet> worker # [    8.480423] systemd[1]: Starting Import lastlog data into lastlog2 database...1732vm-test-run-tribuchet> worker # [    8.488538] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1733vm-test-run-tribuchet> worker # [    8.492578] (udev-worker)[867]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1734vm-test-run-tribuchet> worker # [    8.496311] (udev-worker)[867]: Network interface NamePolicy= disabled on kernel command line.1735vm-test-run-tribuchet> worker # [    8.502977] systemd[1]: Starting Post-Boot Actions...1736vm-test-run-tribuchet> worker # [    8.515850] systemd[1]: Started Reset console on configuration changes.1737vm-test-run-tribuchet> worker # [    8.536215] systemd[1]: Starting resolvconf update...1738vm-test-run-tribuchet> worker # [    8.564102] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1739vm-test-run-tribuchet> worker # [    8.583147] systemd[1]: Finished Post-Boot Actions.1740vm-test-run-tribuchet> worker # [    8.606624] nsncd[967]: Jun 23 21:21:56.203 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1741vm-test-run-tribuchet> worker # [    8.617102] systemd[1]: Started backdoor.service.1742vm-test-run-tribuchet> worker # [    8.633179] systemd[1]: Starting D-Bus System Message Bus...1743vm-test-run-tribuchet> worker # [    8.634745] systemd[1]: Started Name Service Cache Daemon (nsncd).1744vm-test-run-tribuchet> worker # [    8.644128] systemd[1]: Finished Import lastlog data into lastlog2 database.1745vm-test-run-tribuchet> worker # [    8.667689] systemd[1]: Reached target Host and Network Name Lookups.1746vm-test-run-tribuchet> worker # [    8.670263] systemd[1]: Reached target User and Group Name Lookups.1747vm-test-run-tribuchet> worker # [    8.690123] systemd[1]: Starting User Login Management...1748vm-test-run-tribuchet> worker # connecting to host...1749vm-test-run-tribuchet> worker # [    8.759129] dbus-broker-launch[994]: Looking up NSS user entry for 'systemd-timesync'...1750vm-test-run-tribuchet> worker # [    8.765548] dbus-broker-launch[994]: NSS returned no entry for 'systemd-timesync'1751vm-test-run-tribuchet> worker # [    8.767478] dbus-broker-launch[994]: Invalid user-name in /nix/store/gjkzk6znmw98rixqw7di2mknhim38bdj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1752vm-test-run-tribuchet> worker # [    8.779470] systemd[1]: Started D-Bus System Message Bus.1753vm-test-run-tribuchet> worker # [    8.812229] dbus-broker-launch[994]: Ready1754vm-test-run-tribuchet> worker # [    8.822380] systemd[1]: Condition check resulted in Virtio network device being skipped.1755vm-test-run-tribuchet> worker # [    8.841108] systemd[1]: Stopped target Host and Network Name Lookups.1756vm-test-run-tribuchet> worker # [    8.842889] systemd[1]: Stopping Host and Network Name Lookups...1757vm-test-run-tribuchet> worker # [    8.846353] systemd[1]: Stopped target User and Group Name Lookups.1758vm-test-run-tribuchet> worker # [    8.850352] systemd[1]: Stopping User and Group Name Lookups...1759vm-test-run-tribuchet> worker # [    8.851915] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1760vm-test-run-tribuchet> worker # [    8.854535] systemd[1]: nscd.service: Deactivated successfully.1761vm-test-run-tribuchet> worker # [    8.860062] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1762vm-test-run-tribuchet> worker # [    8.861672] systemd[1]: nscd.service: Consumed 18ms CPU time over 357ms wall clock time, 7.6M memory peak, 5.6M read from disk.1763vm-test-run-tribuchet> worker # [    8.883225] systemd-logind[1007]: New seat seat0.1764vm-test-run-tribuchet> worker # [    8.886670] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1765vm-test-run-tribuchet> worker # [    8.890299] systemd[1]: Started User Login Management.1766vm-test-run-tribuchet> worker # [    8.912116] systemd[1]: Starting linger-users.service...1767vm-test-run-tribuchet> worker # [    8.928379] systemd[1]: Finished resolvconf update.1768vm-test-run-tribuchet> worker # [    8.938068] systemd[1]: Reached target Preparation for Network.1769vm-test-run-tribuchet> worker # [    8.947798] systemd[1]: Starting DHCP Client...1770vm-test-run-tribuchet> worker # [    8.956469] nsncd[1044]: Jun 23 21:21:56.553 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1771vm-test-run-tribuchet> worker # [    8.961613] systemd[1]: Starting Address configuration of eth1...1772vm-test-run-tribuchet> worker # [    8.975284] systemd[1]: Starting Extra networking commands....1773vm-test-run-tribuchet> worker # [    8.979238] systemd[1]: Started Name Service Cache Daemon (nsncd).1774vm-test-run-tribuchet> worker # [    8.989510] systemd[1]: linger-users.service: Deactivated successfully.1775vm-test-run-tribuchet> worker # [    9.000409] systemd[1]: Finished linger-users.service.1776vm-test-run-tribuchet> worker # [    9.016281] systemd[1]: Reached target Host and Network Name Lookups.1777vm-test-run-tribuchet> worker # [    9.017959] systemd[1]: Reached target User and Group Name Lookups.1778vm-test-run-tribuchet> worker # [    9.059556] network-addresses-eth1-start[1060]: adding address 192.168.1.2/24... done1779vm-test-run-tribuchet> worker # [    9.070824] network-addresses-eth1-start[1060]: adding address 2001:db8:1::2/64... done1780vm-test-run-tribuchet> worker # [    9.092406] systemd[1]: Finished Address configuration of eth1.1781vm-test-run-tribuchet> worker # [    9.187253] systemd[1]: Finished Extra networking commands..1782vm-test-run-tribuchet> worker # [    9.188841] systemd[1]: Reached target Network.1783vm-test-run-tribuchet> worker # [    9.198432] systemd[1]: Starting Permit User Sessions...1784vm-test-run-tribuchet> worker # [    9.214378] dhcpcd[1084]: dhcpcd-10.3.2 starting1785vm-test-run-tribuchet> worker # [    9.221922] dhcpcd[1128]: dev: loaded udev1786vm-test-run-tribuchet> worker # [    9.486609] 8021q: 802.1Q VLAN Support v1.81787vm-test-run-tribuchet> worker # [    9.487723] 8021q: adding VLAN 0 to HW filter on device eth11788vm-test-run-tribuchet> worker # [    9.244377] systemd[1]: Finished Permit User Sessions.1789vm-test-run-tribuchet> worker # [    9.503064] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31790vm-test-run-tribuchet> worker # [    9.254412] systemd[1]: Started Getty on tty1.1791vm-test-run-tribuchet> worker # [    9.255729] systemd[1]: Reached target Login Prompts.1792vm-test-run-tribuchet> worker # [    9.604881] ACPI: button: Power Button [PWRF]1793vm-test-run-tribuchet> worker # [    9.628158] cfg80211: Loading compiled-in X.509 certificates for regulatory database1794vm-test-run-tribuchet> worker # [    9.632213] rtc_cmos 00:05: RTC can wake from S41795vm-test-run-tribuchet> worker # [    9.637316] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1796vm-test-run-tribuchet> worker # [    9.640343] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1797vm-test-run-tribuchet> worker # [    9.642526] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21798vm-test-run-tribuchet> worker # [    9.644622] cfg80211: failed to load regulatory.db1799vm-test-run-tribuchet> worker # [    9.646940] mousedev: PS/2 mouse device common for all mice1800vm-test-run-tribuchet> worker # [    9.656771] parport_pc 00:03: reported by Plug and Play ACPI1801vm-test-run-tribuchet> worker # [    9.662962] rtc_cmos 00:05: registered as rtc01802vm-test-run-tribuchet> worker # [    9.664241] rtc_cmos 00:05: setting system clock to 2026-06-23T21:21:57 UTC (1782249717)1803vm-test-run-tribuchet> worker # [    9.673961] Floppy drive(s): fd0 is 2.88M AMI BIOS1804vm-test-run-tribuchet> worker # [    9.687058] 8021q: adding VLAN 0 to HW filter on device eth01805vm-test-run-tribuchet> worker # [    9.437116] dhcpcd[1128]: eth0: waiting for carrier1806vm-test-run-tribuchet> worker # [    9.439351] dhcpcd[1128]: eth0: carrier acquired1807vm-test-run-tribuchet> worker # [    9.446505] dhcpcd[1128]: DUID 00:01:00:01:31:cd:b5:75:52:54:00:12:34:561808vm-test-run-tribuchet> worker # [    9.448313] dhcpcd[1128]: eth0: IAID 00:12:34:561809vm-test-run-tribuchet> worker # [    9.703597] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1810vm-test-run-tribuchet> worker # [    9.451490] dhcpcd[1128]: eth0: adding address fe80::5054:ff:fe12:34561811vm-test-run-tribuchet> worker # [    9.719459] FDC 0 is a S82078B1812vm-test-run-tribuchet> worker # [    9.723138] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1813vm-test-run-tribuchet> worker # [    9.741597] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console1814vm-test-run-tribuchet> worker # [    9.746089] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 01815vm-test-run-tribuchet> worker # [    9.756896] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1816vm-test-run-tribuchet> worker # [    9.791103] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input41817vm-test-run-tribuchet> worker # [    9.839541] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61818vm-test-run-tribuchet> worker # [    9.839975] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51819vm-test-run-tribuchet> worker # [    9.860080] Console: switching to colour dummy device 80x251820vm-test-run-tribuchet> worker # [    9.673426] systemd[1]: Starting Virtual Console Setup...1821vm-test-run-tribuchet> worker # [    9.970078] [drm] Found bochs VGA, ID 0xb0c5.1822vm-test-run-tribuchet> worker # [    9.970080] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1823vm-test-run-tribuchet> worker # [    9.699690] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1824vm-test-run-tribuchet> worker # [    9.983660] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic1825vm-test-run-tribuchet> worker # [    9.984972] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 01826vm-test-run-tribuchet> worker # [    9.770648] systemd-logind[1007]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1827vm-test-run-tribuchet> worker # [    9.802416] systemd-vconsole-setup[1147]: Configuration of first virtual console was skipped, ignoring remaining ones.1828vm-test-run-tribuchet> worker # [    9.807853] systemd[1]: Finished Virtual Console Setup.1829vm-test-run-tribuchet> worker # [   10.064914] ppdev: user-space parallel port driver1830vm-test-run-tribuchet> worker # [    9.824930] systemd-logind[1007]: Watching system buttons on /dev/input/event2 (Power Button)1831vm-test-run-tribuchet> worker # [    9.866692] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1832vm-test-run-tribuchet> worker # [    9.870212] systemd[1]: Stopped Virtual Console Setup.1833vm-test-run-tribuchet> worker # [    9.871674] systemd[1]: Stopping Virtual Console Setup...1834vm-test-run-tribuchet> worker # [    9.876182] systemd[1]: Starting Virtual Console Setup...1835vm-test-run-tribuchet> worker # [   10.112085] fbcon: bochs-drmdrmfb (fb0) is primary device1836vm-test-run-tribuchet> worker # [   10.178242] Console: switching to colour frame buffer device 160x501837vm-test-run-tribuchet> worker # [   10.191872] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device1838vm-test-run-tribuchet> worker # [    9.927845] systemd-logind[1007]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1839vm-test-run-tribuchet> worker # [    9.948335] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1840vm-test-run-tribuchet> worker # [    9.951165] systemd[1]: Stopped Virtual Console Setup.1841vm-test-run-tribuchet> worker # [    9.957947] systemd[1]: Starting Virtual Console Setup...1842vm-test-run-tribuchet> worker # [   10.003621] systemd-vconsole-setup[1153]: Configuration of first virtual console was skipped, ignoring remaining ones.1843vm-test-run-tribuchet> worker # [   10.009497] systemd[1]: Finished Virtual Console Setup.1844vm-test-run-tribuchet> worker # [   10.300261] kvm_amd: TSC scaling supported1845vm-test-run-tribuchet> worker # [   10.301211] kvm_amd: Nested Virtualization enabled1846vm-test-run-tribuchet> worker # [   10.302538] kvm_amd: Nested Paging enabled1847vm-test-run-tribuchet> worker # [   10.303519] kvm_amd: LBR virtualization supported1848vm-test-run-tribuchet> worker # [   10.304618] kvm_amd: Virtual VMLOAD VMSAVE supported1849vm-test-run-tribuchet> worker # [   10.305733] kvm_amd: Virtual GIF supported1850vm-test-run-tribuchet> worker # [   10.306674] kvm_amd: Virtual NMI enabled1851vm-test-run-tribuchet> worker # [   10.335760] EDAC MC: Ver: 3.0.01852vm-test-run-tribuchet> worker # [   10.401912] dhcpcd[1128]: eth0: soliciting a DHCP lease1853vm-test-run-tribuchet> worker # [   10.670556] NET: Registered PF_PACKET protocol family1854vm-test-run-tribuchet> worker # [   10.426367] dhcpcd[1128]: eth0: offered 10.0.2.15 from 10.0.2.21855vm-test-run-tribuchet> worker # [   10.430777] dhcpcd[1128]: eth0: probing address 10.0.2.15/241856vm-test-run-tribuchet> hub # [   18.628580] dhcpcd[1148]: eth0: leased 10.0.2.15 for 86400 seconds1857vm-test-run-tribuchet> hub # [   18.633914] dhcpcd[1148]: eth0: adding route to 10.0.2.0/241858vm-test-run-tribuchet> hub # [   18.638338] dhcpcd[1148]: eth0: adding default route via 10.0.2.21859vm-test-run-tribuchet> hub # [   18.788371] systemd[1]: Started DHCP Client.1860vm-test-run-tribuchet> hub # [   18.791234] systemd[1]: Reached target Multi-User System.1861vm-test-run-tribuchet> hub # [   18.793586] systemd[1]: Startup finished in 1.155s (kernel) + 5.414s (initrd) + 12.223s (userspace) = 18.793s.1862vm-test-run-tribuchet> hub: (finished: waiting for unit multi-user.target, in 12.60 seconds)1863vm-test-run-tribuchet> worker: waiting for unit multi-user.target1864vm-test-run-tribuchet> worker: waiting for the VM to finish booting1865vm-test-run-tribuchet> worker: Guest shell says: b'Spawning backdoor root shell...\n'1866vm-test-run-tribuchet> worker: connected to guest root shell1867vm-test-run-tribuchet> worker: (connecting took 0.00 seconds)1868vm-test-run-tribuchet> worker: (finished: waiting for the VM to finish booting, in 0.00 seconds)1869vm-test-run-tribuchet> worker # [   11.775767] dhcpcd[1128]: eth0: soliciting an IPv6 router1870vm-test-run-tribuchet> worker # [   11.780569] dhcpcd[1128]: eth0: Router Advertisement from fe80::21871vm-test-run-tribuchet> worker # [   11.784740] dhcpcd[1128]: eth0: adding address fec0::5054:ff:fe12:3456/641872vm-test-run-tribuchet> worker # [   11.789162] dhcpcd[1128]: eth0: adding route to fec0::/641873vm-test-run-tribuchet> worker # [   11.793282] dhcpcd[1128]: eth0: adding default route via fe80::21874vm-test-run-tribuchet> worker # [   15.123842] dhcpcd[1128]: eth0: leased 10.0.2.15 for 86400 seconds1875vm-test-run-tribuchet> worker # [   15.130345] dhcpcd[1128]: eth0: adding route to 10.0.2.0/241876vm-test-run-tribuchet> worker # [   15.134013] dhcpcd[1128]: eth0: adding default route via 10.0.2.21877vm-test-run-tribuchet> worker # [   15.205813] systemd[1]: Started DHCP Client.1878vm-test-run-tribuchet> worker # [   15.209273] systemd[1]: Reached target Multi-User System.1879vm-test-run-tribuchet> worker # [   15.211228] systemd[1]: Startup finished in 1.126s (kernel) + 4.559s (initrd) + 9.524s (userspace) = 15.210s.1880vm-test-run-tribuchet> worker: (finished: waiting for unit multi-user.target, in 4.31 seconds)1881vm-test-run-tribuchet> subtest: certificate authority1882vm-test-run-tribuchet> hub: must succeed: tribuchet ca init --dir /root/ca1883vm-test-run-tribuchet> hub: (finished: must succeed: tribuchet ca init --dir /root/ca, in 0.12 seconds)1884vm-test-run-tribuchet> hub: must succeed: tribuchet ca issue hub --dir /root/ca1885vm-test-run-tribuchet> hub: (finished: must succeed: tribuchet ca issue hub --dir /root/ca, in 0.04 seconds)1886vm-test-run-tribuchet> hub: must succeed: tribuchet ca issue worker --dir /root/ca1887vm-test-run-tribuchet> hub: (finished: must succeed: tribuchet ca issue worker --dir /root/ca, in 0.03 seconds)1888vm-test-run-tribuchet> hub: must succeed: mkdir -p /etc/tribuchet/ca1889vm-test-run-tribuchet> hub: (finished: must succeed: mkdir -p /etc/tribuchet/ca, in 0.04 seconds)1890vm-test-run-tribuchet> hub: must succeed: cp /root/ca/hub.crt /root/ca/hub.key /root/ca/ca.crt /etc/tribuchet/ca/1891vm-test-run-tribuchet> hub: (finished: must succeed: cp /root/ca/hub.crt /root/ca/hub.key /root/ca/ca.crt /etc/tribuchet/ca/, in 0.04 seconds)1892vm-test-run-tribuchet> worker: must succeed: mkdir -p /var/lib/tribuchet/tls1893vm-test-run-tribuchet> worker: (finished: must succeed: mkdir -p /var/lib/tribuchet/tls, in 0.02 seconds)1894vm-test-run-tribuchet> hub: must succeed: cat /root/ca/worker.crt1895vm-test-run-tribuchet> hub: (finished: must succeed: cat /root/ca/worker.crt, in 0.04 seconds)1896vm-test-run-tribuchet> worker: must succeed: cat > /var/lib/tribuchet/tls/worker.crt << 'PEMEOF'1897vm-test-run-tribuchet> -----BEGIN CERTIFICATE-----1898vm-test-run-tribuchet> MIIBVzCB/6ADAgECAhQQEiA3/G95DtQEwobymaUJzxBxNDAKBggqhkjOPQQDAjAh1899vm-test-run-tribuchet> MR8wHQYDVQQDDBZyY2dlbiBzZWxmIHNpZ25lZCBjZXJ0MB4XDTI2MDYyMzIxMjIw1900vm-test-run-tribuchet> M1oXDTI4MDYyMjIxMjIwM1owITEfMB0GA1UEAwwWcmNnZW4gc2VsZiBzaWduZWQg1901vm-test-run-tribuchet> Y2VydDBZMBMGByqGSM49AgEGCCqGSM49AwEHA0IABPJT4JkW9mEBYiV5QbHewnVk1902vm-test-run-tribuchet> MlzXAqtxZMinC1ZdjoMWG95Dl4VsHaZBoTquHhyEFr2dZpj//zoH3ZSC0SGfCqSj1903vm-test-run-tribuchet> FTATMBEGA1UdEQQKMAiCBndvcmtlcjAKBggqhkjOPQQDAgNHADBEAiBdyGIh0GIQ1904vm-test-run-tribuchet> 1ANlCpph9orMWDY8O0NrZTkeymzzHMerhAIgA6lOlDJ++LBfJfVKIPPBusy8XBmQ1905vm-test-run-tribuchet> cqTnh/NEymCF5Z8=1906vm-test-run-tribuchet> -----END CERTIFICATE-----1907vm-test-run-tribuchet> PEMEOF1908vm-test-run-tribuchet> worker: (finished: must succeed: cat > /var/lib/tribuchet/tls/worker.crt << 'PEMEOF'1909vm-test-run-tribuchet> -----BEGIN CERTIFICATE-----1910vm-test-run-tribuchet> MIIBVzCB/6ADAgECAhQQEiA3/G95DtQEwobymaUJzxBxNDAKBggqhkjOPQQDAjAh1911vm-test-run-tribuchet> MR8wHQYDVQQDDBZyY2dlbiBzZWxmIHNpZ25lZCBjZXJ0MB4XDTI2MDYyMzIxMjIw1912vm-test-run-tribuchet> M1oXDTI4MDYyMjIxMjIwM1owITEfMB0GA1UEAwwWcmNnZW4gc2VsZiBzaWduZWQg1913vm-test-run-tribuchet> Y2VydDBZMBMGByqGSM49AgEGCCqGSM49AwEHA0IABPJT4JkW9mEBYiV5QbHewnVk1914vm-test-run-tribuchet> MlzXAqtxZMinC1ZdjoMWG95Dl4VsHaZBoTquHhyEFr2dZpj//zoH3ZSC0SGfCqSj1915vm-test-run-tribuchet> FTATMBEGA1UdEQQKMAiCBndvcmtlcjAKBggqhkjOPQQDAgNHADBEAiBdyGIh0GIQ1916vm-test-run-tribuchet> 1ANlCpph9orMWDY8O0NrZTkeymzzHMerhAIgA6lOlDJ++LBfJfVKIPPBusy8XBmQ1917vm-test-run-tribuchet> cqTnh/NEymCF5Z8=1918vm-test-run-tribuchet> -----END CERTIFICATE-----1919vm-test-run-tribuchet> PEMEOF, in 0.03 seconds)1920vm-test-run-tribuchet> hub: must succeed: cat /root/ca/worker.key1921vm-test-run-tribuchet> hub: (finished: must succeed: cat /root/ca/worker.key, in 0.04 seconds)1922vm-test-run-tribuchet> worker: must succeed: cat > /var/lib/tribuchet/tls/worker.key << 'PEMEOF'1923vm-test-run-tribuchet> -----BEGIN PRIVATE KEY-----1924vm-test-run-tribuchet> MIGHAgEAMBMGByqGSM49AgEGCCqGSM49AwEHBG0wawIBAQQgoMPgu/SyEoxjLo/51925vm-test-run-tribuchet> ZOCWadOfJDE9/Vqj1waj8drjH0uhRANCAATyU+CZFvZhAWIleUGx3sJ1ZDJc1wKr1926vm-test-run-tribuchet> cWTIpwtWXY6DFhveQ5eFbB2mQaE6rh4chBa9nWaY//86B92UgtEhnwqk1927vm-test-run-tribuchet> -----END PRIVATE KEY-----1928vm-test-run-tribuchet> PEMEOF1929vm-test-run-tribuchet> worker: (finished: must succeed: cat > /var/lib/tribuchet/tls/worker.key << 'PEMEOF'1930vm-test-run-tribuchet> -----BEGIN PRIVATE KEY-----1931vm-test-run-tribuchet> MIGHAgEAMBMGByqGSM49AgEGCCqGSM49AwEHBG0wawIBAQQgoMPgu/SyEoxjLo/51932vm-test-run-tribuchet> ZOCWadOfJDE9/Vqj1waj8drjH0uhRANCAATyU+CZFvZhAWIleUGx3sJ1ZDJc1wKr1933vm-test-run-tribuchet> cWTIpwtWXY6DFhveQ5eFbB2mQaE6rh4chBa9nWaY//86B92UgtEhnwqk1934vm-test-run-tribuchet> -----END PRIVATE KEY-----1935vm-test-run-tribuchet> PEMEOF, in 0.02 seconds)1936vm-test-run-tribuchet> hub: must succeed: cat /root/ca/ca.crt1937vm-test-run-tribuchet> hub: (finished: must succeed: cat /root/ca/ca.crt, in 0.04 seconds)1938vm-test-run-tribuchet> worker: must succeed: cat > /var/lib/tribuchet/tls/ca.crt << 'PEMEOF'1939vm-test-run-tribuchet> -----BEGIN CERTIFICATE-----1940vm-test-run-tribuchet> MIIBjzCCATWgAwIBAgIUQvHwoWrA6ZJ8Kbe5vEgjfhDBEdMwCgYIKoZIzj0EAwIw1941vm-test-run-tribuchet> ITEfMB0GA1UEAwwWcmNnZW4gc2VsZiBzaWduZWQgY2VydDAeFw0yNjA2MjMyMTIy1942vm-test-run-tribuchet> MDNaFw0zNjA2MjAyMTIyMDNaMCExHzAdBgNVBAMMFnJjZ2VuIHNlbGYgc2lnbmVk1943vm-test-run-tribuchet> IGNlcnQwWTATBgcqhkjOPQIBBggqhkjOPQMBBwNCAASYwCXPpit2KzQGXygGFRwB1944vm-test-run-tribuchet> 2NHXK+2NIi52hpRdLV9EwZHefYE5naeOzYDORLmJcenQUPxtiNj+DblJZTM2iY/+1945vm-test-run-tribuchet> o0swSTAXBgNVHREEEDAOggx0cmlidWNoZXQtY2EwHQYDVR0OBBYEFHvmuD4YGvRj1946vm-test-run-tribuchet> 6ZmCJpnfswG8jWIhMA8GA1UdEwEB/wQFMAMBAf8wCgYIKoZIzj0EAwIDSAAwRQIg1947vm-test-run-tribuchet> QCN+3yir2Yyw6Ab0EqzBmnoAJizAcNwvjOoyOg6vFX0CIQCPR1mY0NesoolQWaJt1948vm-test-run-tribuchet> 0f3w+jfqmgP5NQKV6g656F2HZg==1949vm-test-run-tribuchet> -----END CERTIFICATE-----1950vm-test-run-tribuchet> PEMEOF1951vm-test-run-tribuchet> worker: (finished: must succeed: cat > /var/lib/tribuchet/tls/ca.crt << 'PEMEOF'1952vm-test-run-tribuchet> -----BEGIN CERTIFICATE-----1953vm-test-run-tribuchet> MIIBjzCCATWgAwIBAgIUQvHwoWrA6ZJ8Kbe5vEgjfhDBEdMwCgYIKoZIzj0EAwIw1954vm-test-run-tribuchet> ITEfMB0GA1UEAwwWcmNnZW4gc2VsZiBzaWduZWQgY2VydDAeFw0yNjA2MjMyMTIy1955vm-test-run-tribuchet> MDNaFw0zNjA2MjAyMTIyMDNaMCExHzAdBgNVBAMMFnJjZ2VuIHNlbGYgc2lnbmVk1956vm-test-run-tribuchet> IGNlcnQwWTATBgcqhkjOPQIBBggqhkjOPQMBBwNCAASYwCXPpit2KzQGXygGFRwB1957vm-test-run-tribuchet> 2NHXK+2NIi52hpRdLV9EwZHefYE5naeOzYDORLmJcenQUPxtiNj+DblJZTM2iY/+1958vm-test-run-tribuchet> o0swSTAXBgNVHREEEDAOggx0cmlidWNoZXQtY2EwHQYDVR0OBBYEFHvmuD4YGvRj1959vm-test-run-tribuchet> 6ZmCJpnfswG8jWIhMA8GA1UdEwEB/wQFMAMBAf8wCgYIKoZIzj0EAwIDSAAwRQIg1960vm-test-run-tribuchet> QCN+3yir2Yyw6Ab0EqzBmnoAJizAcNwvjOoyOg6vFX0CIQCPR1mY0NesoolQWaJt1961vm-test-run-tribuchet> 0f3w+jfqmgP5NQKV6g656F2HZg==1962vm-test-run-tribuchet> -----END CERTIFICATE-----1963vm-test-run-tribuchet> PEMEOF, in 0.03 seconds)1964vm-test-run-tribuchet> (finished: subtest: certificate authority, in 0.49 seconds)1965vm-test-run-tribuchet> subtest: worker registers at hub over mTLS1966vm-test-run-tribuchet> hub: must succeed: systemctl start tribuchet-hub.socket1967vm-test-run-tribuchet> hub # [   24.057765] systemd[1]: Starting tribuchet-hub.socket...1968vm-test-run-tribuchet> hub # [   24.065738] systemd[1]: Listening on tribuchet-hub.socket.1969vm-test-run-tribuchet> hub: (finished: must succeed: systemctl start tribuchet-hub.socket, in 0.06 seconds)1970vm-test-run-tribuchet> hub: must succeed: systemctl start tribuchet-hub1971vm-test-run-tribuchet> hub # [   24.113883] systemd[1]: Starting tribuchet-hub.service...1972vm-test-run-tribuchet> hub # [   24.170762] tribuchet[1338]: 2026-06-23T21:22:03.987894Z  WARN tribuchet::hub: no trusted-signing-keys file in /etc/tribuchet; accepting any signing key from transport-authenticated workers1973vm-test-run-tribuchet> hub # [   24.176367] tribuchet[1338]: 2026-06-23T21:22:03.994783Z  INFO tribuchet::sd: adopted activated sockets tcp=true unix=true1974vm-test-run-tribuchet> hub # [   24.191699] tribuchet[1338]: 2026-06-23T21:22:04.010217Z  INFO tribuchet::hub: hub running listen="0.0.0.0:7437" socket=/run/tribuchet/hub.sock1975vm-test-run-tribuchet> hub # [   24.196647] systemd[1]: Started tribuchet-hub.service.1976vm-test-run-tribuchet> hub: (finished: must succeed: systemctl start tribuchet-hub, in 0.13 seconds)1977vm-test-run-tribuchet> worker: must succeed: systemctl start tribuchet-worker1978vm-test-run-tribuchet> worker # [   16.701472] systemd[1]: Starting tribuchet-worker.service...1979vm-test-run-tribuchet> worker # [   16.750398] exec[1274]: 2026-06-23T21:22:04.839647Z  INFO tribuchet: worker configuration cfg=WorkerConfig { hub: "https://hub:7437", auth: Mtls, state_dir: "/var/lib/tribuchet", systems: [], ca_cert: "/var/lib/tribuchet/tls/ca.crt", cert: "/var/lib/tribuchet/tls/worker.crt", key: "/var/lib/tribuchet/tls/worker.key", build_timeout_secs: 86400, max_silent_time_secs: 0, max_log_size: 1048576, sandbox_bin_sh: None, build_memory_max_bytes: None, max_jobs: 2, auto_allocate_uids_base: 872415232, emulate: {"aarch64-linux": "/nix/store/d4ga0766s65xpbav600s64kc93w5cjsn-qemu-user-static-x86_64-unknown-linux-musl-11.0.1/bin/qemu-aarch64"}, pasta: None, recursive_nix: true }1980vm-test-run-tribuchet> worker # [   16.765345] exec[1274]: 2026-06-23T21:22:04.854764Z  INFO tribuchet::worker::cgroup: per-build cgroup limits enabled base=/sys/fs/cgroup/system.slice/tribuchet-worker.service1981vm-test-run-tribuchet> worker # [   16.772837] exec[1278]: 2026-06-23T21:22:04.861381Z  INFO tribuchet: worker configuration cfg=WorkerConfig { hub: "https://hub:7437", auth: Mtls, state_dir: "/var/lib/tribuchet", systems: [], ca_cert: "/var/lib/tribuchet/tls/ca.crt", cert: "/var/lib/tribuchet/tls/worker.crt", key: "/var/lib/tribuchet/tls/worker.key", build_timeout_secs: 86400, max_silent_time_secs: 0, max_log_size: 1048576, sandbox_bin_sh: None, build_memory_max_bytes: None, max_jobs: 2, auto_allocate_uids_base: 872415232, emulate: {"aarch64-linux": "/nix/store/d4ga0766s65xpbav600s64kc93w5cjsn-qemu-user-static-x86_64-unknown-linux-musl-11.0.1/bin/qemu-aarch64"}, pasta: None, recursive_nix: true }1982vm-test-run-tribuchet> worker # [   16.791557] systemd[1]: Started tribuchet-worker.service.1983vm-test-run-tribuchet> worker: (finished: must succeed: systemctl start tribuchet-worker, in 0.16 seconds)1984vm-test-run-tribuchet> hub: waiting for success: journalctl -u tribuchet-hub | grep -q 'worker registered'1985vm-test-run-tribuchet> hub # [   24.418399] tribuchet[1338]: 2026-06-23T21:22:04.236548Z  INFO tribuchet::hub: worker registered worker="worker" caps=[SystemCaps { system: "x86_64-linux", features: ["nixos-test", "benchmark", "big-parallel", "kvm", "uid-range", "recursive-nix"] }, SystemCaps { system: "aarch64-linux", features: ["nixos-test", "benchmark", "big-parallel"] }]1986vm-test-run-tribuchet> worker # [   16.870291] exec[1278]: 2026-06-23T21:22:04.959691Z  INFO tribuchet::worker: connected to hub hub="https://hub:7437" systems=["x86_64-linux", "aarch64-linux"]1987vm-test-run-tribuchet> hub: (finished: waiting for success: journalctl -u tribuchet-hub | grep -q 'worker registered', in 0.08 seconds)1988vm-test-run-tribuchet> (finished: subtest: worker registers at hub over mTLS, in 0.44 seconds)1989vm-test-run-tribuchet> subtest: nix-daemon builds remotely via external-builders1990vm-test-run-tribuchet> hub: must succeed: echo tribuchet-payload > /root/payload1991vm-test-run-tribuchet> hub: (finished: must succeed: echo tribuchet-payload > /root/payload, in 0.02 seconds)1992vm-test-run-tribuchet> hub: must succeed: nix-store --add /root/payload1993vm-test-run-tribuchet> hub: (finished: must succeed: nix-store --add /root/payload, in 0.16 seconds)1994vm-test-run-tribuchet> hub: must succeed: cat > /root/test.nix << 'NIXEOF'1995vm-test-run-tribuchet> let1996vm-test-run-tribuchet>   bash = builtins.storePath "/nix/store/cgjr3kj3hs7ngznyws5qfg16c8scpys0-bash-interactive-5.3p9";1997vm-test-run-tribuchet>   unique = builtins.storePath "/nix/store/kv4azcgci3hc0lfsihml40s2gl51h4jj-payload";1998vm-test-run-tribuchet> in derivation {1999vm-test-run-tribuchet>   name = "tt-remote-build";2000vm-test-run-tribuchet>   system = "x86_64-linux";2001vm-test-run-tribuchet>   builder = bash + "/bin/bash";2002vm-test-run-tribuchet>   args = [ "-c" ("read line < " + unique + "; echo \"$line built-remotely\" > $out") ];2003vm-test-run-tribuchet> }2004vm-test-run-tribuchet> NIXEOF2005vm-test-run-tribuchet> hub: (finished: must succeed: cat > /root/test.nix << 'NIXEOF'2006vm-test-run-tribuchet> let2007vm-test-run-tribuchet>   bash = builtins.storePath "/nix/store/cgjr3kj3hs7ngznyws5qfg16c8scpys0-bash-interactive-5.3p9";2008vm-test-run-tribuchet>   unique = builtins.storePath "/nix/store/kv4azcgci3hc0lfsihml40s2gl51h4jj-payload";2009vm-test-run-tribuchet> in derivation {2010vm-test-run-tribuchet>   name = "tt-remote-build";2011vm-test-run-tribuchet>   system = "x86_64-linux";2012vm-test-run-tribuchet>   builder = bash + "/bin/bash";2013vm-test-run-tribuchet>   args = [ "-c" ("read line < " + unique + "; echo \"$line built-remotely\" > $out") ];2014vm-test-run-tribuchet> }2015vm-test-run-tribuchet> NIXEOF, in 0.04 seconds)2016vm-test-run-tribuchet> hub: must succeed: nix-build /root/test.nix --no-out-link2017vm-test-run-tribuchet> hub # this derivation will be built:2018vm-test-run-tribuchet> hub #   /nix/store/b548jzwfih6nxcbfv5zhjhjfmvk4wyxl-tt-remote-build.drv2019vm-test-run-tribuchet> hub # building '/nix/store/b548jzwfih6nxcbfv5zhjhjfmvk4wyxl-tt-remote-build.drv'...2020vm-test-run-tribuchet> hub # [   24.999957] tribuchet[1338]: 2026-06-23T21:22:04.818511Z  INFO tribuchet::hub::submit: queueing build id="883dd9a5fa75c5025c1c91b04f261fe8" system="x86_64-linux"2021vm-test-run-tribuchet> hub # [   25.005532] tribuchet[1338]: 2026-06-23T21:22:04.824120Z  INFO tribuchet::hub: dispatching build id="883dd9a5fa75c5025c1c91b04f261fe8" worker="worker"2022vm-test-run-tribuchet> hub # tribuchet: building on worker2023vm-test-run-tribuchet> worker # [   17.452307] exec[1278]: 2026-06-23T21:22:05.541305Z  INFO tribuchet::worker: build assigned id="883dd9a5fa75c5025c1c91b04f261fe8"2024vm-test-run-tribuchet> worker # [   17.469490] systemd[1]: Started Nix Daemon.2025vm-test-run-tribuchet> worker # [   17.530646] nix-daemon[1282]: accepted connection from pid 1278, user root (trusted)2026vm-test-run-tribuchet> worker # [   17.537830] exec[1278]: 2026-06-23T21:22:05.627337Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2027vm-test-run-tribuchet> worker # [   17.543876] exec[1278]: 2026-06-23T21:22:05.633157Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2028vm-test-run-tribuchet> hub # [   25.110416] tribuchet[1338]: 2026-06-23T21:22:04.928873Z  INFO tribuchet::hub::relay: input path negotiation done id="883dd9a5fa75c5025c1c91b04f261fe8" total=8 missing=12029vm-test-run-tribuchet> hub # [   25.134439] systemd[1]: Started Nix Daemon.2030vm-test-run-tribuchet> hub # [   25.282770] nix-daemon[1368]: accepted connection from pid 1338, user root (trusted)2031vm-test-run-tribuchet> hub # [   25.291879] tribuchet[1338]: 2026-06-23T21:22:05.109602Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2032vm-test-run-tribuchet> hub # [   25.297970] tribuchet[1338]: 2026-06-23T21:22:05.116475Z  INFO harmonia_store_remote::client: Nix Version "2.34.7+3" version="2.34.7+3"2033vm-test-run-tribuchet> worker # [   17.977824] exec[1278]: 2026-06-23T21:22:06.066102Z  INFO tribuchet::worker::build: builder finished id="883dd9a5fa75c5025c1c91b04f261fe8" exit_code=02034vm-test-run-tribuchet> worker # [   17.990577] nix-daemon[1282]: accepted connection from pid 1278, user root (trusted)2035vm-test-run-tribuchet> worker # [   18.005180] exec[1278]: 2026-06-23T21:22:06.094398Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2036vm-test-run-tribuchet> worker # [   18.020755] exec[1278]: 2026-06-23T21:22:06.107987Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2037vm-test-run-tribuchet> worker # [   18.043304] exec[1278]: 2026-06-23T21:22:06.132661Z  INFO tribuchet::worker::resume: build result sent, awaiting ack id="883dd9a5fa75c5025c1c91b04f261fe8"2038vm-test-run-tribuchet> worker # [   18.053918] exec[1278]: 2026-06-23T21:22:06.143425Z  INFO tribuchet::worker::resume: build result acknowledged id="883dd9a5fa75c5025c1c91b04f261fe8"2039vm-test-run-tribuchet> hub: (finished: must succeed: nix-build /root/test.nix --no-out-link, in 1.00 seconds)2040vm-test-run-tribuchet> hub: must succeed: grep -q 'tribuchet-payload built-remotely' /nix/store/9r254480qy44pdnrs14h8iii65wklgap-tt-remote-build2041vm-test-run-tribuchet> hub: (finished: must succeed: grep -q 'tribuchet-payload built-remotely' /nix/store/9r254480qy44pdnrs14h8iii65wklgap-tt-remote-build, in 0.04 seconds)2042vm-test-run-tribuchet> (finished: subtest: nix-daemon builds remotely via external-builders, in 1.25 seconds)2043vm-test-run-tribuchet> subtest: hub restart: socket activation keeps clients connectable2044vm-test-run-tribuchet> hub: must succeed: systemctl restart tribuchet-hub2045vm-test-run-tribuchet> hub # [   25.735338] systemd[1]: Stopping tribuchet-hub.service...2046vm-test-run-tribuchet> hub # [   25.737882] tribuchet[1338]: 2026-06-23T21:22:05.556010Z  INFO tribuchet::hub: SIGTERM: exiting, builds resume against the replacement instance2047vm-test-run-tribuchet> hub # [   25.746591] systemd[1]: tribuchet-hub.service: Deactivated successfully.2048vm-test-run-tribuchet> hub # [   25.753110] systemd[1]: Stopped tribuchet-hub.service.2049vm-test-run-tribuchet> hub # [   25.760516] systemd[1]: Starting tribuchet-hub.service...2050vm-test-run-tribuchet> worker # [   18.182742] exec[1278]: 2026-06-23T21:22:06.272028Z  WARN tribuchet::worker: hub session ended: code: 'Unknown error', message: "h2 protocol error: error reading a body from connection", source: hyper::Error(Body, Error { kind: Io(Custom { kind: UnexpectedEof, error: "peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof" }) }): error reading a body from connection: peer closed connection without sending TLS close_notify: https://docs.rs/rustls/latest/rustls/manual/_03_howto/index.html#unexpected-eof2051vm-test-run-tribuchet> worker # [   18.210725] exec[1278]: 2026-06-23T21:22:06.299377Z  INFO tribuchet::worker: reconnecting to hub in 1s2052vm-test-run-tribuchet> hub # [   25.800739] tribuchet[1383]: 2026-06-23T21:22:05.619014Z  WARN tribuchet::hub: no trusted-signing-keys file in /etc/tribuchet; accepting any signing key from transport-authenticated workers2053vm-test-run-tribuchet> hub # [   25.806115] tribuchet[1383]: 2026-06-23T21:22:05.623901Z  INFO tribuchet::sd: adopted activated sockets tcp=true unix=true2054vm-test-run-tribuchet> hub # [   25.810919] tribuchet[1383]: 2026-06-23T21:22:05.624127Z  INFO tribuchet::hub: hub running listen="0.0.0.0:7437" socket=/run/tribuchet/hub.sock2055vm-test-run-tribuchet> hub # [   25.815260] systemd[1]: Started tribuchet-hub.service.2056vm-test-run-tribuchet> hub: (finished: must succeed: systemctl restart tribuchet-hub, in 0.12 seconds)2057vm-test-run-tribuchet> hub: must succeed: nix-build /root/test.nix --no-out-link 2>/dev/null2058vm-test-run-tribuchet> hub: (finished: must succeed: nix-build /root/test.nix --no-out-link 2>/dev/null, in 0.15 seconds)2059vm-test-run-tribuchet> hub: must succeed: grep -q 'tribuchet-payload built-remotely' /nix/store/9r254480qy44pdnrs14h8iii65wklgap-tt-remote-build2060vm-test-run-tribuchet> hub: (finished: must succeed: grep -q 'tribuchet-payload built-remotely' /nix/store/9r254480qy44pdnrs14h8iii65wklgap-tt-remote-build, in 0.04 seconds)2061vm-test-run-tribuchet> (finished: subtest: hub restart: socket activation keeps clients connectable, in 0.31 seconds)2062vm-test-run-tribuchet> subtest: restarting hub and worker mid-build cancels nothing2063vm-test-run-tribuchet> worker: must succeed: journalctl -u tribuchet-worker | grep -c 'build assigned' || true2064vm-test-run-tribuchet> worker: (finished: must succeed: journalctl -u tribuchet-worker | grep -c 'build assigned' || true, in 0.04 seconds)2065vm-test-run-tribuchet> hub: must succeed: rm -f /tmp/drain.ok && systemd-run --unit=drainbuild bash -lc 'nix-build /etc/tt/drain.nix --no-out-link > /tmp/drain.out && touch /tmp/drain.ok'2066vm-test-run-tribuchet> hub # [   26.104964] systemd[1]: Started [systemd-run] /run/current-system/sw/bin/bash -lc "nix-build /etc/tt/drain.nix --no-out-link > /tmp/drain.out && touch /tmp/drain.ok".2067vm-test-run-tribuchet> hub # Running as unit: drainbuild.service; invocation ID: 31a37b4381c3406199e791360789ea712068vm-test-run-tribuchet> hub: (finished: must succeed: rm -f /tmp/drain.ok && systemd-run --unit=drainbuild bash -lc 'nix-build /etc/tt/drain.nix --no-out-link > /tmp/drain.out && touch /tmp/drain.ok', in 0.08 seconds)2069vm-test-run-tribuchet> worker: waiting for success: [ $(journalctl -u tribuchet-worker | grep -c 'build assigned') -gt 1 ]2070vm-test-run-tribuchet> hub # [   26.276174] bash[1401]: this derivation will be built:2071vm-test-run-tribuchet> hub # [   26.278658] bash[1401]:   /nix/store/cfxlvqmqc3mdi0hlpcpj02kbmfy5knb9-tt-drain.drv2072vm-test-run-tribuchet> hub # [   26.417416] bash[1401]: building '/nix/store/cfxlvqmqc3mdi0hlpcpj02kbmfy5knb9-tt-drain.drv'...2073vm-test-run-tribuchet> hub # [   26.420101] tribuchet[1383]: 2026-06-23T21:22:06.238408Z  INFO tribuchet::hub::submit: no capable worker yet; waiting system="x86_64-linux"2074vm-test-run-tribuchet> worker # [   19.223614] exec[1278]: 2026-06-23T21:22:07.311919Z  INFO tribuchet::worker: connected to hub hub="https://hub:7437" systems=["x86_64-linux", "aarch64-linux"]2075vm-test-run-tribuchet> hub # [   26.783442] tribuchet[1383]: 2026-06-23T21:22:06.600631Z  INFO tribuchet::hub: worker registered worker="worker" caps=[SystemCaps { system: "x86_64-linux", features: ["nixos-test", "benchmark", "big-parallel", "kvm", "uid-range", "recursive-nix"] }, SystemCaps { system: "aarch64-linux", features: ["nixos-test", "benchmark", "big-parallel"] }]2076vm-test-run-tribuchet> worker # [   19.243614] exec[1278]: 2026-06-23T21:22:07.332737Z  INFO tribuchet::worker: build assigned id="90c2ee5ec8daa12e446fbdcb1b637090"2077vm-test-run-tribuchet> hub # [   26.803988] tribuchet[1383]: 2026-06-23T21:22:06.601247Z  INFO tribuchet::hub::submit: queueing build id="90c2ee5ec8daa12e446fbdcb1b637090" system="x86_64-linux"2078vm-test-run-tribuchet> worker # [   19.255881] nix-daemon[1282]: accepted connection from pid 1278, user root (trusted)2079vm-test-run-tribuchet> hub # [   26.815447] tribuchet[1383]: 2026-06-23T21:22:06.621178Z  INFO tribuchet::hub: dispatching build id="90c2ee5ec8daa12e446fbdcb1b637090" worker="worker"2080vm-test-run-tribuchet> hub # [   26.825821] bash[1401]: tribuchet: building on worker2081vm-test-run-tribuchet> worker # [   19.270110] exec[1278]: 2026-06-23T21:22:07.359410Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2082vm-test-run-tribuchet> worker # [   19.281571] exec[1278]: 2026-06-23T21:22:07.370465Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2083vm-test-run-tribuchet> hub # [   26.852898] tribuchet[1383]: 2026-06-23T21:22:06.671060Z  INFO tribuchet::hub::relay: input path negotiation done id="90c2ee5ec8daa12e446fbdcb1b637090" total=7 missing=02084vm-test-run-tribuchet> worker: (finished: waiting for success: [ $(journalctl -u tribuchet-worker | grep -c 'build assigned') -gt 1 ], in 1.08 seconds)2085vm-test-run-tribuchet> worker: must succeed: systemctl reload tribuchet-worker2086vm-test-run-tribuchet> worker # [   19.666092] systemd[1]: Reloading tribuchet-worker.service...2087vm-test-run-tribuchet> worker # [   19.711207] exec[1278]: 2026-06-23T21:22:07.800716Z  INFO tribuchet::worker: handover requested; exiting2088vm-test-run-tribuchet> worker # [   19.720405] systemd[1]: Reloaded tribuchet-worker.service.2089vm-test-run-tribuchet> hub # [   27.278211] tribuchet[1383]: 2026-06-23T21:22:07.096248Z  INFO tribuchet::hub: worker disconnected worker="worker"2090vm-test-run-tribuchet> worker: (finished: must succeed: systemctl reload tribuchet-worker, in 0.08 seconds)2091vm-test-run-tribuchet> hub: must succeed: systemctl restart --no-block tribuchet-hub2092vm-test-run-tribuchet> hub # [   27.287435] tribuchet[1383]: 2026-06-23T21:22:07.105474Z  WARN tribuchet::hub: worker session lost; requeueing build: worker disconnected or went silent id="90c2ee5ec8daa12e446fbdcb1b637090"2093vm-test-run-tribuchet> hub # [   27.356489] tribuchet[1383]: 2026-06-23T21:22:07.174851Z  INFO tribuchet::hub: SIGTERM: exiting, builds resume against the replacement instance2094vm-test-run-tribuchet> hub # [   27.362600] systemd[1]: Stopping tribuchet-hub.service...2095vm-test-run-tribuchet> hub # [   27.366261] bash[1401]: tribuchet: hub connection lost (event stream: code: 'Unknown error', message: "h2 protocol error: error reading a body from connection", source: hyper::Error(Body, Error { kind: Io(Custom { kind: BrokenPipe, error: "stream closed because of a broken pipe" }) }): error reading a body from connection: stream closed because of a broken pipe); reconnecting2096vm-test-run-tribuchet> hub # [   27.374313] systemd[1]: tribuchet-hub.service: Deactivated successfully.2097vm-test-run-tribuchet> hub: (finished: must succeed: systemctl restart --no-block tribuchet-hub, in 0.09 seconds)2098vm-test-run-tribuchet> hub: waiting for success: test -f /tmp/drain.ok2099vm-test-run-tribuchet> hub # [   27.376778] systemd[1]: Stopped tribuchet-hub.service.2100vm-test-run-tribuchet> hub # [   27.384198] systemd[1]: Starting tribuchet-hub.service...2101vm-test-run-tribuchet> hub # [   27.423865] tribuchet[1416]: 2026-06-23T21:22:07.241932Z  WARN tribuchet::hub: no trusted-signing-keys file in /etc/tribuchet; accepting any signing key from transport-authenticated workers2102vm-test-run-tribuchet> hub # [   27.428866] tribuchet[1416]: 2026-06-23T21:22:07.241985Z  INFO tribuchet::sd: adopted activated sockets tcp=true unix=true2103vm-test-run-tribuchet> hub # [   27.433437] tribuchet[1416]: 2026-06-23T21:22:07.242183Z  INFO tribuchet::hub: hub running listen="0.0.0.0:7437" socket=/run/tribuchet/hub.sock2104vm-test-run-tribuchet> hub # [   27.437755] systemd[1]: Started tribuchet-hub.service.2105vm-test-run-tribuchet> worker # [   20.921251] exec[1330]: 2026-06-23T21:22:09.010133Z  INFO tribuchet: worker configuration cfg=WorkerConfig { hub: "https://hub:7437", auth: Mtls, state_dir: "/var/lib/tribuchet", systems: [], ca_cert: "/var/lib/tribuchet/tls/ca.crt", cert: "/var/lib/tribuchet/tls/worker.crt", key: "/var/lib/tribuchet/tls/worker.key", build_timeout_secs: 86400, max_silent_time_secs: 0, max_log_size: 1048576, sandbox_bin_sh: None, build_memory_max_bytes: None, max_jobs: 2, auto_allocate_uids_base: 872415232, emulate: {"aarch64-linux": "/nix/store/d4ga0766s65xpbav600s64kc93w5cjsn-qemu-user-static-x86_64-unknown-linux-musl-11.0.1/bin/qemu-aarch64"}, pasta: None, recursive_nix: true }2106vm-test-run-tribuchet> worker # [   20.936065] exec[1330]: 2026-06-23T21:22:09.025535Z  INFO tribuchet::worker::resume: adopted running build id="90c2ee5ec8daa12e446fbdcb1b637090" pid=13082107vm-test-run-tribuchet> worker # [   20.941250] nix-daemon[1282]: accepted connection from pid 1330, user root (trusted)2108vm-test-run-tribuchet> worker # [   20.950537] exec[1330]: 2026-06-23T21:22:09.040035Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2109vm-test-run-tribuchet> worker # [   20.957334] exec[1330]: 2026-06-23T21:22:09.046713Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2110vm-test-run-tribuchet> worker # [   20.972944] exec[1330]: 2026-06-23T21:22:09.062436Z  INFO tribuchet::worker: connected to hub hub="https://hub:7437" systems=["x86_64-linux", "aarch64-linux"]2111vm-test-run-tribuchet> hub # [   28.533873] tribuchet[1416]: 2026-06-23T21:22:08.350562Z  INFO tribuchet::hub: worker registered worker="worker" caps=[SystemCaps { system: "x86_64-linux", features: ["nixos-test", "benchmark", "big-parallel", "kvm", "uid-range", "recursive-nix"] }, SystemCaps { system: "aarch64-linux", features: ["nixos-test", "benchmark", "big-parallel"] }]2112vm-test-run-tribuchet> hub # [   29.369859] tribuchet[1416]: 2026-06-23T21:22:09.188009Z  INFO tribuchet::hub::submit: queueing build id="abd35fe6fe535a363661fa1242053bc2" system="x86_64-linux"2113vm-test-run-tribuchet> worker # [   21.820910] exec[1330]: 2026-06-23T21:22:09.910378Z  INFO tribuchet::worker: build resumed id="abd35fe6fe535a363661fa1242053bc2" key="6c1f3268178682bcc5fc0c49319c6710436c087b8cdd9b82d736e09458808a35"2114vm-test-run-tribuchet> hub # [   29.382537] tribuchet[1416]: 2026-06-23T21:22:09.199271Z  INFO tribuchet::hub: dispatching build id="abd35fe6fe535a363661fa1242053bc2" worker="worker"2115vm-test-run-tribuchet> hub # [   29.394761] bash[1401]: tribuchet: building on worker2116vm-test-run-tribuchet> hub # [   29.398613] tribuchet[1416]: 2026-06-23T21:22:09.211833Z  INFO tribuchet::hub::relay: worker resumed an in-flight build id="abd35fe6fe535a363661fa1242053bc2"2117vm-test-run-tribuchet> worker # [   39.254430] exec[1330]: 2026-06-23T21:22:27.342317Z  INFO tribuchet::worker::resume: build result sent, awaiting ack id="abd35fe6fe535a363661fa1242053bc2"2118vm-test-run-tribuchet> worker # [   39.268372] exec[1330]: 2026-06-23T21:22:27.357776Z  INFO tribuchet::worker::resume: build result acknowledged id="abd35fe6fe535a363661fa1242053bc2"2119vm-test-run-tribuchet> hub # [   46.875582] systemd[1]: drainbuild.service: Deactivated successfully.2120vm-test-run-tribuchet> hub: (finished: waiting for success: test -f /tmp/drain.ok, in 20.11 seconds)2121vm-test-run-tribuchet> hub: must succeed: cat /tmp/drain.out2122vm-test-run-tribuchet> hub: (finished: must succeed: cat /tmp/drain.out, in 0.04 seconds)2123vm-test-run-tribuchet> hub: must succeed: grep -q drained-not-cancelled /nix/store/0z2yir1fxzxflvyjmdnjyvqzcr4i3svv-tt-drain2124vm-test-run-tribuchet> hub: (finished: must succeed: grep -q drained-not-cancelled /nix/store/0z2yir1fxzxflvyjmdnjyvqzcr4i3svv-tt-drain, in 0.03 seconds)2125vm-test-run-tribuchet> worker: waiting for success: systemctl is-active tribuchet-worker2126vm-test-run-tribuchet> worker: (finished: waiting for success: systemctl is-active tribuchet-worker, in 0.04 seconds)2127vm-test-run-tribuchet> hub: waiting for success: systemctl is-active tribuchet-hub2128vm-test-run-tribuchet> hub: (finished: waiting for success: systemctl is-active tribuchet-hub, in 0.05 seconds)2129vm-test-run-tribuchet> (finished: subtest: restarting hub and worker mid-build cancels nothing, in 21.65 seconds)2130vm-test-run-tribuchet> subtest: resubmitting a previously resumed derivation builds again2131vm-test-run-tribuchet> hub: must succeed: nix-build /etc/tt/drain.nix --no-out-link --check2132vm-test-run-tribuchet> hub # checking outputs of '/nix/store/cfxlvqmqc3mdi0hlpcpj02kbmfy5knb9-tt-drain.drv'...2133vm-test-run-tribuchet> hub # [   47.824677] tribuchet[1416]: 2026-06-23T21:22:27.642507Z  INFO tribuchet::hub::submit: queueing build id="df3b796bceaf05c2543137c6079359b8" system="x86_64-linux"2134vm-test-run-tribuchet> hub # [   47.829184] tribuchet[1416]: 2026-06-23T21:22:27.642579Z  INFO tribuchet::hub: dispatching build id="df3b796bceaf05c2543137c6079359b8" worker="worker"2135vm-test-run-tribuchet> hub # tribuchet: building on worker2136vm-test-run-tribuchet> worker # [   40.265977] exec[1330]: 2026-06-23T21:22:28.354198Z  INFO tribuchet::worker: build assigned id="df3b796bceaf05c2543137c6079359b8"2137vm-test-run-tribuchet> worker # [   40.278592] nix-daemon[1282]: accepted connection from pid 1330, user root (trusted)2138vm-test-run-tribuchet> worker # [   40.292612] exec[1330]: 2026-06-23T21:22:28.381967Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2139vm-test-run-tribuchet> worker # [   40.307296] exec[1330]: 2026-06-23T21:22:28.396102Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2140vm-test-run-tribuchet> hub # [   47.880629] tribuchet[1416]: 2026-06-23T21:22:27.699099Z  INFO tribuchet::hub::relay: input path negotiation done id="df3b796bceaf05c2543137c6079359b8" total=7 missing=02141vm-test-run-tribuchet> worker # [   60.160377] exec[1330]: 2026-06-23T21:22:48.247883Z  INFO tribuchet::worker::build: builder finished id="df3b796bceaf05c2543137c6079359b8" exit_code=02142vm-test-run-tribuchet> worker # [   60.173548] nix-daemon[1282]: accepted connection from pid 1330, user root (trusted)2143vm-test-run-tribuchet> worker # [   60.187633] exec[1330]: 2026-06-23T21:22:48.277031Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2144vm-test-run-tribuchet> worker # [   60.203394] exec[1330]: 2026-06-23T21:22:48.291277Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2145vm-test-run-tribuchet> worker # [   60.222203] exec[1330]: 2026-06-23T21:22:48.310220Z  INFO tribuchet::worker::resume: build result sent, awaiting ack id="df3b796bceaf05c2543137c6079359b8"2146vm-test-run-tribuchet> worker # [   60.229861] exec[1330]: 2026-06-23T21:22:48.319372Z  INFO tribuchet::worker::resume: build result acknowledged id="df3b796bceaf05c2543137c6079359b8"2147vm-test-run-tribuchet> hub: (finished: must succeed: nix-build /etc/tt/drain.nix --no-out-link --check, in 20.17 seconds)2148vm-test-run-tribuchet> (finished: subtest: resubmitting a previously resumed derivation builds again, in 20.17 seconds)2149vm-test-run-tribuchet> subtest: max-log-size applies to a build adopted across a reload2150vm-test-run-tribuchet> worker: must succeed: journalctl -u tribuchet-worker | grep -c 'build assigned' || true2151vm-test-run-tribuchet> worker: (finished: must succeed: journalctl -u tribuchet-worker | grep -c 'build assigned' || true, in 0.01 seconds)2152vm-test-run-tribuchet> hub: must succeed: systemd-run --unit=slowlogbuild -p StandardOutput=file:/tmp/slowlog.out -p StandardError=file:/tmp/slowlog.out bash -lc 'nix-build /etc/tt/slowlog.nix --no-out-link'2153vm-test-run-tribuchet> hub # [   67.897285] systemd[1]: Started [systemd-run] /run/current-system/sw/bin/bash -lc "nix-build /etc/tt/slowlog.nix --no-out-link".2154vm-test-run-tribuchet> hub # Running as unit: slowlogbuild.service; invocation ID: 7f8cb853a258440bafadfd07482fd7f12155vm-test-run-tribuchet> hub: (finished: must succeed: systemd-run --unit=slowlogbuild -p StandardOutput=file:/tmp/slowlog.out -p StandardError=file:/tmp/slowlog.out bash -lc 'nix-build /etc/tt/slowlog.nix --no-out-link', in 0.07 seconds)2156vm-test-run-tribuchet> worker: waiting for success: [ $(journalctl -u tribuchet-worker | grep -c 'build assigned') -gt 3 ]2157vm-test-run-tribuchet> hub # [   68.213373] tribuchet[1416]: 2026-06-23T21:22:48.031163Z  INFO tribuchet::hub::submit: queueing build id="f2aec3909be239a7350ffda454181334" system="x86_64-linux"2158vm-test-run-tribuchet> hub # [   68.217925] tribuchet[1416]: 2026-06-23T21:22:48.031246Z  INFO tribuchet::hub: dispatching build id="f2aec3909be239a7350ffda454181334" worker="worker"2159vm-test-run-tribuchet> worker # [   60.653718] exec[1330]: 2026-06-23T21:22:48.742811Z  INFO tribuchet::worker: build assigned id="f2aec3909be239a7350ffda454181334"2160vm-test-run-tribuchet> worker # [   60.665252] nix-daemon[1282]: accepted connection from pid 1330, user root (trusted)2161vm-test-run-tribuchet> worker # [   60.679294] exec[1330]: 2026-06-23T21:22:48.768710Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2162vm-test-run-tribuchet> worker # [   60.694113] exec[1330]: 2026-06-23T21:22:48.782950Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2163vm-test-run-tribuchet> hub # [   68.267703] tribuchet[1416]: 2026-06-23T21:22:48.086180Z  INFO tribuchet::hub::relay: input path negotiation done id="f2aec3909be239a7350ffda454181334" total=7 missing=02164vm-test-run-tribuchet> worker: (finished: waiting for success: [ $(journalctl -u tribuchet-worker | grep -c 'build assigned') -gt 3 ], in 1.08 seconds)2165vm-test-run-tribuchet> worker: must succeed: systemctl reload tribuchet-worker2166vm-test-run-tribuchet> worker # [   61.456282] systemd[1]: Reloading tribuchet-worker.service...2167vm-test-run-tribuchet> worker # [   61.501676] exec[1330]: 2026-06-23T21:22:49.590893Z  INFO tribuchet::worker: handover requested; exiting2168vm-test-run-tribuchet> worker # [   61.505490] systemd[1]: Reloaded tribuchet-worker.service.2169vm-test-run-tribuchet> worker: (finished: must succeed: systemctl reload tribuchet-worker, in 0.08 seconds)2170vm-test-run-tribuchet> hub: waiting for success: grep -q 'exceeded the limit' /tmp/slowlog.out2171vm-test-run-tribuchet> hub # [   69.071795] tribuchet[1416]: 2026-06-23T21:22:48.889638Z  INFO tribuchet::hub: worker disconnected worker="worker"2172vm-test-run-tribuchet> hub # [   69.080505] tribuchet[1416]: 2026-06-23T21:22:48.889930Z  WARN tribuchet::hub: worker session lost; requeueing build: worker disconnected or went silent id="f2aec3909be239a7350ffda454181334"2173vm-test-run-tribuchet> worker # [   62.712940] exec[1395]: 2026-06-23T21:22:50.802097Z  INFO tribuchet: worker configuration cfg=WorkerConfig { hub: "https://hub:7437", auth: Mtls, state_dir: "/var/lib/tribuchet", systems: [], ca_cert: "/var/lib/tribuchet/tls/ca.crt", cert: "/var/lib/tribuchet/tls/worker.crt", key: "/var/lib/tribuchet/tls/worker.key", build_timeout_secs: 86400, max_silent_time_secs: 0, max_log_size: 1048576, sandbox_bin_sh: None, build_memory_max_bytes: None, max_jobs: 2, auto_allocate_uids_base: 872415232, emulate: {"aarch64-linux": "/nix/store/d4ga0766s65xpbav600s64kc93w5cjsn-qemu-user-static-x86_64-unknown-linux-musl-11.0.1/bin/qemu-aarch64"}, pasta: None, recursive_nix: true }2174vm-test-run-tribuchet> worker # [   62.728937] exec[1395]: 2026-06-23T21:22:50.818206Z  INFO tribuchet::worker::resume: adopted running build id="f2aec3909be239a7350ffda454181334" pid=13732175vm-test-run-tribuchet> worker # [   62.734605] nix-daemon[1282]: accepted connection from pid 1395, user root (trusted)2176vm-test-run-tribuchet> worker # [   62.743551] exec[1395]: 2026-06-23T21:22:50.833028Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2177vm-test-run-tribuchet> worker # [   62.750086] exec[1395]: 2026-06-23T21:22:50.838812Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2178vm-test-run-tribuchet> worker # [   62.762900] exec[1395]: 2026-06-23T21:22:50.852391Z  INFO tribuchet::worker: connected to hub hub="https://hub:7437" systems=["x86_64-linux", "aarch64-linux"]2179vm-test-run-tribuchet> hub # [   70.324436] tribuchet[1416]: 2026-06-23T21:22:50.141618Z  INFO tribuchet::hub: worker registered worker="worker" caps=[SystemCaps { system: "x86_64-linux", features: ["nixos-test", "benchmark", "big-parallel", "kvm", "uid-range", "recursive-nix"] }, SystemCaps { system: "aarch64-linux", features: ["nixos-test", "benchmark", "big-parallel"] }]2180vm-test-run-tribuchet> worker # [   62.782271] exec[1395]: 2026-06-23T21:22:50.871789Z  INFO tribuchet::worker: build resumed id="f2aec3909be239a7350ffda454181334" key="0f6d1f80f9c7dbf8487d90837e57bb24764fa258716daa9972653ce517a6c1ba"2181vm-test-run-tribuchet> hub # [   70.344437] tribuchet[1416]: 2026-06-23T21:22:50.141929Z  INFO tribuchet::hub: dispatching build id="f2aec3909be239a7350ffda454181334" worker="worker"2182vm-test-run-tribuchet> hub # [   70.357739] tribuchet[1416]: 2026-06-23T21:22:50.176235Z  INFO tribuchet::hub::relay: worker resumed an in-flight build id="f2aec3909be239a7350ffda454181334"2183vm-test-run-tribuchet> worker # [   75.265449] exec[1395]: 2026-06-23T21:23:03.353988Z  INFO tribuchet::worker::resume: build result sent, awaiting ack id="f2aec3909be239a7350ffda454181334"2184vm-test-run-tribuchet> worker # [   75.281420] exec[1395]: 2026-06-23T21:23:03.370838Z  INFO tribuchet::worker::resume: build result acknowledged id="f2aec3909be239a7350ffda454181334"2185vm-test-run-tribuchet> hub # [   82.883626] systemd[1]: slowlogbuild.service: Main process exited, code=exited, status=100/n/a2186vm-test-run-tribuchet> hub # [   82.890515] systemd[1]: slowlogbuild.service: Failed with result 'exit-code'.2187vm-test-run-tribuchet> hub: (finished: waiting for success: grep -q 'exceeded the limit' /tmp/slowlog.out, in 14.03 seconds)2188vm-test-run-tribuchet> (finished: subtest: max-log-size applies to a build adopted across a reload, in 15.28 seconds)2189vm-test-run-tribuchet> subtest: worker reload mid-build re-adopts the running build2190vm-test-run-tribuchet> worker: must succeed: journalctl -u tribuchet-worker | grep -c 'build assigned' || true2191vm-test-run-tribuchet> worker: (finished: must succeed: journalctl -u tribuchet-worker | grep -c 'build assigned' || true, in 0.04 seconds)2192vm-test-run-tribuchet> worker: must succeed: journalctl -u tribuchet-worker | grep -c 'adopted running build' || true2193vm-test-run-tribuchet> worker: (finished: must succeed: journalctl -u tribuchet-worker | grep -c 'adopted running build' || true, in 0.03 seconds)2194vm-test-run-tribuchet> hub: must succeed: rm -f /tmp/reload.ok && systemd-run --unit=reloadbuild bash -lc 'nix-build /etc/tt/reload.nix --no-out-link > /tmp/reload.out && touch /tmp/reload.ok'2195vm-test-run-tribuchet> hub # [   83.246925] systemd[1]: Started [systemd-run] /run/current-system/sw/bin/bash -lc "nix-build /etc/tt/reload.nix --no-out-link > /tmp/reload.out && touch /tmp/reload.ok".2196vm-test-run-tribuchet> hub # Running as unit: reloadbuild.service; invocation ID: 7fd3323886ed4b489693465295030d152197vm-test-run-tribuchet> hub: (finished: must succeed: rm -f /tmp/reload.ok && systemd-run --unit=reloadbuild bash -lc 'nix-build /etc/tt/reload.nix --no-out-link > /tmp/reload.out && touch /tmp/reload.ok', in 0.09 seconds)2198vm-test-run-tribuchet> worker: waiting for success: [ $(journalctl -u tribuchet-worker | grep -c 'build assigned') -gt 4 ]2199vm-test-run-tribuchet> hub # [   83.389857] bash[1604]: this derivation will be built:2200vm-test-run-tribuchet> hub # [   83.392342] bash[1604]:   /nix/store/v90kf03dqa2idmg4p5mad4d6jc0ldzdz-tt-reload.drv2201vm-test-run-tribuchet> hub # [   83.525825] bash[1604]: building '/nix/store/v90kf03dqa2idmg4p5mad4d6jc0ldzdz-tt-reload.drv'...2202vm-test-run-tribuchet> hub # [   83.529206] tribuchet[1416]: 2026-06-23T21:23:03.346997Z  INFO tribuchet::hub::submit: queueing build id="7b4db04724beb0b96fe8cfe5c6a9e534" system="x86_64-linux"2203vm-test-run-tribuchet> hub # [   83.533662] tribuchet[1416]: 2026-06-23T21:23:03.347065Z  INFO tribuchet::hub: dispatching build id="7b4db04724beb0b96fe8cfe5c6a9e534" worker="worker"2204vm-test-run-tribuchet> hub # [   83.537811] bash[1604]: tribuchet: building on worker2205vm-test-run-tribuchet> worker # [   75.969649] exec[1395]: 2026-06-23T21:23:04.058699Z  INFO tribuchet::worker: build assigned id="7b4db04724beb0b96fe8cfe5c6a9e534"2206vm-test-run-tribuchet> worker # [   75.982130] nix-daemon[1282]: accepted connection from pid 1395, user root (trusted)2207vm-test-run-tribuchet> worker # [   75.996703] exec[1395]: 2026-06-23T21:23:04.086009Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2208vm-test-run-tribuchet> worker # [   76.011277] exec[1395]: 2026-06-23T21:23:04.100451Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2209vm-test-run-tribuchet> hub # [   83.585175] tribuchet[1416]: 2026-06-23T21:23:03.403563Z  INFO tribuchet::hub::relay: input path negotiation done id="7b4db04724beb0b96fe8cfe5c6a9e534" total=7 missing=02210vm-test-run-tribuchet> worker: (finished: waiting for success: [ $(journalctl -u tribuchet-worker | grep -c 'build assigned') -gt 4 ], in 1.08 seconds)2211vm-test-run-tribuchet> worker: must succeed: cp --remove-destination $(readlink -f /etc/tribuchet/worker.toml) /etc/tribuchet/worker.toml && sed -i 's/max-jobs = 2/max-jobs = 3/' /etc/tribuchet/worker.toml2212vm-test-run-tribuchet> worker: (finished: must succeed: cp --remove-destination $(readlink -f /etc/tribuchet/worker.toml) /etc/tribuchet/worker.toml && sed -i 's/max-jobs = 2/max-jobs = 3/' /etc/tribuchet/worker.toml, in 0.03 seconds)2213vm-test-run-tribuchet> worker: must succeed: systemctl reload tribuchet-worker2214vm-test-run-tribuchet> worker # [   76.827233] systemd[1]: Reloading tribuchet-worker.service...2215vm-test-run-tribuchet> worker # [   76.875425] systemd[1]: Reloaded tribuchet-worker.service.2216vm-test-run-tribuchet> worker # [   76.877838] exec[1395]: 2026-06-23T21:23:04.966637Z  INFO tribuchet::worker: handover requested; exiting2217vm-test-run-tribuchet> worker: (finished: must succeed: systemctl reload tribuchet-worker, in 0.08 seconds)2218vm-test-run-tribuchet> hub: waiting for success: test -f /tmp/reload.ok2219vm-test-run-tribuchet> hub # [   84.444154] tribuchet[1416]: 2026-06-23T21:23:04.262006Z  INFO tribuchet::hub: worker disconnected worker="worker"2220vm-test-run-tribuchet> hub # [   84.452326] tribuchet[1416]: 2026-06-23T21:23:04.262105Z  WARN tribuchet::hub: worker session lost; requeueing build: worker disconnected or went silent id="7b4db04724beb0b96fe8cfe5c6a9e534"2221vm-test-run-tribuchet> worker # [   78.080931] exec[1451]: 2026-06-23T21:23:06.170092Z  INFO tribuchet: worker configuration cfg=WorkerConfig { hub: "https://hub:7437", auth: Mtls, state_dir: "/var/lib/tribuchet", systems: [], ca_cert: "/var/lib/tribuchet/tls/ca.crt", cert: "/var/lib/tribuchet/tls/worker.crt", key: "/var/lib/tribuchet/tls/worker.key", build_timeout_secs: 86400, max_silent_time_secs: 0, max_log_size: 1048576, sandbox_bin_sh: None, build_memory_max_bytes: None, max_jobs: 3, auto_allocate_uids_base: 872415232, emulate: {"aarch64-linux": "/nix/store/d4ga0766s65xpbav600s64kc93w5cjsn-qemu-user-static-x86_64-unknown-linux-musl-11.0.1/bin/qemu-aarch64"}, pasta: None, recursive_nix: true }2222vm-test-run-tribuchet> worker # [   78.096969] exec[1451]: 2026-06-23T21:23:06.186051Z  INFO tribuchet::worker::resume: adopted running build id="7b4db04724beb0b96fe8cfe5c6a9e534" pid=14232223vm-test-run-tribuchet> worker # [   78.102807] nix-daemon[1282]: accepted connection from pid 1451, user root (trusted)2224vm-test-run-tribuchet> worker # [   78.111733] exec[1451]: 2026-06-23T21:23:06.201235Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2225vm-test-run-tribuchet> worker # [   78.117642] exec[1451]: 2026-06-23T21:23:06.206928Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2226vm-test-run-tribuchet> worker # [   78.134061] exec[1451]: 2026-06-23T21:23:06.223392Z  INFO tribuchet::worker: connected to hub hub="https://hub:7437" systems=["x86_64-linux", "aarch64-linux"]2227vm-test-run-tribuchet> worker # [   78.138370] exec[1451]: 2026-06-23T21:23:06.223504Z  INFO tribuchet::worker: build resumed id="7b4db04724beb0b96fe8cfe5c6a9e534" key="4f276ce87109028c0b268f13a9e8dec596545f756d6b32fd6955753abca29ae3"2228vm-test-run-tribuchet> hub # [   85.694936] tribuchet[1416]: 2026-06-23T21:23:05.510898Z  INFO tribuchet::hub: worker registered worker="worker" caps=[SystemCaps { system: "x86_64-linux", features: ["nixos-test", "benchmark", "big-parallel", "kvm", "uid-range", "recursive-nix"] }, SystemCaps { system: "aarch64-linux", features: ["nixos-test", "benchmark", "big-parallel"] }]2229vm-test-run-tribuchet> hub # [   85.714507] tribuchet[1416]: 2026-06-23T21:23:05.511933Z  INFO tribuchet::hub: dispatching build id="7b4db04724beb0b96fe8cfe5c6a9e534" worker="worker"2230vm-test-run-tribuchet> hub # [   85.724962] tribuchet[1416]: 2026-06-23T21:23:05.531882Z  INFO tribuchet::hub::relay: worker resumed an in-flight build id="7b4db04724beb0b96fe8cfe5c6a9e534"2231vm-test-run-tribuchet> hub # [   85.735694] bash[1604]: tribuchet: building on worker2232vm-test-run-tribuchet> hub # [   98.521687] bash[1604]: log-after-reload2233vm-test-run-tribuchet> worker # [   91.160955] exec[1451]: 2026-06-23T21:23:19.249333Z  INFO tribuchet::worker::resume: build result sent, awaiting ack id="7b4db04724beb0b96fe8cfe5c6a9e534"2234vm-test-run-tribuchet> worker # [   91.176645] exec[1451]: 2026-06-23T21:23:19.266057Z  INFO tribuchet::worker::resume: build result acknowledged id="7b4db04724beb0b96fe8cfe5c6a9e534"2235vm-test-run-tribuchet> hub # [   98.786739] systemd[1]: reloadbuild.service: Deactivated successfully.2236vm-test-run-tribuchet> hub: (finished: waiting for success: test -f /tmp/reload.ok, in 14.78 seconds)2237vm-test-run-tribuchet> hub: must succeed: cat /tmp/reload.out2238vm-test-run-tribuchet> hub: (finished: must succeed: cat /tmp/reload.out, in 0.04 seconds)2239vm-test-run-tribuchet> hub: must succeed: grep -q reload-survived /nix/store/6p5gz1myhil4n76y3yv14syr4rk9sfmz-tt-reload2240vm-test-run-tribuchet> hub: (finished: must succeed: grep -q reload-survived /nix/store/6p5gz1myhil4n76y3yv14syr4rk9sfmz-tt-reload, in 0.02 seconds)2241vm-test-run-tribuchet> worker: must succeed: [ $(journalctl -u tribuchet-worker | grep -c 'adopted running build') -gt 2 ]2242vm-test-run-tribuchet> worker: (finished: must succeed: [ $(journalctl -u tribuchet-worker | grep -c 'adopted running build') -gt 2 ], in 0.04 seconds)2243vm-test-run-tribuchet> hub: must succeed: journalctl -u reloadbuild | grep -q log-after-reload2244vm-test-run-tribuchet> hub: (finished: must succeed: journalctl -u reloadbuild | grep -q log-after-reload, in 0.03 seconds)2245vm-test-run-tribuchet> worker: must succeed: journalctl -u tribuchet-worker | grep -q 'max_jobs: 3'2246vm-test-run-tribuchet> worker: (finished: must succeed: journalctl -u tribuchet-worker | grep -q 'max_jobs: 3', in 0.03 seconds)2247vm-test-run-tribuchet> (finished: subtest: worker reload mid-build re-adopts the running build, in 16.30 seconds)2248vm-test-run-tribuchet> subtest: killing the client cancels the build on the worker2249vm-test-run-tribuchet> hub: must succeed: systemd-run --unit=cancelbuild bash -lc 'nix-build /etc/tt/cancel.nix --no-out-link'2250vm-test-run-tribuchet> hub # [   99.434715] systemd[1]: Started [systemd-run] /run/current-system/sw/bin/bash -lc "nix-build /etc/tt/cancel.nix --no-out-link".2251vm-test-run-tribuchet> hub # Running as unit: cancelbuild.service; invocation ID: d94f0dcad7244863acb32e68cffb4ccf2252vm-test-run-tribuchet> hub: (finished: must succeed: systemd-run --unit=cancelbuild bash -lc 'nix-build /etc/tt/cancel.nix --no-out-link', in 0.05 seconds)2253vm-test-run-tribuchet> worker: waiting for success: pgrep -f 'cancel-marker-runnin[g]'2254vm-test-run-tribuchet> hub # [   99.584825] bash[1691]: this derivation will be built:2255vm-test-run-tribuchet> hub # [   99.586363] bash[1691]:   /nix/store/vkw5gm4iv0xvgwq4j83ajnw7yqg6zggy-tt-cancel.drv2256vm-test-run-tribuchet> hub # [   99.717624] bash[1691]: building '/nix/store/vkw5gm4iv0xvgwq4j83ajnw7yqg6zggy-tt-cancel.drv'...2257vm-test-run-tribuchet> hub # [   99.720990] tribuchet[1416]: 2026-06-23T21:23:19.539068Z  INFO tribuchet::hub::submit: queueing build id="04a8768df300b69bb61d4998e72fa50e" system="x86_64-linux"2258vm-test-run-tribuchet> hub # [   99.725687] tribuchet[1416]: 2026-06-23T21:23:19.539140Z  INFO tribuchet::hub: dispatching build id="04a8768df300b69bb61d4998e72fa50e" worker="worker"2259vm-test-run-tribuchet> hub # [   99.729793] bash[1691]: tribuchet: building on worker2260vm-test-run-tribuchet> worker # [   92.162617] exec[1451]: 2026-06-23T21:23:20.250824Z  INFO tribuchet::worker: build assigned id="04a8768df300b69bb61d4998e72fa50e"2261vm-test-run-tribuchet> worker # [   92.174866] nix-daemon[1282]: accepted connection from pid 1451, user root (trusted)2262vm-test-run-tribuchet> worker # [   92.189462] exec[1451]: 2026-06-23T21:23:20.278819Z  INFO harmonia_store_remote::client: Client Version is 1.38, server version is 1.38 version=ProtocolVersion(1, 38) server_version=ProtocolVersion(1, 38) features={}2263vm-test-run-tribuchet> worker # [   92.204259] exec[1451]: 2026-06-23T21:23:20.292974Z  INFO harmonia_store_remote::client: Nix Version "2.34.7" version="2.34.7"2264vm-test-run-tribuchet> hub # [   99.777709] tribuchet[1416]: 2026-06-23T21:23:19.596238Z  INFO tribuchet::hub::relay: input path negotiation done id="04a8768df300b69bb61d4998e72fa50e" total=7 missing=02265vm-test-run-tribuchet> hub # [   99.893774] bash[1691]: cancel-marker-running2266vm-test-run-tribuchet> hub # [   99.896778] bash[1691]: still running 02267vm-test-run-tribuchet> hub # [  100.494944] bash[1691]: still running 12268vm-test-run-tribuchet> worker: (finished: waiting for success: pgrep -f 'cancel-marker-runnin[g]', in 1.09 seconds)2269vm-test-run-tribuchet> hub: must succeed: systemctl kill --signal=SIGKILL cancelbuild2270vm-test-run-tribuchet> hub # [  100.596661] systemd[1]: cancelbuild.service: Sent signal SIGKILL to main process 1691 (nix-build) on client request.2271vm-test-run-tribuchet> hub # [  100.602823] systemd[1]: cancelbuild.service: Killed unit cgroup '/system.slice/cancelbuild.service' with SIGKILL on client request.2272vm-test-run-tribuchet> hub: (finished: must succeed: systemctl kill --signal=SIGKILL cancelbuild, in 0.07 seconds)2273vm-test-run-tribuchet> worker: waiting for success: ! pgrep -f 'cancel-marker-runnin[g]'2274vm-test-run-tribuchet> hub # [  100.622702] systemd[1]: cancelbuild.service: Main process exited, code=killed, status=9/KILL2275vm-test-run-tribuchet> hub # [  100.625842] systemd[1]: cancelbuild.service: Failed with result 'signal'.