tribuchet: building on jamie Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script start all VMs machine: starting vm machine: QEMU running (pid 46) machine # Disk image does not exist, creating the virtualisation disk image... machine # Formatting '/build/vm-state-machine/tmp.e62lEMALog', fmt=raw size=1073741824 (finished: start all VMs, in 0.23 seconds) machine # mke2fs 1.47.4 (6-Mar-2025) machine: waiting for unit vault.service machine # Discarding device blocks: 0/262144 done machine: waiting for the VM to finish booting machine # Creating filesystem with 262144 4k blocks and 65536 inodes machine # Filesystem UUID: 09db12ed-9e17-4fb5-ad83-7a5afdca3273 machine # Superblock backups stored on blocks: machine # 32768, 98304, 163840, 229376 machine # machine # Allocating group tables: 0/8 done machine # Writing inode tables: 0/8 done machine # Creating journal (8192 blocks): done machine # Writing superblocks and filesystem accounting information: 0/8 done machine # machine # Virtualisation disk image created. machine # Starting virtiofs daemons... machine # [2026-09-15T20:06:42Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-15T20:06:42Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-15T20:06:42Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-15T20:06:42Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-15T20:06:42Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-15T20:06:42Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-15T20:06:42Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) machine # [2026-09-15T20:06:42Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether machine # [2026-09-15T20:06:42Z INFO virtiofsd] Waiting for vhost-user socket connection... machine # [2026-09-15T20:06:42Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-15T20:06:42Z INFO virtiofsd] Client connected, servicing requests machine # [2026-09-15T20:06:42Z INFO virtiofsd] Client connected, servicing requests machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) machine # machine # machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA00 machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)... machine # machine # machine # machine # machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB00 machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)... machine # machine # machine # Booting from ROM... machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026 machine # [ 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/x2xjw51wh4rcckidvj571z1xnxawj42j-nixos-system-machine-test/init regInfo=/nix/store/5f7jck25n4338a7gc7pnn1ic4f808p3h-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.000000] BIOS-provided physical RAM map: machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved machine # [ 0.000000] NX (Execute Disable) protection: active machine # [ 0.000000] APIC: Static calls initialized machine # [ 0.000000] SMBIOS 2.8 present. machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 machine # [ 0.000000] DMI: Memory slots populated: 1/1 machine # [ 0.000000] Hypervisor detected: KVM machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine # [ 0.000000] kvm-clock: using sched offset of 467175706 cycles machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine # [ 0.000004] tsc: Detected 2400.012 MHz processor machine # [ 0.000809] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000 machine # [ 0.000835] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs machine # [ 0.000838] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT machine # [ 0.002723] found SMP MP-table at [mem 0x000f5450-0x000f545f] machine # [ 0.002734] Using GB pages for direct mapping machine # [ 0.002833] RAMDISK: [mem 0x3e36b000-0x3ffcffff] machine # [ 0.002840] ACPI: Early table checksum verification disabled machine # [ 0.002843] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) machine # [ 0.002847] ACPI: RSDT 0x000000003FFE2539 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002851] ACPI: FACP 0x000000003FFE2319 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002858] ACPI: DSDT 0x000000003FFE0040 0022D9 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002860] ACPI: FACS 0x000000003FFE0000 000040 machine # [ 0.002861] ACPI: APIC 0x000000003FFE240D 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002863] ACPI: HPET 0x000000003FFE249D 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002864] ACPI: MCFG 0x000000003FFE24D5 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002866] ACPI: WAET 0x000000003FFE2511 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) machine # [ 0.002867] ACPI: Reserving FACP table memory at [mem 0x3ffe2319-0x3ffe240c] machine # [ 0.002868] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2318] machine # [ 0.002869] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] machine # [ 0.002869] ACPI: Reserving APIC table memory at [mem 0x3ffe240d-0x3ffe249c] machine # [ 0.002870] ACPI: Reserving HPET table memory at [mem 0x3ffe249d-0x3ffe24d4] machine # [ 0.002870] ACPI: Reserving MCFG table memory at [mem 0x3ffe24d5-0x3ffe2510] machine # [ 0.002871] ACPI: Reserving WAET table memory at [mem 0x3ffe2511-0x3ffe2538] machine # [ 0.003091] No NUMA configuration found machine # [ 0.003092] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff] machine # [ 0.003095] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff] machine # [ 0.005245] Zone ranges: machine # [ 0.005245] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine # [ 0.005247] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff] machine # [ 0.005248] Normal empty machine # [ 0.005248] Device empty machine # [ 0.005249] Movable zone start for each node machine # [ 0.005250] Early memory node ranges machine # [ 0.005250] node 0: [mem 0x0000000000001000-0x000000000009efff] machine # [ 0.005251] node 0: [mem 0x0000000000100000-0x000000003ffd7fff] machine # [ 0.005252] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff] machine # [ 0.005270] On node 0, zone DMA: 1 pages in unavailable ranges machine # [ 0.005522] On node 0, zone DMA: 97 pages in unavailable ranges machine # [ 0.022678] On node 0, zone DMA32: 40 pages in unavailable ranges machine # [ 0.023126] ACPI: PM-Timer IO Port: 0x608 machine # [ 0.023135] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine # [ 0.023165] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine # [ 0.023168] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine # [ 0.023169] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine # [ 0.023170] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine # [ 0.023171] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine # [ 0.023172] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine # [ 0.023174] ACPI: Using ACPI (MADT) for SMP configuration information machine # [ 0.023175] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine # [ 0.023178] TSC deadline timer available machine # [ 0.023182] CPU topo: Max. logical packages: 1 machine # [ 0.023183] CPU topo: Max. logical dies: 1 machine # [ 0.023183] CPU topo: Max. dies per package: 1 machine # [ 0.023187] CPU topo: Max. threads per core: 1 machine # [ 0.023187] CPU topo: Num. cores per package: 4 machine # [ 0.023187] CPU topo: Num. threads per package: 4 machine # [ 0.023188] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs machine # [ 0.023204] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() machine # [ 0.023218] kvm-guest: KVM setup pv remote TLB flush machine # [ 0.023220] kvm-guest: setup PV sched yield machine # [ 0.023233] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] machine # [ 0.023235] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] machine # [ 0.023236] [mem 0x40000000-0xafffffff] available for PCI devices machine # [ 0.023238] Booting paravirtualized kernel on KVM machine # [ 0.023241] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine # [ 0.027665] setup_percpu: NR_CPUS:384 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 machine # [ 0.029792] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u524288 machine # [ 0.029835] kvm-guest: PV spinlocks enabled machine # [ 0.029836] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) machine # [ 0.029839] 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/x2xjw51wh4rcckidvj571z1xnxawj42j-nixos-system-machine-test/init regInfo=/nix/store/5f7jck25n4338a7gc7pnn1ic4f808p3h-closure-info/registration console=ttyS0,115200n8 console=tty0 machine # [ 0.029927] Unknown kernel command line parameters "regInfo=/nix/store/5f7jck25n4338a7gc7pnn1ic4f808p3h-closure-info/registration", will be passed to user space. machine # [ 0.029940] random: crng init done machine # [ 0.029940] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes machine # [ 0.030980] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) machine # [ 0.031505] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) machine # [ 0.031570] Fallback order for Node 0: 0 machine # [ 0.031573] Built 1 zonelists, mobility grouping on. Total pages: 262006 machine # [ 0.031574] Policy zone: DMA32 machine # [ 0.034085] mem auto-init: stack:all(zero), heap alloc:on, heap free:off machine # [ 0.036450] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 machine # [ 0.038632] allocated 2097152 bytes of page_ext machine # [ 0.047910] ftrace: allocating 48736 entries in 192 pages machine # [ 0.047912] ftrace: allocated 192 pages with 2 groups machine # [ 0.048702] Dynamic Preempt: lazy machine # [ 0.048862] rcu: Preemptible hierarchical RCU implementation. machine # [ 0.048863] rcu: RCU event tracing is enabled. machine # [ 0.048863] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4. machine # [ 0.048864] Trampoline variant of Tasks RCU enabled. machine # [ 0.048865] Rude variant of Tasks RCU enabled. machine # [ 0.048865] Tracing variant of Tasks RCU enabled. machine # [ 0.048866] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. machine # [ 0.048867] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 machine # [ 0.048885] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.048887] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.048888] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. machine # [ 0.053253] NR_IRQS: 24832, nr_irqs: 456, preallocated irqs: 16 machine # [ 0.053557] rcu: srcu_init: Setting srcu_struct sizes based on contention. machine # [ 0.053563] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine # [ 0.053671] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) machine # [ 0.057248] Console: colour VGA+ 80x25 machine # [ 0.057251] printk: legacy console [tty0] enabled machine # [ 0.087878] printk: legacy console [ttyS0] enabled machine # [ 0.195321] ACPI: Core revision 20250807 machine # [ 0.196225] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine # [ 0.197822] APIC: Switch to symmetric I/O mode setup machine # [ 0.198890] x2apic enabled machine # [ 0.199650] APIC: Switched APIC routing to: physical x2apic machine # [ 0.200629] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() machine # [ 0.201929] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() machine # [ 0.203455] kvm-guest: setup PV IPIs machine # [ 0.205062] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine # [ 0.206130] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.207933] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012) machine # [ 0.209016] x86/cpu: User Mode Instruction Prevention (UMIP) activated machine # [ 0.210054] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 machine # [ 0.211688] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 machine # [ 0.212934] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto machine # [ 0.213931] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl machine # [ 0.214931] Transient Scheduler Attacks: Vulnerable: No microcode machine # [ 0.216730] Spectre V2 : Mitigation: Enhanced / Automatic IBRS machine # [ 0.217693] Speculative Return Stack Overflow: Mitigation: Safe RET machine # [ 0.218744] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization machine # [ 0.219938] Spectre V2 : Enabling IBPB for BPF machine # [ 0.220931] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier machine # [ 0.221931] active return thunk: srso_alias_return_thunk machine # [ 0.222952] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' machine # [ 0.223931] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' machine # [ 0.224930] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' machine # [ 0.225929] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' machine # [ 0.226931] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' machine # [ 0.227930] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' machine # [ 0.228930] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' machine # [ 0.229931] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 machine # [ 0.231732] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 machine # [ 0.232726] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 machine # [ 0.233930] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 machine # [ 0.234929] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 machine # [ 0.235930] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. machine # [ 0.265176] Freeing SMP alternatives memory: 44K machine # [ 0.265933] pid_max: default: 32768 minimum: 301 machine # [ 0.266941] LSM: initializing lsm=capability,landlock,yama,bpf,ima machine # [ 0.268023] landlock: Up and running. machine # [ 0.268677] Yama: becoming mindful. machine # [ 0.269145] LSM support for eBPF active machine # [ 0.270049] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.270950] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) machine # [ 0.274357] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1) machine # [ 0.275534] Performance Events: Fam17h+ core perfctr, AMD PMU driver. machine # [ 0.275940] ... version: 2 machine # [ 0.276727] ... bit width: 48 machine # [ 0.276933] ... generic counters: 6 machine # [ 0.277701] ... generic bitmap: 000000000000003f machine # [ 0.277933] ... fixed-purpose counters: 0 machine # [ 0.278705] ... fixed-purpose bitmap: 0000000000000000 machine # [ 0.278933] ... value mask: 0000ffffffffffff machine # [ 0.279867] ... max period: 00007fffffffffff machine # [ 0.280663] ... global_ctrl mask: 000000000000003f machine # [ 0.281034] signal: max sigframe size: 3376 machine # [ 0.281853] rcu: Hierarchical SRCU implementation. machine # [ 0.282597] rcu: Max phase no-delay instances is 400. machine # [ 0.283146] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level machine # [ 0.288429] smp: Bringing up secondary CPUs ... machine # [ 0.289328] smpboot: x86: Booting SMP configuration: machine # [ 0.289936] .... node #0, CPUs: #1 #2 #3 machine # [ 0.293064] smp: Brought up 1 node, 4 CPUs machine # [ 0.294648] smpboot: Total of 4 processors activated (19200.09 BogoMIPS) machine # [ 0.295279] Memory: 938172K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 100804K reserved, 0K cma-reserved) machine # [ 0.296934] devtmpfs: initialized machine # [ 0.298053] x86/mm: Memory block size: 128MB machine # [ 0.300084] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) machine # [ 0.301019] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). machine # [ 0.302025] pinctrl core: initialized pinctrl subsystem machine # [ 0.303181] PM: RTC time: 20:06:42, date: 2026-09-15 machine # [ 0.306687] NET: Registered PF_NETLINK/PF_ROUTE protocol family machine # [ 0.307279] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations machine # [ 0.307950] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations machine # [ 0.309072] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations machine # [ 0.309943] audit: initializing netlink subsys (disabled) machine # [ 0.310992] audit: type=2000 audit(1789502802.960:1): state=initialized audit_enabled=0 res=1 machine # [ 0.311267] thermal_sys: Registered thermal governor 'fair_share' machine # [ 0.312933] thermal_sys: Registered thermal governor 'bang_bang' machine # [ 0.313933] thermal_sys: Registered thermal governor 'step_wise' machine # [ 0.314933] thermal_sys: Registered thermal governor 'user_space' machine # [ 0.315933] thermal_sys: Registered thermal governor 'power_allocator' machine # [ 0.316946] cpuidle: using governor menu machine # [ 0.319130] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 machine # [ 0.320201] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] machine # [ 0.320936] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry machine # [ 0.321946] PCI: Using configuration type 1 for base access machine # [ 0.323084] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. machine # [ 0.325361] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages machine # [ 0.326872] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page machine # [ 0.327791] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages machine # [ 0.328843] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page machine # [ 0.333916] ACPI: Added _OSI(Module Device) machine # [ 0.335940] ACPI: Added _OSI(Processor Device) machine # [ 0.337939] ACPI: Added _OSI(Processor Aggregator Device) machine # [ 0.346551] ACPI: 1 ACPI AML tables successfully acquired and loaded machine # [ 0.355536] ACPI: Interpreter enabled machine # [ 0.357991] ACPI: PM: (supports S0 S3 S4 S5) machine # [ 0.359940] ACPI: Using IOAPIC for interrupt routing machine # [ 0.362162] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine # [ 0.365939] PCI: Using E820 reservations for host bridge windows machine # [ 0.368349] ACPI: Enabled 2 GPEs in block 00 to 3F machine # [ 0.381669] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine # [ 0.382946] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] machine # [ 0.386092] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] machine # [ 0.389198] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] machine # [ 0.391723] PCI host bridge to bus 0000:00 machine # [ 0.393964] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine # [ 0.395938] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine # [ 0.397947] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine # [ 0.399937] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] machine # [ 0.402937] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] machine # [ 0.404934] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] machine # [ 0.405934] pci_bus 0000:00: root bus resource [bus 00-ff] machine # [ 0.407038] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint machine # [ 0.409401] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint machine # [ 0.417028] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] machine # [ 0.417945] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] machine # [ 0.418954] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] machine # [ 0.420107] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine # [ 0.424370] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.434974] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f] machine # [ 0.436955] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] machine # [ 0.439994] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] machine # [ 0.442954] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] machine # [ 0.446318] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.455064] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] machine # [ 0.457956] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] machine # [ 0.459993] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] machine # [ 0.464318] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint machine # [ 0.473979] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] machine # [ 0.475949] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] machine # [ 0.477981] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] machine # [ 0.482357] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint machine # [ 0.490121] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f] machine # [ 0.492957] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] machine # [ 0.494995] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] machine # [ 0.497955] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref] machine # [ 0.503173] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint machine # [ 0.511008] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] machine # [ 0.513995] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] machine # [ 0.518447] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.524988] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] machine # [ 0.526994] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] machine # [ 0.531298] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.538022] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] machine # [ 0.539980] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] machine # [ 0.543009] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint machine # [ 0.547793] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] machine # [ 0.550000] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] machine # [ 0.552716] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint machine # [ 0.556982] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf] machine # [ 0.557880] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] machine # [ 0.559713] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] machine # [ 0.561362] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint machine # [ 0.575973] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f] machine # [ 0.576939] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff] machine # [ 0.577955] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] machine # [ 0.580523] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.582961] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f] machine # [ 0.584386] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.586951] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf] machine # [ 0.588044] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint machine # [ 0.589798] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df] machine # [ 0.590884] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint machine # [ 0.593731] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff] machine # [ 0.595232] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint machine # [ 0.596329] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO machine # [ 0.598208] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint machine # [ 0.600961] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff] machine # [ 0.601939] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff] machine # [ 0.603254] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint machine # [ 0.605656] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] machine # [ 0.609979] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 machine # [ 0.611042] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 machine # [ 0.612036] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 machine # [ 0.613035] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 machine # [ 0.614051] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 machine # [ 0.615040] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 machine # [ 0.617032] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 machine # [ 0.618036] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 machine # [ 0.618968] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 machine # [ 0.619946] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 machine # [ 0.620950] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 machine # [ 0.622240] ACPI: PCI: Interrupt link GSID configured for IRQ 19 machine # [ 0.624980] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 machine # [ 0.626972] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 machine # [ 0.629970] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 machine # [ 0.631968] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 machine # [ 0.639138] iommu: Default domain type: Translated machine # [ 0.640939] iommu: DMA domain TLB invalidation policy: lazy mode machine # [ 0.645138] ACPI: bus type USB registered machine # [ 0.647065] usbcore: registered new interface driver usbfs machine # [ 0.648967] usbcore: registered new interface driver hub machine # [ 0.650961] usbcore: registered new device driver usb machine # [ 0.655885] NetLabel: Initializing machine # [ 0.657938] NetLabel: domain hash size = 128 machine # [ 0.659938] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO machine # [ 0.662094] NetLabel: unlabeled traffic allowed by default machine # [ 0.663963] PCI: Using ACPI for IRQ routing machine # [ 0.724218] pci 0000:00:01.0: vgaarb: setting as boot VGA device machine # [ 0.724929] pci 0000:00:01.0: vgaarb: bridge control possible machine # [ 0.724929] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none machine # [ 0.727940] vgaarb: loaded machine # [ 0.728653] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine # [ 0.728933] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine # [ 0.733021] clocksource: Switched to clocksource kvm-clock machine # [ 0.737446] VFS: Disk quotas dquot_6.6.0 machine # [ 0.738182] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine # [ 0.739697] pnp: PnP ACPI init machine # [ 0.740513] ACPI: IRQ 4 override to edge(!), high(!) machine # [ 0.741505] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved machine # [ 0.743128] pnp: PnP ACPI: found 5 devices machine # [ 0.752646] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine # [ 0.754247] clocksource: Switched to clocksource acpi_pm machine # [ 0.755275] NET: Registered PF_INET protocol family machine # [ 0.756400] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) machine # [ 0.773172] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) machine # [ 0.774690] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) machine # [ 0.776066] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) machine # [ 0.777455] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) machine # [ 0.778732] TCP: Hash tables configured (established 8192 bind 8192) machine # [ 0.779908] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) machine # [ 0.781221] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.782323] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) machine # [ 0.783569] NET: Registered PF_UNIX/PF_LOCAL protocol family machine # [ 0.784601] NET: Registered PF_XDP protocol family machine # [ 0.785483] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] machine # [ 0.786517] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] machine # [ 0.787548] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] machine # [ 0.788673] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] machine # [ 0.789798] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] machine # [ 0.790888] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] machine # [ 0.792698] ACPI: \_SB_.GSIA: Enabled at IRQ 16 machine # [ 0.795154] ACPI: \_SB_.GSIB: Enabled at IRQ 17 machine # [ 0.797187] ACPI: \_SB_.GSIC: Enabled at IRQ 18 machine # [ 0.799191] ACPI: \_SB_.GSID: Enabled at IRQ 19 machine # [ 0.800970] PCI: CLS 0 bytes, default 64 machine # [ 0.801845] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns machine # [ 0.802169] Trying to unpack rootfs image as initramfs... machine # [ 0.821138] Initialise system trusted keyrings machine # [ 0.822400] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine # [ 0.833707] Key type asymmetric registered machine # [ 0.834468] Asymmetric key parser 'x509' registered machine # [ 0.835397] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) machine # [ 0.837000] io scheduler mq-deadline registered machine # [ 0.837777] io scheduler kyber registered machine # [ 0.841080] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine # [ 0.842448] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine # [ 0.844786] Linux agpgart interface v0.103 machine # [ 0.845554] ACPI: bus type drm_connector registered machine # [ 0.848246] usbcore: registered new interface driver usbserial_generic machine # [ 0.849347] usbserial: USB Serial support registered for generic machine # [ 0.850351] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS. machine # [ 0.850351] Please enable it if your BIOS has the CPPC option. machine # [ 0.852695] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled machine # [ 0.854048] drop_monitor: Initializing network drop monitor service machine # [ 0.855254] NET: Registered PF_INET6 protocol family machine # [ 0.857079] Segment Routing with IPv6 machine # [ 0.857773] In-situ OAM (IOAM) with IPv6 machine # [ 0.859932] IPI shorthand broadcast: enabled machine # [ 0.864231] sched_clock: Marking stable (717014319, 146366589)->(892267449, -28886541) machine # [ 0.865968] registered taskstats version 1 machine # [ 0.866959] Loading compiled-in X.509 certificates machine # [ 0.876082] Demotion targets for Node 0: null machine # [ 0.877148] Key type .fscrypt registered machine # [ 0.877842] Key type fscrypt-provisioning registered machine # [ 0.878778] ima: No TPM chip found, activating TPM-bypass! machine # [ 0.879678] ima: Allocated hash algorithm: sha1 machine # [ 0.880507] ima: No architecture policies found machine # [ 0.881602] PM: Magic number: 10:333:144 machine # [ 0.883148] RAS: Correctable Errors collector initialized. machine # [ 0.887627] clk: Disabling unused clocks machine # [ 0.888347] PM: genpd: Disabling unused power domains machine # [ 1.068476] Freeing initrd memory: 29076K machine # [ 1.072024] Freeing unused decrypted memory: 2028K machine # [ 1.074758] Freeing unused kernel image (initmem) memory: 3644K machine # [ 1.075840] Write protecting the kernel read-only data: 32768k machine # [ 1.077980] Freeing unused kernel image (text/rodata gap) memory: 1200K machine # [ 1.079700] Freeing unused kernel image (rodata/data gap) memory: 736K machine # [ 1.146164] x86/mm: Checked W+X mappings: passed, no W+X pages found. machine # [ 1.147312] Run /init as init process machine # [ 1.157892] systemd[1]: Inserted module 'autofs4' machine # [ 1.183491] fuse: init (API version 7.45) machine # [ 1.192144] ACPI: \_SB_.GSIG: Enabled at IRQ 22 machine # [ 1.194746] ACPI: \_SB_.GSIH: Enabled at IRQ 23 machine # [ 1.197684] ACPI: \_SB_.GSIE: Enabled at IRQ 20 machine # [ 1.199858] ACPI: \_SB_.GSIF: Enabled at IRQ 21 machine # [ 1.204905] virtiofs virtio5: discovered new tag: nix-store machine # [ 1.206516] virtiofs virtio5: virtio_fs_setup_dax: No cache capability machine # [ 1.213660] virtiofs virtio6: discovered new tag: shared machine # [ 1.215279] virtiofs virtio6: virtio_fs_setup_dax: No cache capability machine # [ 1.218479] virtiofs virtio7: discovered new tag: xchg machine # [ 1.219915] virtiofs virtio7: virtio_fs_setup_dax: No cache capability machine # [ 1.245964] systemd[1]: Successfully made /usr/ read-only. machine # [ 1.581660] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 1.593560] systemd[1]: Detected virtualization kvm. machine # [ 1.595726] systemd[1]: Detected architecture x86-64. machine # [ 1.597888] systemd[1]: Running in initrd. machine # [ 1.600449] systemd[1]: Initializing machine ID from random generator. machine # [ 1.603431] systemd[1]: Hostname set to . machine # [ 1.725886] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 1.759429] systemd[1]: Queued start job for default target Initrd Default Target. machine # [ 1.767781] systemd[1]: Created slice Slice /system/modprobe. machine # [ 1.768987] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 1.770425] systemd[1]: Expecting device /dev/disk/by-label/nixos... machine # [ 1.783130] systemd[1]: Reached target Path Units. machine # [ 1.784025] systemd[1]: Reached target Slice Units. machine # [ 1.784887] systemd[1]: Reached target Swaps. machine # [ 1.785679] systemd[1]: Reached target Timer Units. machine # [ 1.786677] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 1.787891] systemd[1]: Listening on Journal Socket (/dev/log). machine # [ 1.789045] systemd[1]: Listening on Journal Sockets. machine # [ 1.790018] systemd[1]: Listening on udev Control Socket. machine # [ 1.791008] systemd[1]: Listening on udev Kernel Socket. machine # [ 1.791934] systemd[1]: Reached target Socket Units. machine # [ 1.793744] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 1.795786] systemd[1]: Starting Load Kernel Module configfs... machine # [ 1.798751] systemd[1]: Starting Journal Service... machine # [ 1.801295] systemd[1]: Starting Load Kernel Modules... machine # [ 1.802291] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 1.805463] systemd[1]: Starting Coldplug All udev Devices... machine # [ 1.808232] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 1.810673] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 1.816684] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 1.820801] systemd[1]: Finished Load Kernel Module configfs. machine # [ 1.825299] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 1.827173] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config machine # [ 1.829883] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 1.836194] systemd-journald[90]: Collecting audit messages is disabled. machine # [ 1.836385] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. machine # [ 1.839532] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev machine # [ 1.845551] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 1.846814] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 1.848031] systemd[1]: Reached target Local File Systems. machine # [ 1.850046] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 1.851941] systemd[1]: Finished Load Kernel Modules. machine # [ 1.853712] systemd[1]: Starting Apply Kernel Variables... machine # [ 1.867614] systemd[1]: Started Journal Service. machine # [ 1.722937] systemd-modules-load[91]: Using 4 probe threads machine # [ 1.724239] systemd-modules-load[91]: Inserted module 'virtio_balloon' machine # [ 1.725276] systemd-modules-load[91]: Inserted module 'dm_mod' machine # [ 1.726240] systemd-modules-load[91]: Inserted module 'virtio_gpu' machine # [ 1.728212] systemd[1]: Starting Create System Files and Directories... machine # [ 1.732737] systemd[1]: Finished Apply Kernel Variables. machine # [ 1.741127] systemd[1]: Finished Create System Files and Directories. machine # [ 1.745341] systemd-udevd[103]: Using default interface naming scheme 'v261'. machine # [ 1.755405] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 1.777264] systemd[1]: Finished Coldplug All udev Devices. machine # [ 1.778098] systemd[1]: Reached target System Initialization. machine # [ 1.778924] systemd[1]: Reached target Basic System. machine # [ 1.990006] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine # [ 1.992010] serio: i8042 KBD port at 0x60,0x64 irq 1 machine # [ 1.992736] serio: i8042 AUX port at 0x60,0x64 irq 12 machine # [ 1.999380] virtio_blk virtio2: 4/0/0 default/read/poll queues machine # [ 2.004700] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) machine # [ 2.008048] uhci_hcd 0000:00:1d.0: UHCI Host Controller machine # [ 2.008865] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1 machine # [ 2.009974] uhci_hcd 0000:00:1d.0: detected 2 ports machine # [ 2.010923] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180 machine # [ 2.012391] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.013625] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.014711] usb usb1: Product: UHCI Host Controller machine # [ 2.015471] usb usb1: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.016251] usb usb1: SerialNumber: 0000:00:1d.0 machine # [ 2.017933] hub 1-0:1.0: USB hub found machine # [ 2.017983] SCSI subsystem initialized machine # [ 2.018618] hub 1-0:1.0: 2 ports detected machine # [ 2.020797] ehci-pci 0000:00:1d.7: EHCI Host Controller machine # [ 2.022048] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 2 machine # [ 2.023442] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000 machine # [ 2.029409] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00 machine # [ 2.030642] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18 machine # [ 2.031799] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.032977] usb usb2: Product: EHCI Host Controller machine # [ 2.033759] usb usb2: Manufacturer: Linux 6.18.51 ehci_hcd machine # [ 2.034598] usb usb2: SerialNumber: 0000:00:1d.7 machine # [ 2.035680] hub 2-0:1.0: USB hub found machine # [ 2.036297] hub 2-0:1.0: 6 ports detected machine # [ 1.896288] systemd[1]: Starting Virtual Console Setup... machine # [ 1.907374] systemd[1]: Found device /dev/disk/by-label/nixos. machine # [ 1.908277] systemd[1]: Reached target Initrd Root Device. machine # [ 1.909278] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... machine # [ 2.058491] hub 1-0:1.0: USB hub found machine # [ 2.059143] hub 1-0:1.0: 2 ports detected machine # [ 2.060466] uhci_hcd 0000:00:1d.1: UHCI Host Controller machine # [ 2.061248] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3 machine # [ 2.062381] uhci_hcd 0000:00:1d.1: detected 2 ports machine # [ 2.063175] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0 machine # [ 1.917538] (udev-worker)[117]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 1.920040] (udev-worker)[117]: Network interface NamePolicy= disabled on kernel command line. machine # [ 1.921690] systemd-vconsole-setup[128]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 2.070929] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine # [ 1.926273] (udev-worker)[120]: Network interface NamePolicy= disabled on kernel command line. machine # [ 1.927532] systemd[1]: Finished Virtual Console Setup. machine # [ 2.074571] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.076063] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.077174] usb usb3: Product: UHCI Host Controller machine # [ 2.077924] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.078734] usb usb3: SerialNumber: 0000:00:1d.1 machine # [ 2.080143] hub 3-0:1.0: USB hub found machine # [ 2.081092] hub 3-0:1.0: 2 ports detected machine # [ 1.935546] systemd-fsck[136]: nixos: clean, 12/65536 files, 13019/262144 blocks machine # [ 2.084290] uhci_hcd 0000:00:1d.2: UHCI Host Controller machine # [ 1.939041] systemd[1]: Finis[ 2.085193] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4 machine # hed File System Check on /dev/disk/by-label/nixos. machine # [ 2.086785] uhci_hcd 0000:00:1d.2: detected 2 ports machine # [ 2.088269] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0 machine # [ 2.089487] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 machine # [ 2.090903] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine # [ 2.092027] usb usb4: Product: UHCI Host Controller machine # [ 2.092804] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd machine # [ 2.093739] usb usb4: SerialNumber: 0000:00:1d.2 machine # [ 2.094984] hub 4-0:1.0: USB hub found machine # [ 2.095758] hub 4-0:1.0: 2 ports detected machine # [ 2.106330] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode machine # [ 2.107523] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) machine # [ 2.108426] ahci 0000:00:1f.2: flags: 64bit ncq only machine # [ 2.110946] scsi host0: ahci machine # [ 2.111709] scsi host1: ahci machine # [ 2.112929] scsi host2: ahci machine # [ 2.113681] scsi host3: ahci machine # [ 2.114931] scsi host4: ahci machine # [ 2.115752] scsi host5: ahci machine # [ 2.116398] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 50 lpm-pol 1 machine # [ 2.117573] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 50 lpm-pol 1 machine # [ 2.118741] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 50 lpm-pol 1 machine # [ 2.119884] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 50 lpm-pol 1 machine # [ 2.121060] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 50 lpm-pol 1 machine # [ 2.122246] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 50 lpm-pol 1 machine # [ 2.272500] usb 2-1: new high-speed USB device number 2 using ehci-pci machine # [ 2.401602] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 machine # [ 2.402787] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 machine # [ 2.403846] usb 2-1: Product: QEMU USB Tablet machine # [ 2.404491] usb 2-1: Manufacturer: QEMU machine # [ 2.405075] usb 2-1: SerialNumber: 28754-0000:00:1d.7-1 machine # [ 2.420129] hid: raw HID events driver (C) Jiri Kosina machine # [ 2.438546] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) machine # [ 2.439781] ata1: SATA link down (SStatus 0 SControl 300) machine # [ 2.442088] ata4: SATA link down (SStatus 0 SControl 300) machine # [ 2.444525] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine # [ 2.445344] ata3.00: applying bridge limits machine # [ 2.446135] ata2: SATA link down (SStatus 0 SControl 300) machine # [ 2.447202] ata6: SATA link down (SStatus 0 SControl 300) machine # [ 2.449518] ata3.00: configured for UDMA/100 machine # [ 2.450474] ata5: SATA link down (SStatus 0 SControl 300) machine # [ 2.452870] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine # [ 2.481382] usbcore: registered new interface driver usbhid machine # [ 2.482193] usbhid: USB HID core driver machine # [ 2.493738] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input2 machine # [ 2.497987] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0 machine # [ 2.503762] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine # [ 2.539616] cdrom: Uniform CD-ROM driver Revision: 3.20 machine # [ 2.662793] systemd[1]: Mounting /sysroot... machine # [ 2.952287] EXT4-fs (vda): mounted filesystem 09db12ed-9e17-4fb5-ad83-7a5afdca3273 r/w with ordered data mode. Quota mode: none. machine # [ 2.809077] systemd[1]: Mounted /sysroot. machine # [ 2.809790] systemd[1]: Reached target Initrd Root File System. machine # [ 2.811277] systemd[1]: Mounting /sysroot/nix/.ro-store... machine # [ 2.812166] systemd[1]: Mounting /sysroot/nix/.rw-store... machine # [ 2.812959] systemd[1]: Mounting /sysroot/run... machine # [ 2.815777] systemd[1]: Mounting /sysroot/tmp/shared... machine # [ 2.816749] systemd[1]: Mounting /sysroot/tmp/xchg... machine # [ 2.830642] systemd[1]: Starting Mountpoints Configured in the Real Root... machine # [ 2.833136] systemd[1]: Mounted /sysroot/run. machine # [ 2.835787] systemd[1]: Mounted /sysroot/nix/.rw-store. machine # [ 2.836649] systemd[1]: Mounted /sysroot/tmp/shared. machine # [ 2.838968] systemd-sysroot-fstab-check[177]: /sysroot should be mounted in the initrd, will request daemon-reload. machine # [ 2.840983] systemd[1]: Mounted /sysroot/tmp/xchg. machine # [ 2.842219] systemd[1]: Mounted /sysroot/nix/.ro-store. machine # [ 2.845559] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.846609] systemd[1]: Reload requested from client PID 177 ('systemd-sysroot') (unit initrd-parse-etc.service)... machine # [ 2.848043] systemd[1]: Reloading... machine # [ 2.900223] systemd[1]: Reloading finished in 51 ms. machine # [ 2.924020] systemd-sysroot-fstab-check[177]: Requesting initrd-fs.target/start/replace... machine # [ 2.925221] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.926231] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 2.927056] systemd-sysroot-fstab-check[177]: Requesting swap.target/start/replace... machine # [ 2.928139] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 2.928971] systemd[1]: initrd-parse-etc.service: Deactivated successfully. machine # [ 2.929942] systemd[1]: Finished Mountpoints Configured in the Real Root. machine # [ 2.930870] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. machine # [ 2.947318] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 2.948340] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.664691] systemd[1]: Mounting /sysroot/nix/store... machine # [ 3.706298] systemd[1]: Mounted /sysroot/nix/store. machine # [ 3.708275] systemd[1]: Reached target Initrd File Systems. machine # [ 3.710368] systemd[1]: Starting Find NixOS closure... machine # [ 3.712373] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... machine # [ 3.732919] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. machine # [ 3.735098] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. machine # [ 3.742563] systemd[1]: Finished Find NixOS closure. machine # [ 3.744357] systemd[1]: Reached target Initrd Default Target. machine # [ 3.746321] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... machine # [ 3.764091] systemd[1]: Stopped target Initrd Default Target. machine # [ 3.766197] systemd[1]: Stopped target Basic System. machine # [ 3.767879] systemd[1]: Stopped target Initrd Root Device. machine # [ 3.769658] systemd[1]: Stopped target Path Units. machine # [ 3.771284] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. machine # [ 3.773702] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. machine # [ 3.776227] systemd[1]: Stopped target Slice Units. machine # [ 3.778340] systemd[1]: Stopped target Socket Units. machine # [ 3.781065] systemd[1]: Stopped target System Initialization. machine # [ 3.783264] systemd[1]: Stopped target Swaps. machine # [ 3.785138] systemd[1]: Stopped target Timer Units. machine # [ 3.786817] systemd[1]: dbus.socket: Deactivated successfully. machine # [ 3.788753] systemd[1]: Closed D-Bus System Message Bus Socket. machine # [ 3.790793] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. machine # [ 3.793226] systemd[1]: Stopped Find NixOS closure. machine # [ 3.794877] systemd[1]: Starting rw-sysroot-nix-store.service... machine # [ 3.796685] systemd[1]: systemd-sysctl.service: Deactivated successfully. machine # [ 3.798680] systemd[1]: Stopped Apply Kernel Variables. machine # [ 3.800226] systemd[1]: systemd-modules-load.service: Deactivated successfully. machine # [ 3.802237] systemd[1]: Stopped Load Kernel Modules. machine # [ 3.803719] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. machine # [ 3.805834] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. machine # [ 3.807847] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. machine # [ 3.810078] systemd[1]: Stopped Create System Files and Directories. machine # [ 3.811708] systemd[1]: Stopped target Local File Systems. machine # [ 3.813185] systemd[1]: Stopped target Preparation for Local File Systems. machine # [ 3.814939] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. machine # [ 3.816795] systemd[1]: Stopped Coldplug All udev Devices. machine # [ 3.817781] systemd[1]: Stopping Rule-based Manager for Device Events and Files... machine # [ 3.819086] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 3.820332] systemd[1]: Stopped Virtual Console Setup. machine # [ 3.821483] systemd[1]: initrd-cleanup.service: Deactivated successfully. machine # [ 3.822629] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. machine # [ 3.823734] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. machine # [ 3.824879] systemd[1]: Finished rw-sysroot-nix-store.service. machine # [ 3.825696] systemd[1]: systemd-udevd.service: Deactivated successfully. machine # [ 3.826566] systemd[1]: Stopped Rule-based Manager for Device Events and Files. machine # [ 3.827482] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. machine # [ 3.828440] systemd[1]: Closed udev Control Socket. machine # [ 3.829154] systemd[1]: Starting Cleanup udev Database... machine # [ 3.829914] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. machine # [ 3.830939] systemd[1]: Stopped Create Static Device Nodes in /dev. machine # [ 3.831808] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. machine # [ 3.832858] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. machine # [ 3.833792] systemd[1]: kmod-static-nodes.service: Deactivated successfully. machine # [ 3.834737] systemd[1]: Stopped Create List of Static Device Nodes. machine # [ 3.840022] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. machine # [ 3.841069] systemd[1]: Finished Cleanup udev Database. machine # [ 3.841791] systemd[1]: Reached target Switch Root. machine # [ 3.842474] systemd[1]: Starting NixOS Activation... machine # [ 3.951965] initrd-nixos-activation-start[222]: booting system configuration /nix/store/x2xjw51wh4rcckidvj571z1xnxawj42j-nixos-system-machine-test machine # [ 3.987586] initrd-nixos-activation-start[222]: running activation script... machine # [ 4.229222] initrd-nixos-activation-start[245]: setting up /etc... machine # [ 4.410431] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. machine # [ 4.412666] systemd[1]: Finished NixOS Activation. machine # [ 4.414129] systemd[1]: Starting Switch Root... machine # [ 4.431808] systemd[1]: Switching root. machine # [ 4.641828] systemd-journald[90]: Received SIGTERM from PID 1 (systemd). machine # [ 4.760288] NET: Registered PF_VSOCK protocol family machine # [ 5.143950] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) machine # [ 5.154236] systemd[1]: Detected virtualization kvm. machine # [ 5.156177] systemd[1]: Detected architecture x86-64. machine # [ 5.158222] systemd[1]: Detected first boot. machine # [ 5.162585] systemd[1]: Initializing machine ID from random generator. machine # [ 5.338216] systemd[1]: bpf-restrict-fs: LSM BPF program attached machine # [ 5.409229] systemd[1]: Applying preset policy. machine # [ 5.605480] systemd[1]: Populated /etc with preset unit settings. machine # [ 5.801748] systemd[1]: initrd-switch-root.service: Deactivated successfully. machine # [ 5.805158] systemd[1]: Stopped initrd-switch-root.service. machine # [ 5.809972] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. machine # [ 5.815184] systemd[1]: Created slice Slice /system/getty. machine # [ 5.818680] systemd[1]: Created slice User and Session Slice. machine # [ 5.849998] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. machine # [ 5.852656] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine # [ 5.854894] systemd[1]: Expecting device /dev/hvc0... machine # [ 5.856473] systemd[1]: Expecting device /dev/ttyS0... machine # [ 5.858047] systemd[1]: Reached target Local Encrypted Volumes. machine # [ 5.859839] systemd[1]: Stopped target initrd-fs.target. machine # [ 5.861433] systemd[1]: Stopped target initrd-root-fs.target. machine # [ 5.863141] systemd[1]: Stopped target initrd-switch-root.target. machine # [ 5.864992] systemd[1]: Reached target Virtual Machines and Containers. machine # [ 5.867020] systemd[1]: Reached target Path Units. machine # [ 5.868334] systemd[1]: Reached target Remote File Systems. machine # [ 5.869169] systemd[1]: Reached target Slice Units. machine # [ 5.869924] systemd[1]: Reached target Swaps. machine # [ 5.871978] systemd[1]: Listening on Query the User Interactively for a Password. machine # [ 5.874545] systemd[1]: Listening on Process Core Dump Socket. machine # [ 5.876326] systemd[1]: Listening on Credential Encryption/Decryption. machine # [ 5.878275] systemd[1]: Listening on Factory Reset Management. machine # [ 5.879293] systemd[1]: Listening on Hostname Service Socket. machine # [ 5.882033] systemd[1]: Starting Journal Log Access Socket... machine # [ 5.883492] systemd[1]: Listening on Journal Audit Socket. machine # [ 5.885475] systemd[1]: Listening on Console Output Muting Service Socket. machine # [ 5.886646] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. machine # [ 5.887954] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.889417] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki machine # [ 5.893619] systemd[1]: Listening on Disk Repartitioning Service Socket. machine # [ 5.894786] systemd[1]: Listening on udev Control Socket. machine # [ 5.895751] systemd[1]: Listening on udev Varlink Socket. machine # [ 5.898253] systemd[1]: Mounting Huge Pages File System... machine # [ 5.900248] systemd[1]: Mounting POSIX Message Queue File System... machine # [ 5.902445] systemd[1]: Mounting Kernel Debug File System... machine # [ 5.904689] systemd[1]: Mounting Kernel Trace File System... machine # [ 5.911698] systemd[1]: Starting Create List of Static Device Nodes... machine # [ 5.915501] systemd[1]: Starting Load Kernel Module configfs... machine # [ 5.916513] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm machine # [ 5.918090] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 5.919858] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 5.922551] systemd[1]: Mounting FUSE Control File System... machine # [ 5.923544] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 5.931028] systemd[1]: Starting Journal Service... machine # [ 5.938800] systemd[1]: Starting Load Kernel Modules... machine # [ 5.941581] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... machine # [ 5.944004] systemd[1]: Starting Remount Root and Kernel File Systems... machine # [ 5.945159] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 5.951857] systemd[1]: Starting Coldplug All udev Devices... machine # [ 5.955465] systemd[1]: Listening on Journal Log Access Socket. machine # [ 5.956612] systemd[1]: Mounted Huge Pages File System. machine # [ 5.957663] systemd[1]: Mounted POSIX Message Queue File System. machine # [ 5.958770] systemd[1]: Mounted Kernel Debug File System. machine # [ 5.959777] systemd[1]: Mounted Kernel Trace File System. machine # [ 5.961081] systemd[1]: Finished Create List of Static Device Nodes. machine # [ 5.962309] systemd[1]: Mounted FUSE Control File System. machine # [ 5.965151] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... machine # [ 5.973014] systemd[1]: modprobe@configfs.service: Deactivated successfully. machine # [ 5.975885] systemd[1]: Finished Load Kernel Module configfs. machine # [ 5.982781] systemd[1]: Mounting Kernel Configuration File System... machine # [ 5.993867] systemd-journald[315]: Collecting audit messages is enabled. machine # [ 6.000110] loop: module loaded machine # [ 6.002937] systemd[1]: Finished Load Kernel Modules. machine # [ 5.857157] systemd[1]: Queued start job for [ 6.003855] systemd[1]: Started Journal Service. machine # default target Multi-User System. machine # [ 5.859191] systemd[1]: systemd-journald.service: Deactivated successfully. machine # [ 6.006600] EXT4-fs (vda): re-mounted 09db12ed-9e17-4fb5-ad83-7a5afdca3273. machine # [ 5.861419] systemd-modules-load[316]: Using 2 probe threads machine # [ 5.862712] systemd-modules-load[316]: Inserted module 'loop' machine # [ 5.863862] systemd-oomd[317]: No swap; memory pressure usage will be degraded machine # [ 5.866411] systemd[1]: Starting Firewall... machine # [ 5.867261] systemd[1]: Starting Apply Kernel Variables... machine # [ 5.868655] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. machine # [ 5.885474] systemd[1]: Finished Remount Root and Kernel File Systems. machine # [ 5.886532] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine # [ 5.887545] systemd[1]: Mounted Kernel Configuration File System. machine # [ 5.888457] systemd[1]: Listening on Disk Image Download Service Socket. machine # [ 5.889599] systemd[1]: Starting Flush Journal to Persistent Storage... machine # [ 5.890700] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 5.892886] systemd[1]: Starting Load/Save OS Random Seed... machine # [ 5.896372] systemd[1]: Starting Create Static Device Nodes in /dev... machine # [ 5.897319] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.059764] systemd-journald[315]: Received client request to flush runtime journal. machine # [ 5.956282] systemd[1]: Finished Load/Save OS Random Seed. machine # [ 5.957412] systemd[1]: Reached target First Boot Complete. machine # [ 5.958388] systemd[1]: Finished Apply Kernel Variables. machine # [ 5.959242] systemd[1]: Finished Flush Journal to Persistent Storage. machine # [ 5.964051] systemd[1]: Finished Create Static Device Nodes in /dev. machine # [ 5.965083] systemd[1]: Reached target Preparation for Local File Systems. machine # [ 5.966134] systemd[1]: Starting Rule-based Manager for Device Events and Files... machine # [ 5.978736] systemd[1]: Finished Coldplug All udev Devices. machine # [ 6.004424] systemd-udevd[351]: Using default interface naming scheme 'v261'. machine # [ 6.031088] systemd[1]: Started Rule-based Manager for Device Events and Files. machine # [ 6.092583] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse machine # [ 6.109726] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. machine # [ 6.117903] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. machine # [ 6.121385] (udev-worker)[384]: Network interface NamePolicy= disabled on kernel command line. machine # [ 6.124209] (udev-worker)[386]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. machine # [ 6.126319] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line. machine # [ 6.143704] systemd[1]: Condition check resulted in Virtio network device being skipped. machine # [ 6.144908] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore machine # [ 6.146603] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 machine # [ 6.148921] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore machine # [ 6.150521] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.152860] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os machine # [ 6.320558] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console machine # [ 6.321001] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine # [ 6.322745] mousedev: PS/2 mouse device common for all mice machine # [ 6.330376] ACPI: button: Power Button [PWRF] machine # [ 6.336087] Console: switching to colour dummy device 80x25 machine # [ 6.337993] [drm] Found bochs VGA, ID 0xb0c5. machine # [ 6.337995] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine # [ 6.339648] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic machine # [ 6.340422] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0 machine # [ 6.349739] Console: switching to colour frame buffer device 160x50 machine # [ 6.349869] rtc_cmos PNP0B00:00: RTC can wake from S4 machine # [ 6.351795] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device machine # [ 6.351934] rtc_cmos PNP0B00:00: registered as rtc0 machine # [ 6.353367] rtc_cmos PNP0B00:00: setting system clock to 2026-09-15T20:06:48 UTC (1789502808) machine # [ 6.358854] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs machine # [ 6.360665] systemd-journald[315]: Time jumped backwards, rotating. machine # [ 6.362226] parport_pc 00:02: reported by Plug and Play ACPI machine # [ 6.365102] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine # [ 6.369658] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4 machine # [ 6.380209] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized machine # [ 6.394058] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 machine # [ 6.395338] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 machine # [ 6.398032] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt machine # [ 6.398817] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD machine # [ 6.293052] systemd[1]: Starting Virtual Console Setup... machine # [ 6.310514] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 6.311704] systemd[1]: Stopped Virtual Console Setup. machine # [ 6.325120] systemd[1]: Starting Virtual Console Setup... machine # [ 6.484408] ppdev: user-space parallel port driver machine # [ 6.343332] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. machine # [ 6.345088] systemd[1]: Stopped Virtual Console Setup. machine # [ 6.493819] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) machine # [ 6.495171] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0) machine # [ 6.348947] systemd[1]: Starting Virtual Console Setup... machine # [ 6.517695] kvm_amd: TSC scaling supported machine # [ 6.518207] kvm_amd: Nested Virtualization enabled machine # [ 6.518796] kvm_amd: Nested Paging enabled machine # [ 6.519298] kvm_amd: LBR virtualization supported machine # [ 6.519865] kvm_amd: Virtual VMLOAD VMSAVE supported machine # [ 6.520445] kvm_amd: Virtual GIF supported machine # [ 6.520936] kvm_amd: Virtual NMI enabled machine # [ 6.622900] EDAC MC: Ver: 3.0.0 machine # [ 6.518618] systemd-vconsole-setup[438]: Configuration of first virtual console was skipped, ignoring remaining ones. machine # [ 6.521427] systemd[1]: Finished Virtual Console Setup. machine # [ 6.523376] systemd[1]: Finished Firewall. machine # [ 6.659031] systemd[1]: Mounting /run/wrappers... machine # [ 6.690424] systemd[1]: Mounted /run/wrappers. machine # [ 6.693201] systemd[1]: Reached target Local File Systems. machine # [ 6.697103] systemd[1]: Listening on Boot Loader Control Service Socket. machine # [ 6.701634] systemd[1]: Starting register-nix-paths.service... machine # [ 6.705822] systemd[1]: Starting Create SUID/SGID Wrappers... machine # [ 6.707563] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. machine # [ 6.712705] systemd[1]: Starting Save Transient machine-id to Disk... machine # [ 6.715501] systemd[1]: Starting Create System Files and Directories... machine # [ 6.759210] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine # [ 6.761617] systemd[1]: Finished Create System Files and Directories. machine # [ 6.764432] systemd[1]: Finished Save Transient machine-id to Disk. machine # [ 6.769848] systemd[1]: Starting Rebuild Journal Catalog... machine # [ 6.774586] systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine # [ 6.800291] systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine # [ 6.810650] systemd[1]: Finished Rebuild Journal Catalog. machine # [ 6.812880] systemd[1]: Starting Update is Completed... machine # [ 6.831479] systemd[1]: Finished Update is Completed. machine # [ 6.950573] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. machine # [ 6.952295] systemd[1]: Finished Create SUID/SGID Wrappers. machine # [ 6.978220] systemd[1]: Finished register-nix-paths.service. machine # [ 6.979864] systemd[1]: Reached target System Initialization. machine # [ 6.981405] systemd[1]: Started Discard unused filesystem blocks once a week. machine # [ 6.983364] systemd[1]: Started Daily Cleanup of Temporary Directories. machine # [ 6.985186] systemd[1]: Reached target Timer Units. machine # [ 6.986491] systemd[1]: Listening on D-Bus System Message Bus Socket. machine # [ 6.988233] systemd[1]: Listening on Nix Daemon Socket. machine # [ 6.989629] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine # [ 6.992039] systemd[1]: Starting systemd-vaultd socket... machine # [ 6.994947] systemd[1]: Starting D-Bus System Message Bus... machine # [ 6.996248] systemd[1]: Listening on systemd-vaultd socket. machine # [ 6.997518] systemd[1]: Reached target Socket Units. machine # [ 7.028734] dbus-broker-launch[555]: Looking up NSS user entry for 'systemd-timesync'... machine # [ 7.033336] dbus-broker-launch[555]: NSS returned no entry for 'systemd-timesync' machine # [ 7.035374] dbus-broker-launch[555]: Invalid user-name in /nix/store/8ijxipkchmphnd5ihjv3zhp09518rk7s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine # [ 7.040794] systemd[1]: Started D-Bus System Message Bus. machine # [ 7.042855] systemd[1]: Reached target Basic System. machine # [ 7.044292] systemd[1]: Started backdoor.service. machine # [ 7.045801] systemd[1]: Starting Import lastlog data into lastlog2 database... machine # [ 7.048389] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 7.053675] systemd[1]: Starting Post-Boot Actions... machine # [ 7.063212] dbus-broker-launch[555]: Ready machine # [ 7.068139] systemd[1]: Started Reset console on configuration changes. machine # [ 7.072395] systemd[1]: Starting resolvconf update... machine # [ 7.076472] systemd[1]: Starting service1-envfile.service... machine # [ 7.087764] systemd[1]: Starting service2.service... machine # [ 7.091989] nsncd[559]: Sep 15 20:06:49.383 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.096093] systemd[1]: Starting setup-vault-agent-approle.service... machine # [ 7.098160] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.101840] systemd[1]: Finished Post-Boot Actions. machine # connecting to host... machine # [ 7.115721] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.116736] systemd[1]: Reached target User and Group Name Lookups. machine: Guest shell says: b'Spawning backdoor root shell...\n' machine # [ 7.119530] systemd[1]: Starting User Login Management... machine: connected to guest root shell machine # [ 7.121835] systemd[1]: Started systemd-vaultd daemon. machine: (connecting took 7.74 seconds) machine # [ 7.124427] setup-vault-agent-approle-start[569]: + vault status machine: (finished: waiting for the VM to finish booting, in 7.74 seconds) machine # [ 7.131736] systemd[1]: Finished Import lastlog data into lastlog2 database. machine # [ 7.149188] systemd-vaultd[588]: 2026/09/15 20:06:49 Use unix socket received from systemd machine # [ 7.149794] systemd-vaultd[588]: 2026/09/15 20:06:49 Listening on /run/systemd-vaultd/sock machine # [ 7.150511] systemd-vaultd[588]: 2026/09/15 20:06:49 Systemd requested secret for service2.service/secret machine # [ 7.151145] systemd-vaultd[588]: 2026/09/15 20:06:49 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service machine # [ 7.151797] systemd-vaultd[588]: 2026/09/15 20:06:49 Block start until service2.service.json appears machine # [ 7.152614] systemd-vaultd[588]: 2026/09/15 20:06:49 Block start until service1.service.EnvironmentFile appears machine # [ 7.177147] systemd[1]: Stopped target Host and Network Name Lookups. machine # [ 7.177849] systemd[1]: Stopping Host and Network Name Lookups... machine # [ 7.178454] systemd[1]: Stopped target User and Group Name Lookups. machine # [ 7.178962] systemd[1]: Stopping User and Group Name Lookups... machine # [ 7.181613] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... machine # [ 7.183814] systemd-logind[587]: New seat seat0. machine # [ 7.184618] systemd[1]: nscd.service: Deactivated successfully. machine # [ 7.185451] systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine # [ 7.186346] systemd-logind[587]: Watching system buttons on /dev/input/event2 (Power Button) machine # [ 7.187510] systemd-logind[587]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) machine # [ 7.189136] systemd-logind[587]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) machine # [ 7.190652] systemd[1]: Started User Login Management. machine # [ 7.191647] systemd[1]: Starting linger-users.service... machine # [ 7.193786] systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine # [ 7.214313] systemd[1]: linger-users.service: Deactivated successfully. machine # [ 7.215061] systemd[1]: Finished linger-users.service. machine # [ 7.222619] nsncd[655]: Sep 15 20:06:49.514 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine # [ 7.223297] systemd[1]: Started Name Service Cache Daemon (nsncd). machine # [ 7.223932] systemd[1]: Reached target Host and Network Name Lookups. machine # [ 7.224387] systemd[1]: Reached target User and Group Name Lookups. machine # [ 7.242197] systemd[1]: Finished resolvconf update. machine # [ 7.242689] systemd[1]: Reached target Preparation for Network. machine # [ 7.245176] systemd[1]: Starting DHCP Client... machine # [ 7.246597] systemd[1]: Starting Address configuration of eth1... machine # [ 7.248232] systemd[1]: Starting Extra networking commands.... machine # [ 7.292275] network-addresses-eth1-start[684]: adding address 192.168.1.1/24... done machine # [ 7.297833] network-addresses-eth1-start[684]: adding address 2001:db8:1::1/64... done machine # [ 7.308738] systemd[1]: Finished Address configuration of eth1. machine # [ 7.330692] dhcpcd[694]: dhcpcd-10.3.2 starting machine # [ 7.335554] dhcpcd[745]: dev: loaded udev machine # [ 7.344171] systemd[1]: Finished Extra networking commands.. machine # [ 7.344836] systemd[1]: Reached target Network. machine # [ 7.346186] systemd[1]: Starting Permit User Sessions... machine # [ 7.493741] 8021q: 802.1Q VLAN Support v1.8 machine # [ 7.494572] 8021q: adding VLAN 0 to HW filter on device eth1 machine # [ 7.349936] systemd[1]: Started vault-agent-default.service. machine # [ 7.351664] systemd[1]: Started Vault server daemon. machine # [ 7.364618] systemd[1]: Finished Permit User Sessions. machine # [ 7.367179] systemd[1]: Started Getty on tty1. machine # [ 7.368567] systemd[1]: Reached target Login Prompts. machine # [ 7.395865] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine # [ 7.596299] cfg80211: Loading compiled-in X.509 certificates for regulatory database machine # [ 7.610441] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' machine # [ 7.611706] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' machine # [ 7.613562] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 machine # [ 7.614784] cfg80211: failed to load regulatory.db machine # [ 7.681584] 8021q: adding VLAN 0 to HW filter on device eth0 machine # [ 7.537091] dhcpcd[745]: eth0: waiting for carrier machine # [ 7.537957] dhcpcd[745]: eth0: carrier acquired machine # [ 7.546441] setup-vault-agent-approle-start[590]: Error checking seal status: Get "http://127.0.0.1:8200/v1/sys/seal-status": dial tcp 127.0.0.1:8200: connect: connection refused machine # [ 7.549650] dhcpcd[745]: DUID 00:01:00:01:32:3c:61:d9:52:54:00:12:34:56 machine # [ 7.550426] dhcpcd[745]: eth0: IAID 00:12:34:56 machine # [ 7.551128] dhcpcd[745]: eth0: adding address fe80::5054:ff:fe12:3456 machine # [ 7.552127] setup-vault-agent-approle-start[569]: + sleep 1 machine # [ 7.557499] vault[754]: 2026-09-15T20:06:49.850Z [WARN] The HSM distribution of OpenBao is discontinued and will no longer receive updates beyond this minor version. PKCS#11 support has not been removed, but is now available via an external KMS plugin that is drop-in compatible with the previously built-in PKCS#11 seal. To remove this warning, migrate your deployment to the default distribution of OpenBao and use the PKCS#11 KMS plugin to regain PKCS#11 seal functionality. For more information, see https://openbao.org/docs/release-notes/2-6-0/#v260 machine # [ 7.564269] vault[754]: 2026-09-15T20:06:49.850Z [INFO] proxy environment: http_proxy="" https_proxy="" no_proxy="" machine # [ 7.565074] vault[754]: 2026-09-15T20:06:49.850Z [WARN] no `api_addr` value specified in config or in BAO_API_ADDR; falling back to detection if possible, but this value should be manually set machine # [ 7.565562] vault[754]: 2026-09-15T20:06:49.857Z [INFO] core: Initializing version history cache for core machine # [ 7.566091] vault[754]: ==> OpenBao server configuration: machine # [ 7.566557] vault[754]: Api Address: http://127.0.0.1:8200 machine # [ 7.567136] vault[754]: Cgo: enabled machine # [ 7.567569] vault[754]: Cluster Address: https://127.0.0.1:8201 machine # [ 7.568104] vault[754]: Environment Variables: HOME, INVOCATION_ID, IO_PRESSURE_WATCH, IO_PRESSURE_WRITE, JOURNAL_STREAM, LANG, LOCALE_ARCHIVE, LOGNAME, MEMORY_PRESSURE_WATCH, MEMORY_PRESSURE_WRITE, PATH, SHELL, STATE_DIRECTORY, SYSTEMD_EXEC_PID, TZDIR, USER machine # [ 7.568563] vault[754]: Go Version: go1.26.7 machine # [ 7.569108] vault[754]: Listener 1: tcp (addr: "127.0.0.1:8200", cluster address: "127.0.0.1:8201", max_request_duration: "1m30s", max_request_json_memory: "34078720", max_request_json_strings: "1000", max_request_size: "33554432", tls: "disabled") machine # [ 7.569567] vault[754]: Log Level: machine # [ 7.570102] vault[754]: Recovery Mode: false machine # [ 7.570561] vault[754]: Storage: inmem machine # [ 7.570978] vault[754]: Version: OpenBao v2.6.2 machine # [ 7.571560] vault[754]: Version Sha: refs/tags/v2.6.2 machine # [ 7.572379] bao[753]: ==> OpenBao Agent started! machine # [ 7.572939] bao[753]: ==> OpenBao Agent configuration: machine # [ 7.573553] bao[753]: Api Address 1: http://bufconn machine # [ 7.574055] bao[753]: Cgo: enabled machine # [ 7.574509] bao[753]: Log Level: machine # [ 7.575046] bao[753]: Version: OpenBao v2.6.2 machine # [ 7.575521] bao[753]: Version Sha: refs/tags/v2.6.2 machine # [ 7.576057] vault[754]: 2026-09-15T20:06:49.858Z [INFO] core: security barrier not initialized machine # [ 7.576502] vault[754]: 2026-09-15T20:06:49.859Z [INFO] core: security barrier initialized: shares=1 threshold=1 machine # [ 7.577018] vault[754]: 2026-09-15T20:06:49.861Z [INFO] core: post-unseal setup starting machine # [ 7.578194] vault[754]: 2026-09-15T20:06:49.871Z [INFO] core: loaded wrapping token key machine # [ 7.578624] vault[754]: 2026-09-15T20:06:49.871Z [INFO] core: successfully setup plugin catalog: plugin-directory="" machine # [ 7.579799] vault[754]: 2026-09-15T20:06:49.872Z [INFO] core: reading transactional mount table machine # [ 7.580435] vault[754]: 2026-09-15T20:06:49.872Z [INFO] core: no mounts in transactional mount table; adding default mount table machine # [ 7.581461] vault[754]: 2026-09-15T20:06:49.874Z [INFO] core: successfully mounted: type=cubbyhole version="v2.6.2+builtin.bao" path=cubbyhole/ namespace="ID: root. Path: " machine # [ 7.582606] vault[754]: 2026-09-15T20:06:49.875Z [INFO] core: successfully mounted: type=system version="v2.6.2+builtin.bao" path=sys/ namespace="ID: root. Path: " machine # [ 7.583674] vault[754]: 2026-09-15T20:06:49.876Z [INFO] core: successfully mounted: type=identity version="v2.6.2+builtin.bao" path=identity/ namespace="ID: root. Path: " machine # [ 7.587850] vault[754]: 2026-09-15T20:06:49.880Z [INFO] core: reading transactional auth mount table machine # [ 7.588546] vault[754]: 2026-09-15T20:06:49.880Z [INFO] core: no auth mounts in transactional auth mount table; adding default auth mount table machine # [ 7.589211] vault[754]: 2026-09-15T20:06:49.882Z [INFO] core: successfully mounted: type=token version="v2.6.2+builtin.bao" path=token/ namespace="ID: root. Path: " machine # [ 7.589777] vault[754]: 2026-09-15T20:06:49.882Z [INFO] rollback: Starting the rollback manager with 256 workers machine # [ 7.592456] vault[754]: 2026-09-15T20:06:49.884Z [INFO] core: restoring leases machine # [ 7.593197] vault[754]: 2026-09-15T20:06:49.884Z [INFO] rollback: starting rollback manager machine # [ 7.594871] vault[754]: 2026-09-15T20:06:49.887Z [INFO] expiration: lease restore complete machine # [ 7.595505] vault[754]: 2026-09-15T20:06:49.888Z [INFO] core: entities restored machine # [ 7.595966] vault[754]: 2026-09-15T20:06:49.888Z [INFO] core: groups restored machine # [ 7.596604] vault[754]: 2026-09-15T20:06:49.888Z [INFO] core: Recorded vault version: vault version=2.6.2 upgrade time="2026-09-15 20:06:49.888815058 +0000 UTC" commit date="" machine # [ 7.598474] vault[754]: 2026-09-15T20:06:49.891Z [INFO] core: post-unseal setup complete machine # [ 7.599413] vault[754]: 2026-09-15T20:06:49.892Z [INFO] core: root token generated machine # [ 7.599885] vault[754]: 2026-09-15T20:06:49.892Z [INFO] core: pre-seal teardown starting machine # [ 7.600540] vault[754]: 2026-09-15T20:06:49.892Z [INFO] rollback: stopping rollback manager machine # [ 7.601097] vault[754]: 2026-09-15T20:06:49.893Z [INFO] core: pre-seal teardown complete machine # [ 7.601540] vault[754]: 2026-09-15T20:06:49.893Z [INFO] core.cluster-listener.tcp: starting listener: listener_address=127.0.0.1:8201 machine # [ 7.602077] vault[754]: 2026-09-15T20:06:49.893Z [INFO] core.cluster-listener: serving cluster requests: cluster_listen_address=127.0.0.1:8201 machine # [ 7.602528] vault[754]: 2026-09-15T20:06:49.894Z [INFO] core: post-unseal setup starting machine # [ 7.603079] vault[754]: 2026-09-15T20:06:49.894Z [INFO] core: loaded wrapping token key machine # [ 7.603516] vault[754]: 2026-09-15T20:06:49.894Z [INFO] core: successfully setup plugin catalog: plugin-directory="" machine # [ 7.604058] vault[754]: 2026-09-15T20:06:49.895Z [INFO] core: reading transactional mount table machine # [ 7.604538] vault[754]: 2026-09-15T20:06:49.896Z [INFO] core: successfully mounted: type=system version="v2.6.2+builtin.bao" path=sys/ namespace="ID: root. Path: " machine # [ 7.605065] vault[754]: 2026-09-15T20:06:49.897Z [INFO] core: successfully mounted: type=identity version="v2.6.2+builtin.bao" path=identity/ namespace="ID: root. Path: " machine # [ 7.605521] vault[754]: 2026-09-15T20:06:49.897Z [INFO] core: successfully mounted: type=cubbyhole version="v2.6.2+builtin.bao" path=cubbyhole/ namespace="ID: root. Path: " machine # [ 7.606090] vault[754]: 2026-09-15T20:06:49.898Z [INFO] core: reading transactional auth mount table machine # [ 7.606526] vault[754]: 2026-09-15T20:06:49.898Z [INFO] core: successfully mounted: type=token version="v2.6.2+builtin.bao" path=token/ namespace="ID: root. Path: " machine # [ 7.607097] vault[754]: 2026-09-15T20:06:49.899Z [INFO] rollback: Starting the rollback manager with 256 workers machine # [ 7.607801] vault[754]: 2026-09-15T20:06:49.900Z [INFO] core: restoring leases machine # [ 7.609583] vault[754]: 2026-09-15T20:06:49.902Z [INFO] core: entities restored machine # [ 7.610056] vault[754]: 2026-09-15T20:06:49.902Z [INFO] rollback: starting rollback manager machine # [ 7.610504] vault[754]: 2026-09-15T20:06:49.902Z [INFO] core: groups restored machine # [ 7.611494] vault[754]: 2026-09-15T20:06:49.902Z [INFO] expiration: lease restore complete machine # [ 7.612052] vault[754]: 2026-09-15T20:06:49.903Z [INFO] core: post-unseal setup complete machine # [ 7.612491] vault[754]: 2026-09-15T20:06:49.903Z [INFO] core: vault is unsealed machine # [ 7.616710] vault[754]: 2026-09-15T20:06:49.909Z [INFO] expiration: revoked lease: lease_id=auth/token/root/h542bd5e0cebb3632415f5ce81c6fe5d2f4ff9cda6d533b13f95a228a4c2aa989 machine # [ 7.625230] vault[754]: 2026-09-15T20:06:49.917Z [INFO] core: successful mount: namespace="" path=secret/ type=kv version="" machine # [ 7.625950] vault[754]: 2026-09-15T20:06:49.917Z [INFO] beginning post-unseal configuration machine # [ 7.626553] vault[754]: 2026-09-15T20:06:49.918Z [INFO] secrets.kv.kv_a5f393fd: collecting keys to upgrade machine # [ 7.627086] vault[754]: ==> OpenBao server started! machine # [ 7.627560] vault[754]: 2026-09-15T20:06:49.918Z [INFO] secrets.kv.kv_a5f393fd: done collecting keys: num_keys=1 machine # [ 7.628098] vault[754]: 2026-09-15T20:06:49.918Z [INFO] secrets.kv.kv_a5f393fd: upgrading keys finished machine # [ 7.646912] bao[753]: 2026-09-15T20:06:49.939Z [INFO] agent.exec.server: starting exec server machine # [ 7.647525] bao[753]: 2026-09-15T20:06:49.939Z [INFO] agent.exec.server: no env templates or exec config, exiting machine # [ 7.648048] bao[753]: 2026-09-15T20:06:49.939Z [INFO] agent.auth.handler: starting auth handler machine # [ 7.648492] bao[753]: 2026-09-15T20:06:49.939Z [INFO] agent.auth.handler: authenticating machine # [ 7.649032] bao[753]: 2026-09-15T20:06:49.939Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1s machine # [ 7.649483] bao[753]: 2026-09-15T20:06:49.939Z [INFO] agent.sink.server: starting sink server machine # [ 7.653878] bao[753]: 2026-09-15T20:06:49.946Z [INFO] agent.template.server: starting template server machine # [ 7.655190] bao[753]: 2026-09-15T20:06:49.948Z [INFO] agent: (runner) creating new runner (dry: false, once: false) machine # [ 7.657212] bao[753]: 2026-09-15T20:06:49.950Z [INFO] agent: (runner) creating watcher machine # [ 7.726578] vault[754]: WARNING! dev mode is enabled! In this mode, OpenBao runs entirely in-memory machine # [ 7.728245] vault[754]: and starts unsealed with a single unseal key. The root token is already machine # [ 7.729816] vault[754]: authenticated to the CLI, so you can immediately begin using OpenBao. machine # [ 7.731422] vault[754]: You may need to set the following environment variables: machine # [ 7.732980] vault[754]: $ export BAO_ADDR='http://127.0.0.1:8200' machine # [ 7.735094] vault[754]: The unseal key and root token are displayed below in case you want to machine # [ 7.736217] vault[754]: seal/unseal the Vault or re-authenticate. machine # [ 7.737370] vault[754]: Unseal Key: k5JMQKOPpX2/K0CW3KUedQLde89NzCEkvm5H51wva+4= machine # [ 7.738925] vault[754]: Root Token: phony-secret machine # [ 7.739726] vault[754]: Development mode should NOT be used in production installations! machine: (finished: waiting for unit vault.service, in 8.86 seconds) machine # [ 8.244299] dhcpcd[745]: eth0: soliciting a DHCP lease machine: waiting for TCP port 8200 on localhost machine # [ 8.401156] NET: Registered PF_PACKET protocol family machine # [ 8.263592] dhcpcd[745]: eth0: offered 10.0.2.15 from 10.0.2.2 machine # [ 8.269335] dhcpcd[745]: eth0: probing address 10.0.2.15/24 machine # Connection to localhost (127.0.0.1) 8200 port [tcp/trivnet1] succeeded! machine: (finished: waiting for TCP port 8200 on localhost, in 0.04 seconds) machine: waiting for unit setup-vault-agent-approle.service machine # [ 8.566428] setup-vault-agent-approle-start[569]: + vault status machine # [ 8.647534] bao[753]: 2026-09-15T20:06:50.940Z [INFO] agent.auth.handler: authenticating machine # [ 8.649204] bao[753]: 2026-09-15T20:06:50.940Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1.63s machine # [ 8.697675] setup-vault-agent-approle-start[805]: Key Value machine # [ 8.699106] setup-vault-agent-approle-start[805]: --- ----- machine # [ 8.700254] setup-vault-agent-approle-start[805]: Seal Type shamir machine # [ 8.701363] setup-vault-agent-approle-start[805]: Initialized true machine # [ 8.702865] setup-vault-agent-approle-start[805]: Sealed false machine # [ 8.704407] setup-vault-agent-approle-start[805]: Total Shares 1 machine # [ 8.705966] setup-vault-agent-approle-start[805]: Threshold 1 machine # [ 8.707100] setup-vault-agent-approle-start[805]: Version 2.6.2 machine # [ 8.708244] setup-vault-agent-approle-start[805]: Storage Type inmem machine # [ 8.709384] setup-vault-agent-approle-start[805]: Cluster Name vault-cluster-e65de198 machine # [ 8.710935] setup-vault-agent-approle-start[805]: Cluster ID 3d4d0de5-7bed-6778-710a-f2c180b96057 machine # [ 8.712463] setup-vault-agent-approle-start[805]: HA Enabled false machine # [ 8.722754] setup-vault-agent-approle-start[569]: + cat machine # [ 8.728488] setup-vault-agent-approle-start[569]: + vault policy write demo /tmp/policy-file.hcl machine # [ 8.869601] setup-vault-agent-approle-start[814]: Success! Uploaded policy: demo machine # [ 8.875155] setup-vault-agent-approle-start[823]: ++ printf '\x00\x01binary\xff' machine # [ 8.876989] setup-vault-agent-approle-start[824]: ++ base64 machine # [ 8.879688] setup-vault-agent-approle-start[569]: + vault kv put secret/my-secret foo=bar blob=base64:AAFiaW5hcnn/ machine # [ 9.007138] setup-vault-agent-approle-start[825]: ==== Secret Path ==== machine # [ 9.009348] setup-vault-agent-approle-start[825]: secret/data/my-secret machine # [ 9.011474] setup-vault-agent-approle-start[825]: ======= Metadata ======= machine # [ 9.013632] setup-vault-agent-approle-start[825]: Key Value machine # [ 9.015816] setup-vault-agent-approle-start[825]: --- ----- machine # [ 9.018064] setup-vault-agent-approle-start[825]: created_time 2026-09-15T20:06:51.297630424Z machine # [ 9.020802] setup-vault-agent-approle-start[825]: custom_metadata machine # [ 9.022988] setup-vault-agent-approle-start[825]: deletion_time n/a machine # [ 9.025182] setup-vault-agent-approle-start[825]: destroyed false machine # [ 9.027325] setup-vault-agent-approle-start[825]: version 1 machine # [ 9.029442] setup-vault-agent-approle-start[569]: + vault auth enable approle machine # [ 9.117659] vault[754]: 2026-09-15T20:06:51.410Z [INFO] core: enabled credential backend: namespace="" path=approle/ type=approle version="" machine # [ 9.121699] setup-vault-agent-approle-start[833]: Success! Enabled approle auth method at: approle/ machine # [ 9.125737] setup-vault-agent-approle-start[569]: + vault write auth/approle/role/role1 bind_secret_id=true token_policies=demo machine # [ 9.241787] setup-vault-agent-approle-start[841]: Success! Data written to: auth/approle/role/role1 machine # [ 9.250276] setup-vault-agent-approle-start[850]: ++ jq -r .data.role_id machine # [ 9.252894] setup-vault-agent-approle-start[849]: ++ vault read -format json auth/approle/role/role1/role-id machine # [ 9.402120] setup-vault-agent-approle-start[569]: + echo -n ec58417f-9972-39ca-ca6a-1457cb27c865 machine # [ 9.405514] setup-vault-agent-approle-start[863]: ++ vault write -force -format json auth/approle/role/role1/secret-id machine # [ 9.406843] setup-vault-agent-approle-start[864]: ++ jq -r .data.secret_id machine # [ 9.410801] dhcpcd[745]: eth0: soliciting an IPv6 router machine # [ 9.412729] dhcpcd[745]: eth0: Router Advertisement from fe80::2 machine # [ 9.414808] dhcpcd[745]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine # [ 9.417084] dhcpcd[745]: eth0: adding route to fec0::/64 machine # [ 9.418957] dhcpcd[745]: eth0: adding default route via fe80::2 machine # [ 9.525657] setup-vault-agent-approle-start[569]: + echo -n 1ae7ece4-63eb-9154-4435-82956f8d4697 machine # [ 9.530786] systemd[1]: Finished setup-vault-agent-approle.service. machine # [ 10.287171] bao[753]: 2026-09-15T20:06:52.579Z [INFO] agent.auth.handler: authenticating machine # [ 10.300427] bao[753]: 2026-09-15T20:06:52.593Z [INFO] agent.auth.handler: authentication successful, sending token to sinks machine # [ 10.302356] bao[753]: 2026-09-15T20:06:52.593Z [INFO] agent.template.server: template server received new token machine # [ 10.303495] bao[753]: 2026-09-15T20:06:52.593Z [INFO] agent: (runner) stopping machine # [ 10.304974] bao[753]: 2026-09-15T20:06:52.593Z [INFO] agent: (runner) creating new runner (dry: false, once: false) machine # [ 10.306878] bao[753]: 2026-09-15T20:06:52.593Z [INFO] agent: (runner) creating watcher machine # [ 10.308455] bao[753]: 2026-09-15T20:06:52.594Z [INFO] agent: (runner) starting machine # [ 10.310062] bao[753]: 2026-09-15T20:06:52.596Z [INFO] agent.auth.handler: starting renewal process machine # [ 10.311165] bao[753]: 2026-09-15T20:06:52.600Z [INFO] agent.auth.handler: renewed auth token machine # [ 10.332770] bao[753]: 2026-09-15T20:06:52.625Z [INFO] agent: (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json" machine # [ 10.334842] systemd-vaultd[588]: 2026/09/15 20:06:52 Detected added file: 2670693248 machine # [ 10.336199] systemd-vaultd[588]: 2026/09/15 20:06:52 Detected added file: service1.service.json machine # [ 10.337313] systemd-vaultd[588]: 2026/09/15 20:06:52 Ignore unknown file: 2670693248 machine # [ 10.338299] systemd-vaultd[588]: 2026/09/15 20:06:52 Ignore unknown file: service1.service.json machine # [ 10.339298] systemd-vaultd[588]: 2026/09/15 20:06:52 Detected added file: 3110755908 machine # [ 10.340291] systemd-vaultd[588]: 2026/09/15 20:06:52 Ignore unknown file: 3110755908 machine # [ 10.341637] systemd-vaultd[588]: 2026/09/15 20:06:52 Detected added file: service1.service.EnvironmentFile machine # [ 10.342826] systemd-vaultd[588]: 2026/09/15 20:06:52 Served service1.service.EnvironmentFile to @c6c961e45f2f2fa7/unit/service1-envfile.service/service1.service.EnvironmentFile machine # [ 10.344077] bao[753]: 2026-09-15T20:06:52.626Z [INFO] agent: (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.EnvironmentFile" machine # [ 10.345321] bao[753]: 2026-09-15T20:06:52.626Z [INFO] agent: (runner) executing command "[\"systemctl try-reload-or-restart service1.service\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json" machine # [ 10.346585] bao[753]: 2026-09-15T20:06:52.627Z [INFO] agent: (child) spawning: /bin/sh -c systemctl try-reload-or-restart service1.service machine # [ 10.347810] bao[753]: 2026-09-15T20:06:52.628Z [WARN] agent: (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 1 after "250ms") machine # [ 10.361061] systemd[1]: Finished service1-envfile.service. machine # [ 10.369571] systemd[1]: Starting service1.service... machine # [ 10.381515] systemd-vaultd[588]: 2026/09/15 20:06:52 Systemd requested secret for service1.service/blob machine # [ 10.382504] systemd-vaultd[588]: 2026/09/15 20:06:52 Systemd requested secret for service1.service/foo machine: (finished: waiting for unit setup-vault-agent-approle.service, in 2.10 seconds) machine: waiting for unit vault-agent-default.service machine # [ 10.394449] systemd[1]: Started service1.service. machine # [ 10.406087] systemd[1]: service1.service: Deactivated successfully. machine # [ 10.408351] systemd[1]: service1-envfile.service: Deactivated successfully. machine # [ 10.408976] systemd[1]: Stopped service1-envfile.service. machine: (finished: waiting for unit vault-agent-default.service, in 0.03 seconds) machine: waiting for success: grep -q bar /tmp/service1 machine: (finished: waiting for success: grep -q bar /tmp/service1, in 0.02 seconds) machine: must succeed: grep -q bar /tmp/service1-env machine: (finished: must succeed: grep -q bar /tmp/service1-env, in 0.01 seconds) machine: must succeed: printf '\x00\x01binary\xff' | cmp - /tmp/service1-blob machine: (finished: must succeed: printf '\x00\x01binary\xff' | cmp - /tmp/service1-blob, in 0.01 seconds) machine: must succeed: systemctl status service2 || : machine: (finished: must succeed: systemctl status service2 || :, in 0.02 seconds) machine: must succeed: vault kv put secret/blocking-secret foo=bar machine # [ 10.588700] bao[753]: 2026-09-15T20:06:52.881Z [WARN] agent: (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 2 after "500ms") machine: (finished: must succeed: vault kv put secret/blocking-secret foo=bar, in 0.12 seconds) machine: waiting for success: grep -q bar /tmp/service2 >&2 machine # grep: /tmp/service2: No such file or directory machine # [ 11.093368] systemd-vaultd[588]: 2026/09/15 20:06:53 Detected added file: 306096445 machine # [ 11.094528] systemd-vaultd[588]: 2026/09/15 20:06:53 Detected added file: service2.service.json machine # [ 11.095434] systemd-vaultd[588]: 2026/09/15 20:06:53 Ignore unknown file: 306096445 machine # [ 11.096268] bao[753]: 2026-09-15T20:06:53.386Z [INFO] agent: (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json" machine # [ 11.097117] bao[753]: 2026-09-15T20:06:53.386Z [INFO] agent: (runner) executing command "[\"systemctl try-reload-or-restart service2.service\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json" machine # [ 11.097909] systemd-vaultd[588]: 2026/09/15 20:06:53 Served service2.service.json to @49b36785c8c8afcb/unit/service2.service/secret machine # [ 11.102202] bao[753]: 2026-09-15T20:06:53.395Z [INFO] agent: (child) spawning: /bin/sh -c systemctl try-reload-or-restart service2.service machine # [ 11.145727] systemd-vaultd[588]: 2026/09/15 20:06:53 Systemd requested secret for service2.service/secret machine # [ 11.158833] systemd[1]: Started service2.service. machine # [ 11.165084] service2-start[927]: + true machine # [ 11.177588] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.178643] service2-start[929]: bar machine # [ 11.179167] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.179963] service2-start[927]: + sleep 0.1 machine # [ 11.278937] service2-start[927]: + true machine # [ 11.279656] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.284782] service2-start[932]: bar machine # [ 11.285382] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.288622] service2-start[927]: + sleep 0.1 machine # [ 11.394594] service2-start[927]: + true machine # [ 11.395282] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.400230] service2-start[935]: bar machine # [ 11.400741] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.404023] service2-start[927]: + sleep 0.1 machine # [ 11.513076] service2-start[927]: + true machine # [ 11.514158] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.517988] service2-start[938]: bar machine # [ 11.518882] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.521503] service2-start[927]: + sleep 0.1 machine # [ 11.626737] service2-start[927]: + true machine # [ 11.627384] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.630165] service2-start[944]: bar machine # [ 11.630705] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.635651] service2-start[927]: + sleep 0.1 machine: (finished: waiting for success: grep -q bar /tmp/service2 >&2, in 1.05 seconds) machine: must succeed: umount /run/credentials/service2.service machine: (finished: must succeed: umount /run/credentials/service2.service, in 0.01 seconds) machine: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json machine: (finished: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json, in 0.02 seconds) machine: must succeed: vault kv put secret/blocking-secret foo=reload machine # [ 11.741801] service2-start[927]: + true machine # [ 11.742824] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.745348] service2-start[965]: bar machine # [ 11.746235] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.748485] service2-start[927]: + sleep 0.1 machine: (finished: must succeed: vault kv put secret/blocking-secret foo=reload, in 0.15 seconds) machine: must succeed: systemctl restart vault-agent-default machine # [ 11.847439] bao[753]: ==> OpenBao Agent shutdown triggered machine # [ 11.848578] bao[753]: 2026-09-15T20:06:54.139Z [INFO] agent: (runner) stopping machine # [ 11.850154] systemd[1]: Stopping vault-agent-default.service... machine # [ 11.851145] bao[753]: 2026-09-15T20:06:54.139Z [INFO] agent.template.server: template server stopped machine # [ 11.851986] bao[753]: 2026-09-15T20:06:54.139Z [INFO] agent.auth.handler: shutdown triggered, stopping lifetime watcher machine # [ 11.852714] bao[753]: 2026-09-15T20:06:54.139Z [INFO] agent.auth.handler: auth handler stopped machine # [ 11.853437] bao[753]: 2026-09-15T20:06:54.139Z [INFO] agent.sink.server: sink server stopped machine # [ 11.854138] bao[753]: 2026-09-15T20:06:54.139Z [INFO] agent: sinks finished, exiting machine # [ 11.854763] bao[753]: 2026-09-15T20:06:54.139Z [INFO] agent.exec.server: exec server stopped machine # [ 11.855556] service2-start[927]: + true machine # [ 11.856149] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.857190] service2-start[972]: bar machine # [ 11.857840] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.858767] service2-start[927]: + sleep 0.1 machine # [ 11.861271] systemd[1]: vault-agent-default.service: Deactivated successfully. machine # [ 11.862788] systemd[1]: Stopped vault-agent-default.service. machine # [ 11.863420] systemd[1]: vault-agent-default.service: Consumed 210ms CPU time over 4.513s wall clock time, 18.4M memory peak, 5.5K incoming IP traffic, 3.8K outgoing IP traffic. machine # [ 11.867583] systemd[1]: Started vault-agent-default.service. machine: (finished: must succeed: systemctl restart vault-agent-default, in 0.04 seconds) machine: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2 machine # cat: /run/systemd-vaultd/secrets/service2.service.json: No such file or directory machine # [ 11.963574] service2-start[927]: + true machine # [ 11.964758] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.971463] service2-start[991]: bar machine # [ 11.972751] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 11.979144] service2-start[927]: + sleep 0.1 machine # [ 11.992878] bao[976]: ==> OpenBao Agent started! machine # [ 11.993576] bao[976]: ==> OpenBao Agent configuration: machine # [ 11.995417] bao[976]: Api Address 1: http://bufconn machine # [ 11.996085] bao[976]: Cgo: enabled machine # [ 11.996808] bao[976]: Log Level: machine # [ 11.997761] bao[976]: Version: OpenBao v2.6.2 machine # [ 11.998635] bao[976]: Version Sha: refs/tags/v2.6.2 machine # [ 11.999506] bao[976]: 2026-09-15T20:06:54.288Z [INFO] agent.exec.server: starting exec server machine # [ 12.000408] bao[976]: 2026-09-15T20:06:54.288Z [INFO] agent.auth.handler: starting auth handler machine # [ 12.001045] bao[976]: 2026-09-15T20:06:54.288Z [INFO] agent.exec.server: no env templates or exec config, exiting machine # [ 12.001627] bao[976]: 2026-09-15T20:06:54.288Z [INFO] agent.auth.handler: authenticating machine # [ 12.002514] bao[976]: 2026-09-15T20:06:54.288Z [INFO] agent.sink.server: starting sink server machine # [ 12.003413] bao[976]: 2026-09-15T20:06:54.288Z [INFO] agent.template.server: starting template server machine # [ 12.004047] bao[976]: 2026-09-15T20:06:54.288Z [INFO] agent: (runner) creating new runner (dry: false, once: false) machine # [ 12.007705] bao[976]: 2026-09-15T20:06:54.300Z [INFO] agent.auth.handler: authentication successful, sending token to sinks machine # [ 12.008867] bao[976]: 2026-09-15T20:06:54.300Z [INFO] agent.auth.handler: starting renewal process machine # [ 12.018447] bao[976]: 2026-09-15T20:06:54.311Z [INFO] agent: (runner) creating watcher machine # [ 12.019058] bao[976]: 2026-09-15T20:06:54.311Z [INFO] agent.template.server: template server received new token machine # [ 12.019472] bao[976]: 2026-09-15T20:06:54.311Z [INFO] agent: (runner) stopping machine # [ 12.019976] bao[976]: 2026-09-15T20:06:54.311Z [INFO] agent: (runner) creating new runner (dry: false, once: false) machine # [ 12.020586] bao[976]: 2026-09-15T20:06:54.311Z [INFO] agent: (runner) creating watcher machine # [ 12.021196] bao[976]: 2026-09-15T20:06:54.311Z [INFO] agent: (runner) starting machine # [ 12.021651] bao[976]: 2026-09-15T20:06:54.313Z [INFO] agent.auth.handler: renewed auth token machine # [ 12.031981] systemd-vaultd[588]: 2026/09/15 20:06:54 Detected added file: 1607132815 machine # [ 12.032540] systemd-vaultd[588]: 2026/09/15 20:06:54 Detected added file: service2.service.json machine # [ 12.033165] systemd-vaultd[588]: 2026/09/15 20:06:54 Ignore unknown file: 1607132815 machine # [ 12.033630] systemd-vaultd[588]: 2026/09/15 20:06:54 Ignore unknown file: service2.service.json machine # [ 12.034429] bao[976]: 2026-09-15T20:06:54.324Z [INFO] agent: (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json" machine # [ 12.034904] bao[976]: 2026-09-15T20:06:54.324Z [INFO] agent: (runner) executing command "[\"systemctl try-reload-or-restart service2.service\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json" machine # [ 12.035641] bao[976]: 2026-09-15T20:06:54.326Z [INFO] agent: (child) spawning: /bin/sh -c systemctl try-reload-or-restart service2.service machine # [ 12.043975] systemd[1]: Reloading service2.service... machine # [ 12.068359] systemd[1]: Reloaded service2.service. machine # [ 12.088290] service2-start[927]: + true machine # [ 12.088583] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.092920] service2-start[1004]: reload machine # [ 12.093665] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.096880] service2-start[927]: + sleep 0.1 machine # [ 12.203054] service2-start[927]: + true machine # [ 12.204425] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.210751] service2-start[1007]: reload machine # [ 12.212105] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.214217] service2-start[927]: + sleep 0.1 machine # [ 12.322275] service2-start[927]: + true machine # [ 12.323739] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.330542] service2-start[1010]: reload machine # [ 12.331889] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.338429] service2-start[927]: + sleep 0.1 machine # [ 12.446091] service2-start[927]: + true machine # [ 12.447544] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.454246] service2-start[1013]: reload machine # [ 12.455578] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.462104] service2-start[927]: + sleep 0.1 machine # [ 12.572362] service2-start[927]: + true machine # [ 12.573914] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.579093] service2-start[1016]: reload machine # [ 12.580403] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.587268] service2-start[927]: + sleep 0.1 machine # [ 12.692818] service2-start[927]: + true machine # [ 12.694287] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.701131] service2-start[1019]: reload machine # [ 12.702449] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.704346] service2-start[927]: + sleep 0.1 machine # [ 12.812919] service2-start[927]: + true machine # [ 12.814450] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.821347] service2-start[1022]: reload machine # [ 12.822709] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.829263] service2-start[927]: + sleep 0.1 machine # machine # {"secret":"reload"} machine: (finished: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2, in 1.05 seconds) machine: must succeed: systemctl restart service2 machine # [ 12.940516] service2-start[927]: + true machine # [ 12.941525] service2-start[927]: + cat /run/service2/secrets/secret machine # [ 12.942760] systemd[1]: Stopping service2.service... machine # [ 12.944822] systemd[1]: service2.service: Deactivated successfully. machine # [ 12.945917] systemd[1]: Stopped service2.service. machine # [ 12.951444] systemd[1]: Starting service2.service... machine # [ 12.967886] systemd-vaultd[588]: 2026/09/15 20:06:55 Systemd requested secret for service2.service/secret machine # [ 13.004825] systemd-vaultd[588]: 2026/09/15 20:06:55 Systemd requested secret for service2.service/secret machine # [ 13.017848] systemd[1]: Started service2.service. machine: (finished: must succeed: systemctl restart service2, in 0.10 seconds) machine: must succeed: rm /tmp/service2 machine # [ 13.024554] service2-start[1040]: + true machine # [ 13.025786] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.029711] service2-start[1044]: reload machine # [ 13.030909] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.034622] service2-start[1040]: + sleep 0.1 machine: (finished: must succeed: rm /tmp/service2, in 0.02 seconds) machine: waiting for success: grep -q reload /tmp/service2 >&2 machine # grep: /tmp/service2: No such file or directory machine # [ 13.125703] dhcpcd[745]: eth0: leased 10.0.2.15 for 86400 seconds machine # [ 13.127192] dhcpcd[745]: eth0: adding route to 10.0.2.0/24 machine # [ 13.128330] dhcpcd[745]: eth0: adding default route via 10.0.2.2 machine # [ 13.144325] service2-start[1040]: + true machine # [ 13.145706] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.151797] service2-start[1057]: reload machine # [ 13.153133] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.159164] service2-start[1040]: + sleep 0.1 machine # [ 13.266890] systemd[1]: Started DHCP Client. machine # [ 13.268503] systemd[1]: Reached target Multi-User System. machine # [ 13.270306] systemd[1]: Startup finished in 1.004s (kernel) + 3.573s (initrd) + 8.690s (userspace) = 13.268s. machine # [ 13.272214] service2-start[1040]: + true machine # [ 13.273269] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.275830] service2-start[1111]: reload machine # [ 13.277258] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.283837] service2-start[1040]: + sleep 0.1 machine # [ 13.394264] service2-start[1040]: + true machine # [ 13.395723] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.402617] service2-start[1114]: reload machine # [ 13.403906] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.410417] service2-start[1040]: + sleep 0.1 machine # [ 13.521063] service2-start[1040]: + true machine # [ 13.522474] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.529275] service2-start[1117]: reload machine # [ 13.530657] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.537529] service2-start[1040]: + sleep 0.1 machine # [ 13.647995] service2-start[1040]: + true machine # [ 13.649454] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.656242] service2-start[1120]: reload machine # [ 13.657552] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.664407] service2-start[1040]: + sleep 0.1 machine # [ 13.774310] service2-start[1040]: + true machine # [ 13.775068] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.778433] service2-start[1123]: reload machine # [ 13.778685] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.785781] service2-start[1040]: + sleep 0.1 machine # [ 13.891050] service2-start[1040]: + true machine # [ 13.891946] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.894887] service2-start[1127]: reload machine # [ 13.895511] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 13.902168] service2-start[1040]: + sleep 0.1 machine # [ 14.007548] service2-start[1040]: + true machine # [ 14.008663] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 14.014950] service2-start[1130]: reload machine # [ 14.015886] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 14.019090] service2-start[1040]: + sleep 0.1 machine: (finished: waiting for success: grep -q reload /tmp/service2 >&2, in 1.06 seconds) machine: must succeed: stat -c %u /run/service2/secrets/secret machine: (finished: must succeed: stat -c %u /run/service2/secrets/secret, in 0.02 seconds) machine: must succeed: stat -c %g /run/service2/secrets/secret machine # [ 14.123471] service2-start[1040]: + true machine # [ 14.124521] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 14.127791] service2-start[1142]: reload machine # [ 14.128577] service2-start[1040]: + cat /run/service2/secrets/secret machine # [ 14.134162] service2-start[1040]: + sleep 0.1 machine: (finished: must succeed: stat -c %g /run/service2/secrets/secret, in 0.02 seconds) machine: must succeed: stat -c %a /run/service2/secrets/secret machine: (finished: must succeed: stat -c %a /run/service2/secrets/secret, in 0.02 seconds) (finished: run the VM test script, in 15.00 seconds) test script finished in 15.05s cleanup kill QemuMachine (pid 46) machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14) machine # [2026-09-15T20:06:57Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-15T20:06:57Z INFO virtiofsd] Client disconnected, shutting down machine # [2026-09-15T20:06:57Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.20 seconds) additionally exposed symbols: machine, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh ● service2.service Loaded: loaded (/etc/systemd/system/service2.service; enabled; preset: ignored) Active: activating (start-pre) since Tue 2026-09-15 20:06:49 UTC; 3s ago Job: 275 Invocation: b50908127e6d4fe2b7826b1676c94611 Cntrl PID: 565 ((service2-pre-)) IP: 0B in, 0B out IO: 0B read, 0B written Tasks: 1 (limit: 1110) Memory: 1.1M (peak: 1.5M) CPU: 6ms CGroup: /system.slice/service2.service └─565 "(service2-pre-start)" Sep 15 20:06:49 machine systemd[1]: Starting service2.service...