tribuchet: building on eliza
Machine state will be reset. To keep it, pass --keep-vm-state
start all VLans
start vlan
running vlan (pid 5; ctl /build/vde1.ctl)
(finished: start all VLans, in 0.00 seconds)
run the VM test script
start all VMs
server: starting vm
server: QEMU running (pid 6)
server # Disk image do not exist, creating the virtualisation disk image...
server # Formatting '/build/vm-state-server/tmp.T5Q2LqcCIS', fmt=raw size=1073741824
server # mke2fs 1.47.0 (5-Feb-2023)
(finished: start all VMs, in 0.36 seconds)
server # Discarding device blocks:      0/262144             done
server: waiting for unit vault.service
server # Creating filesystem with 262144 4k blocks and 65536 inodes
server: waiting for the VM to finish booting
server # Filesystem UUID: 1beb2050-ce03-4307-ad64-a41f26d11858
server # Superblock backups stored on blocks:
server # 	32768, 98304, 163840, 229376
server # 
server # Allocating group tables: 0/8   done
server # Writing inode tables: 0/8   done
server # Creating journal (8192 blocks): done
server # Writing superblocks and filesystem accounting information: 0/8   done
server # 
server # Virtualisation disk image created.
server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]
server # [    0.000000] Linux version 6.1.60 (nixbld@localhost) (gcc (GCC) 12.3.0, GNU ld (GNU Binutils) 2.40) #1-NixOS SMP Wed Oct 25 10:03:17 UTC 2023
server # [    0.000000] random: crng init done
server # [    0.000000] Machine model: linux,dummy-virt
server # [    0.000000] efi: UEFI not found.
server # [    0.000000] NUMA: No NUMA configuration found
server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] NUMA: NODE_DATA [mem 0x7fdf49c0-0x7fdf7fff]
server # [    0.000000] Zone ranges:
server # [    0.000000]   DMA      [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000]   DMA32    empty
server # [    0.000000]   Normal   empty
server # [    0.000000]   Device   empty
server # [    0.000000] Movable zone start for each node
server # [    0.000000] Early memory node ranges
server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] cma: Reserved 32 MiB at 0x000000007cc00000
server # [    0.000000] psci: probing for conduit method from DT.
server # [    0.000000] psci: PSCIv1.3 detected in firmware.
server # [    0.000000] psci: Using standard PSCI v0.2 function IDs
server # [    0.000000] psci: Trusted OS migration not required
server # [    0.000000] psci: SMC Calling Convention v1.1
server # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)
server # [    0.000000] percpu: Embedded 30 pages/cpu s84008 r8192 d30680 u122880
server # [    0.000000] Detected PIPT I-cache on CPU0
server # [    0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)
server # [    0.000000] CPU features: detected: GIC system register CPU interface
server # [    0.000000] CPU features: detected: Spectre-v4
server # [    0.000000] alternatives: applying boot alternatives
server # [    0.000000] Fallback order for Node 0: 0
server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 258048
server # [    0.000000] Policy zone: DMA
server # [    0.000000] Kernel command line: console=ttyAMA0 panic=1 boot.panic_on_fail clock=acpi_pm loglevel=7 net.ifnames=0 init=/nix/store/frrpvxcpgflaskajs4v60085nq9f8qd9-nixos-system-server-23.11pre-git/init regInfo=/nix/store/sc0vfplxqzhw4khz77zydhyzgfnbifql-closure-info/registration console=ttyAMA0
server # [    0.000000] clocksource: clock= boot option is deprecated - use clocksource=xyz
server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/sc0vfplxqzhw4khz77zydhyzgfnbifql-closure-info/registration", will be passed to user space.
server # [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
server # [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
server # [    0.000000] Memory: 927548K/1048576K available (20544K kernel code, 5386K rwdata, 18796K rodata, 12032K init, 743K bss, 88260K reserved, 32768K cma-reserved)
server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
server # [    0.000000] ftrace: allocating 66065 entries in 259 pages
server # [    0.000000] ftrace: allocated 259 pages with 3 groups
server # [    0.000000] trace event string verifier disabled
server # [    0.000000] rcu: Hierarchical RCU implementation.
server # [    0.000000] rcu: 	RCU event tracing is enabled.
server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4.
server # [    0.000000] 	Rude variant of Tasks RCU enabled.
server # [    0.000000] 	Tracing variant of Tasks RCU enabled.
server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
server # [    0.000000] GICv3: 256 SPIs implemented
server # [    0.000000] GICv3: 0 Extended SPIs implemented
server # [    0.000000] Root IRQ handler: gic_handle_irq
server # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI
server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000
server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @43e20000 (indirect, esz 8, psz 64K, shr 1)
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @43e30000 (flat, esz 8, psz 64K, shr 1)
server # [    0.000000] GICv3: using LPI property table @0x0000000043e40000
server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000043e50000
server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
server # [    0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).
server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
server # [    0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns
server # [    0.000022] arm-pv: using stolen time PV
server # [    0.000091] Console: colour dummy device 80x25
server # [    0.000110] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)
server # [    0.000113] pid_max: default: 32768 minimum: 301
server # [    0.000133] LSM: Security Framework initializing
server # [    0.000138] landlock: Up and running.
server # [    0.000139] Yama: becoming mindful.
server # [    0.000146] LSM support for eBPF active
server # [    0.000162] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
server # [    0.000164] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
server # [    0.000504] cblist_init_generic: Setting adjustable number of callback queues.
server # [    0.000505] cblist_init_generic: Setting shift to 2 and lim to 1.
server # [    0.000516] cblist_init_generic: Setting adjustable number of callback queues.
server # [    0.000517] cblist_init_generic: Setting shift to 2 and lim to 1.
server # [    0.000549] rcu: Hierarchical SRCU implementation.
server # [    0.000549] rcu: 	Max phase no-delay instances is 1000.
server # [    0.000856] Platform MSI: its@8080000 domain created
server # [    0.000864] PCI/MSI: /intc@8000000/its@8080000 domain created
server # [    0.000873] fsl-mc MSI: its@8080000 domain created
server # [    0.000969] EFI services will not be available.
server # [    0.001009] smp: Bringing up secondary CPUs ...
server # [    0.001458] Detected PIPT I-cache on CPU1
server # [    0.001597] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000
server # [    0.001723] GICv3: CPU1: using allocated LPI pending table @0x0000000043e60000
server # [    0.001836] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40]
server # [    0.002304] Detected PIPT I-cache on CPU2
server # [    0.002426] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000
server # [    0.002551] GICv3: CPU2: using allocated LPI pending table @0x0000000043e70000
server # [    0.002665] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40]
server # [    0.003044] Detected PIPT I-cache on CPU3
server # [    0.003175] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000
server # [    0.003301] GICv3: CPU3: using allocated LPI pending table @0x0000000043e80000
server # [    0.003414] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40]
server # [    0.003532] smp: Brought up 1 node, 4 CPUs
server # [    0.003534] SMP: Total of 4 processors activated.
server # [    0.003537] CPU features: detected: Branch Target Identification
server # [    0.003539] CPU features: detected: ARMv8.4 Translation Table Level
server # [    0.003540] CPU features: detected: Instruction cache invalidation not required for I/D coherence
server # [    0.003541] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
server # [    0.003542] CPU features: detected: Common not Private translations
server # [    0.003543] CPU features: detected: CRC32 instructions
server # [    0.003544] CPU features: detected: E0PD
server # [    0.003545] CPU features: detected: Enhanced Counter Virtualization
server # [    0.003547] CPU features: detected: Generic authentication (architected QARMA5 algorithm)
server # [    0.003548] CPU features: detected: RCpc load-acquire (LDAPR)
server # [    0.003549] CPU features: detected: LSE atomic instructions
server # [    0.003551] CPU features: detected: Privileged Access Never
server # [    0.003552] CPU features: detected: RAS Extension Support
server # [    0.003552] CPU features: detected: Random Number Generator
server # [    0.003553] CPU features: detected: Speculation barrier (SB)
server # [    0.003554] CPU features: detected: Stage-2 Force Write-Back
server # [    0.003555] CPU features: detected: TLB range maintenance instructions
server # [    0.003557] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
server # [    0.003660] CPU: All CPU(s) started at EL1
server # [    0.003665] alternatives: applying system-wide alternatives
server # [    0.007432] devtmpfs: initialized
server # [    0.008060] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
server # [    0.008064] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
server # [    0.008180] pinctrl core: initialized pinctrl subsystem
server # [    0.008413] DMI not present or invalid.
server # [    0.008543] NET: Registered PF_NETLINK/PF_ROUTE protocol family
server # [    0.009195] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
server # [    0.009231] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
server # [    0.009266] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
server # [    0.009279] audit: initializing netlink subsys (disabled)
server # [    0.009352] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1
server # [    0.009485] thermal_sys: Registered thermal governor 'step_wise'
server # [    0.009487] thermal_sys: Registered thermal governor 'power_allocator'
server # [    0.009497] cpuidle: using governor ladder
server # [    0.009500] cpuidle: using governor menu
server # [    0.009562] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.
server # [    0.009804] ASID allocator initialised with 65536 entries
server # [    0.010047] Serial: AMBA PL011 UART driver
server # [    0.011983] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1
server # [    0.118186] printk: console [ttyAMA0] enabled
server # [    0.119072] KASLR enabled
server # [    0.141028] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
server # [    0.141848] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
server # [    0.142572] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
server # [    0.143354] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
server # [    0.144098] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
server # [    0.144878] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
server # [    0.145606] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
server # [    0.146410] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
server # [    0.149334] ACPI: Interpreter disabled.
server # [    0.150171] iommu: Default domain type: Translated
server # [    0.150762] iommu: DMA domain TLB invalidation policy: strict mode
server # [    0.151563] SCSI subsystem initialized
server # [    0.152138] usbcore: registered new interface driver usbfs
server # [    0.152805] usbcore: registered new interface driver hub
server # [    0.153449] usbcore: registered new device driver usb
server # [    0.154187] pps_core: LinuxPPS API ver. 1 registered
server # [    0.154781] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
server # [    0.155864] PTP clock support registered
server # [    0.156391] EDAC MC: Ver: 3.0.0
server # [    0.157081] FPGA manager framework
server # [    0.157531] Advanced Linux Sound Architecture Driver Initialized.
server # [    0.158461] vgaarb: loaded
server # [    0.159827] clocksource: Switched to clocksource arch_sys_counter
server # [    0.160677] VFS: Disk quotas dquot_6.6.0
server # [    0.161166] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
server # [    0.162037] pnp: PnP ACPI: disabled
server # [    0.164463] NET: Registered PF_INET protocol family
server # [    0.165082] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
server # [    0.166220] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
server # [    0.167231] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
server # [    0.168202] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
server # [    0.169319] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
server # [    0.170255] TCP: Hash tables configured (established 8192 bind 8192)
server # [    0.171049] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)
server # [    0.171946] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
server # [    0.172740] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
server # [    0.173602] NET: Registered PF_UNIX/PF_LOCAL protocol family
server # [    0.174467] RPC: Registered named UNIX socket transport module.
server # [    0.175177] RPC: Registered udp transport module.
server # [    0.175740] RPC: Registered tcp transport module.
server # [    0.176353] RPC: Registered tcp NFSv4.1 backchannel transport module.
server # [    0.177120] NET: Registered PF_XDP protocol family
server # [    0.177695] PCI: CLS 0 bytes, default 64
server # [    0.178320] Trying to unpack rootfs image as initramfs...
server # [    0.190231] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 counters available
server # [    0.191265] kvm [1]: HYP mode not available
server # [    0.192107] Initialise system trusted keyrings
server # [    0.192764] workingset: timestamp_bits=42 max_order=18 bucket_order=0
server # [    0.194736] zbud: loaded
server # [    0.195400] squashfs: version 4.0 (2009/01/31) Phillip Lougher
server # [    0.196338] NFS: Registering the id_resolver key type
server # [    0.196954] Key type id_resolver registered
server # [    0.197460] Key type id_legacy registered
server # [    0.197967] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
server # [    0.198761] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
server # [    0.199723] 9p: Installing v9fs 9p2000 file system support
server # [    0.210090] Key type asymmetric registered
server # [    0.210586] Asymmetric key parser 'x509' registered
server # [    0.291327] Freeing initrd memory: 9012K
server # [    0.292846] alg: self-tests for CTR-KDF (hmac(sha256)) passed
server # [    0.294979] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
server # [    0.297892] io scheduler mq-deadline registered
server # [    0.299519] io scheduler kyber registered
server # [    0.305427] pl061_gpio 9030000.pl061: PL061 GPIO chip registered
server # [    0.308423] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:
server # [    0.311087] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
server # [    0.314342] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
server # [    0.317304] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000
server # [    0.320018] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits
server # [    0.322463] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
server # [    0.325131] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00
server # [    0.327012] pci_bus 0000:00: root bus resource [bus 00-ff]
server # [    0.328479] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
server # [    0.330062] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]
server # [    0.331714] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]
server # [    0.333629] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000
server # [    0.335610] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000
server # [    0.337175] pci 0000:00:01.0: reg 0x10: [io  0x0000-0x001f]
server # [    0.338432] pci 0000:00:01.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.339924] pci 0000:00:01.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.341484] pci 0000:00:01.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]
server # [    0.343492] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00
server # [    0.344853] pci 0000:00:02.0: reg 0x10: [io  0x0000-0x001f]
server # [    0.345995] pci 0000:00:02.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.347321] pci 0000:00:02.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.349431] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200
server # [    0.350643] pci 0000:00:03.0: reg 0x10: [io  0x0000-0x003f]
server # [    0.351705] pci 0000:00:03.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.353072] pci 0000:00:03.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.354962] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200
server # [    0.356120] pci 0000:00:04.0: reg 0x10: [io  0x0000-0x001f]
server # [    0.357134] pci 0000:00:04.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.358345] pci 0000:00:04.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.360140] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200
server # [    0.361232] pci 0000:00:05.0: reg 0x10: [io  0x0000-0x001f]
server # [    0.362191] pci 0000:00:05.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.363322] pci 0000:00:05.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.365086] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
server # [    0.366116] pci 0000:00:06.0: reg 0x10: [io  0x0000-0x007f]
server # [    0.367010] pci 0000:00:06.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.368111] pci 0000:00:06.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.369743] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000
server # [    0.370713] pci 0000:00:07.0: reg 0x10: [io  0x0000-0x001f]
server # [    0.371580] pci 0000:00:07.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.372692] pci 0000:00:07.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.373802] pci 0000:00:07.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]
server # [    0.375225] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000
server # [    0.376208] pci 0000:00:08.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.377209] pci 0000:00:08.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.378676] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000
server # [    0.379574] pci 0000:00:09.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.380577] pci 0000:00:09.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.381934] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320
server # [    0.382778] pci 0000:00:0a.0: reg 0x10: [mem 0x00000000-0x00000fff]
server # [    0.383926] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000
server # [    0.384851] pci 0000:00:0b.0: reg 0x10: [io  0x0000-0x003f]
server # [    0.385615] pci 0000:00:0b.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.386528] pci 0000:00:0b.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.387925] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00
server # [    0.388793] pci 0000:00:0c.0: reg 0x10: [io  0x0000-0x001f]
server # [    0.389571] pci 0000:00:0c.0: reg 0x14: [mem 0x00000000-0x00000fff]
server # [    0.390506] pci 0000:00:0c.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]
server # [    0.392035] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref]
server # [    0.393002] pci 0000:00:07.0: BAR 6: assigned [mem 0x10040000-0x1007ffff pref]
server # [    0.393971] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref]
server # [    0.395084] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref]
server # [    0.396235] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref]
server # [    0.397350] pci 0000:00:04.0: BAR 4: assigned [mem 0x800000c000-0x800000ffff 64bit pref]
server # [    0.398473] pci 0000:00:05.0: BAR 4: assigned [mem 0x8000010000-0x8000013fff 64bit pref]
server # [    0.399588] pci 0000:00:06.0: BAR 4: assigned [mem 0x8000014000-0x8000017fff 64bit pref]
server # [    0.400784] pci 0000:00:07.0: BAR 4: assigned [mem 0x8000018000-0x800001bfff 64bit pref]
server # [    0.401914] pci 0000:00:08.0: BAR 4: assigned [mem 0x800001c000-0x800001ffff 64bit pref]
server # [    0.403041] pci 0000:00:09.0: BAR 4: assigned [mem 0x8000020000-0x8000023fff 64bit pref]
server # [    0.404211] pci 0000:00:0b.0: BAR 4: assigned [mem 0x8000024000-0x8000027fff 64bit pref]
server # [    0.405364] pci 0000:00:0c.0: BAR 4: assigned [mem 0x8000028000-0x800002bfff 64bit pref]
server # [    0.406494] pci 0000:00:01.0: BAR 1: assigned [mem 0x10080000-0x10080fff]
server # [    0.407413] pci 0000:00:02.0: BAR 1: assigned [mem 0x10081000-0x10081fff]
server # [    0.408380] pci 0000:00:03.0: BAR 1: assigned [mem 0x10082000-0x10082fff]
server # [    0.409300] pci 0000:00:04.0: BAR 1: assigned [mem 0x10083000-0x10083fff]
server # [    0.410222] pci 0000:00:05.0: BAR 1: assigned [mem 0x10084000-0x10084fff]
server # [    0.411142] pci 0000:00:06.0: BAR 1: assigned [mem 0x10085000-0x10085fff]
server # [    0.412088] pci 0000:00:07.0: BAR 1: assigned [mem 0x10086000-0x10086fff]
server # [    0.413011] pci 0000:00:08.0: BAR 1: assigned [mem 0x10087000-0x10087fff]
server # [    0.413936] pci 0000:00:09.0: BAR 1: assigned [mem 0x10088000-0x10088fff]
server # [    0.414857] pci 0000:00:0a.0: BAR 0: assigned [mem 0x10089000-0x10089fff]
server # [    0.415784] pci 0000:00:0b.0: BAR 1: assigned [mem 0x1008a000-0x1008afff]
server # [    0.416792] pci 0000:00:0c.0: BAR 1: assigned [mem 0x1008b000-0x1008bfff]
server # [    0.417724] pci 0000:00:06.0: BAR 0: assigned [io  0x1000-0x107f]
server # [    0.418560] pci 0000:00:03.0: BAR 0: assigned [io  0x1080-0x10bf]
server # [    0.419397] pci 0000:00:0b.0: BAR 0: assigned [io  0x10c0-0x10ff]
server # [    0.420272] pci 0000:00:01.0: BAR 0: assigned [io  0x1100-0x111f]
server # [    0.421101] pci 0000:00:02.0: BAR 0: assigned [io  0x1120-0x113f]
server # [    0.421932] pci 0000:00:04.0: BAR 0: assigned [io  0x1140-0x115f]
server # [    0.422760] pci 0000:00:05.0: BAR 0: assigned [io  0x1160-0x117f]
server # [    0.423589] pci 0000:00:07.0: BAR 0: assigned [io  0x1180-0x119f]
server # [    0.424475] pci 0000:00:0c.0: BAR 0: assigned [io  0x11a0-0x11bf]
server # [    0.425335] pci 0000:00:0a.0: enabling device (0000 -> 0002)
server # [    0.427051] EINJ: ACPI disabled.
server # [    0.430914] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
server # [    0.433371] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
server # [    0.435746] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
server # [    0.438331] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
server # [    0.440827] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)
server # [    0.443326] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)
server # [    0.446025] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)
server # [    0.449036] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)
server # [    0.451259] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)
server # [    0.453804] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)
server # [    0.456223] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)
server # [    0.460170] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
server # [    0.461785] SuperH (H)SCI(F) driver initialized
server # [    0.462465] msm_serial: driver initialized
server # [    0.473385] loop: module loaded
server # [    0.473958] virtio_blk virtio5: 4/0/0 default/read/poll queues
server # [    0.475816] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
server # [    0.478835] megasas: 07.719.03.00-rc1
server # [    0.479579] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]
server # [    0.483871] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    0.485041] Intel/Sharp Extended Query Table at 0x0031
server # [    0.487737] Using buffer write method
server # [    0.488279] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]
server # [    0.491410] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    0.493530] Intel/Sharp Extended Query Table at 0x0031
server # [    0.496014] Using buffer write method
server # [    0.496487] Concatenating MTD devices:
server # [    0.496944] (0): "0.flash"
server # [    0.497277] (1): "0.flash"
server # [    0.497618] into device "0.flash"
server # [    0.500020] tun: Universal TUN/TAP device driver, 1.6
server # [    0.503036] thunder_xcv, ver 1.0
server # [    0.503459] thunder_bgx, ver 1.0
server # [    0.503892] nicpf, ver 1.0
server # [    0.504449] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version
server # [    0.505316] hns3: Copyright (c) 2017 Huawei Corporation.
server # [    0.505980] hclge is initializing
server # [    0.506390] e1000: Intel(R) PRO/1000 Network Driver
server # [    0.506976] e1000: Copyright (c) 1999-2006 Intel Corporation.
server # [    0.507676] e1000e: Intel(R) PRO/1000 Network Driver
server # [    0.508317] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
server # [    0.509044] igb: Intel(R) Gigabit Ethernet Network Driver
server # [    0.509697] igb: Copyright (c) 2007-2014 Intel Corporation.
server # [    0.510379] igbvf: Intel(R) Gigabit Virtual Function Network Driver
server # [    0.511134] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
server # [    0.511921] sky2: driver version 1.30
server # [    0.512606] VFIO - User Level meta-driver version: 0.3
server # [    0.514145] ehci-pci 0000:00:0a.0: EHCI Host Controller
server # [    0.514795] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1
server # [    0.515817] ehci-pci 0000:00:0a.0: irq 16, io mem 0x10089000
server # [    0.531877] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00
server # [    0.532776] hub 1-0:1.0: USB hub found
server # [    0.533242] hub 1-0:1.0: 6 ports detected
server # [    0.534099] usbcore: registered new interface driver usb-storage
server # [    0.535386] rtc-pl031 9010000.pl031: registered as rtc0
server # [    0.536065] rtc-pl031 9010000.pl031: setting system clock to 2026-07-29T05:20:56 UTC (1785302456)
server # [    0.537245] i2c_dev: i2c /dev entries driver
server # [    0.538867] sdhci: Secure Digital Host Controller Interface driver
server # [    0.539617] sdhci: Copyright(c) Pierre Ossman
server # [    0.540316] Synopsys Designware Multimedia Card Interface Driver
server # [    0.541195] sdhci-pltfm: SDHCI platform and OF driver helper
server # [    0.542342] ledtrig-cpu: registered to indicate activity on CPUs
server # [    0.543378] hid: raw HID events driver (C) Jiri Kosina
server # [    0.544100] usbcore: registered new interface driver usbhid
server # [    0.544767] usbhid: USB HID core driver
server # [    0.546127] drop_monitor: Initializing network drop monitor service
server # [    0.560874] NET: Registered PF_INET6 protocol family
server # [    0.562900] Segment Routing with IPv6
server # [    0.563358] In-situ OAM (IOAM) with IPv6
server # [    0.563869] NET: Registered PF_PACKET protocol family
server # [    0.564639] 9pnet: Installing 9P2000 support
server # [    0.566961] Key type dns_resolver registered
server # [    0.567954] registered taskstats version 1
server # [    0.568471] Loading compiled-in X.509 certificates
server # [    0.569283] zswap: loaded using pool lzo/zbud
server # [    0.570147] Key type .fscrypt registered
server # [    0.570623] Key type fscrypt-provisioning registered
server # [    0.575187] input: gpio-keys as /devices/platform/gpio-keys/input/input0
server # [    0.576363] ALSA device list:
server # [    0.576749]   No soundcards found.
server # [    0.577261] uart-pl011 9000000.pl011: no DMA platform data
server # [    0.581787] Freeing unused kernel memory: 12032K
server # [    0.596135] Run /init as init process
server # 
server # <<< NixOS Stage 1 >>>
server # 
server # loading module virtio_balloon...
server # loading module virtio_console...
server # loading module virtio_rng...
server # loading module dm_mod...
server # [    0.697090] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com
server # running udev...
server # Starting systemd-udevd version 254.3
server # [    0.799862] usb 1-1: new high-speed USB device number 2 using ehci-pci
server # kbd_mode: KDSKBMODE: Inappropriate ioctl for device
server # Gstarting device mapper and LVM...
server # checking /dev/disk/by-label/nixos...
server # fsck (busybox 1.36.1)
server # [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/disk/by-label/nixos
server # nixos: clean, 11/65536 files, 12955/262144 blocks
server # mounting /dev/disk/by-label/nixos on /...
server # [    0.944330] EXT4-fs (vda): mounted filesystem with ordered data mode. Quota mode: none.
server # [    0.949735] EXT4-fs (vda): re-mounted. Quota mode: none.
server # [    0.970314] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1
server # mounting nix-store on /nix/.ro-store...
server # mounting tmpfs on /nix/.rw-store...
server # mounting shared on /tmp/shared...
server # [    1.032691] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0
server # mounting xchg on /tmp/xchg...
server # mounting overlay filesystem on /nix/store...
server # [    1.171922] usb 1-2: new high-speed USB device number 3 using ehci-pci
server # [    1.337690] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2
server # 
server # <<< NixOS Stage 2 >>>[    1.343981] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0
server # 
server # 
server # [    1.369209] EXT4-fs (vda): re-mounted. Quota mode: none.
server # [    1.373000] booting system configuration /nix/store/frrpvxcpgflaskajs4v60085nq9f8qd9-nixos-system-server-23.11pre-git
server # running activation script...
server # setting up /etc...
server # starting systemd...
server # [    3.146355] systemd[1]: systemd 254.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
server # [    3.151674] systemd[1]: Detected virtualization qemu.
server # [    3.152675] systemd[1]: Detected architecture arm64.
server # [    3.157207] systemd[1]: Initializing machine ID from random generator.
server # [    3.246958] systemd[1]: bpf-lsm: Failed to link program; assuming BPF LSM is not available
server # [    3.757619] systemd[1]: Queued start job for default target Multi-User System.
server # [    3.781611] systemd[1]: Created slice Slice /system/getty.
server # [    3.782745] systemd[1]: Created slice Slice /system/modprobe.
server # [    3.783803] systemd[1]: Created slice User and Session Slice.
server # [    3.784647] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
server # [    3.785665] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
server # [    3.786777] systemd[1]: Reached target Local Encrypted Volumes.
server # [    3.787521] systemd[1]: Reached target Containers.
server # [    3.788166] systemd[1]: Reached target Path Units.
server # [    3.788763] systemd[1]: Reached target Remote File Systems.
server # [    3.789444] systemd[1]: Reached target Slice Units.
server # [    3.790059] systemd[1]: Reached target Swaps.
server # [    3.793716] systemd[1]: Listening on Process Core Dump Socket.
server # [    3.794846] systemd[1]: Listening on Journal Socket (/dev/log).
server # [    3.795671] systemd[1]: Listening on Journal Socket.
server # [    3.796790] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
server # [    3.798642] systemd[1]: Listening on udev Control Socket.
server # [    3.799503] systemd[1]: Listening on udev Kernel Socket.
server # [    3.801475] systemd[1]: Mounting Huge Pages File System...
server # [    3.803445] systemd[1]: Mounting POSIX Message Queue File System...
server # [    3.805641] systemd[1]: Mounting Kernel Debug File System...
server # [    3.808561] systemd[1]: Starting Create List of Static Device Nodes...
server # [    3.810682] systemd[1]: Starting Load Kernel Module configfs...
server # [    3.812757] systemd[1]: Starting Load Kernel Module drm...
server # [    3.814824] systemd[1]: Starting Load Kernel Module efi_pstore...
server # [    3.817003] systemd[1]: Starting Load Kernel Module fuse...
server # [    3.818962] systemd[1]: Starting mount-pstore.service...
server # [    3.819119] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
server # [    3.824557] systemd[1]: Starting Journal Service...
server # [    3.826633] systemd[1]: Starting Load Kernel Modules...
server # [    3.828725] systemd[1]: Starting Remount Root and Kernel File Systems...
server # [    3.832574] systemd[1]: Starting Coldplug All udev Devices...
server # [    3.844514] systemd[1]: Finished Create List of Static Device Nodes.
server # [    3.846139] systemd[1]: Mounted Huge Pages File System.
server # [    3.847339] systemd[1]: Mounted POSIX Message Queue File System.
server # [    3.848526] systemd[1]: Mounted Kernel Debug File System.
server # [    3.850860] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
server # [    3.856877] systemd[1]: modprobe@configfs.service: Deactivated successfully.
server # [    3.859001] systemd[1]: Finished Load Kernel Module configfs.
server # [    3.860499] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
server # [    3.861723] systemd[1]: Finished Load Kernel Module efi_pstore.
server # [    3.864358] systemd[1]: Mounting Kernel Configuration File System...
server # [    3.877466] systemd[1]: Mounted Kernel Configuration File System.
server # [    3.879441] EXT4-fs (vda): re-mounted. Quota mode: none.
server # [    3.890884] fuse: init (API version 7.37)
server # [    3.893941] systemd-journald[537]: Collecting audit messages is disabled.
server # [    3.896095] systemd[1]: Finished Remount Root and Kernel File Systems.
server # [    3.901573] systemd[1]: modprobe@fuse.service: Deactivated successfully.
server # [    3.905299] systemd[1]: Finished Load Kernel Module fuse.
server # [    3.935396] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
server # [    3.938690] systemd[1]: Mounting FUSE Control File System...
server # [    3.941626] systemd[1]: Starting Load/Save OS Random Seed...
server # [    3.944307] systemd[1]: modprobe@drm.service: Deactivated successfully.
server # [    3.946586] systemd[1]: Finished Load Kernel Module drm.
server # [    3.929397] systemd-modules-load[538]: Inserted module 'bridge'[    3.949735] systemd[1]: Started Journal Service.
server # 
server # [    3.938847] systemd-modules-load[538]: Inserted module 'macvlan'
server # [    3.941576] systemd-modules-load[538]: Inserted module 'tap'
server # [    3.944042] systemd-modules-load[538]: Module 'tun' is built in
server # [    3.946737] systemd-modules-load[538]: Module 'loop' is built in
server # [    3.949425] systemd-modules-load[538]: Module 'atkbd' is built in
server # [    3.953156] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [    3.954496] systemd[1]: Finished Load Kernel Modules.
server # [    3.955457] systemd[1]: Mounted FUSE Control File System.
server # [    3.956443] systemd[1]: Starting Firewall...
server # [    3.957449] systemd[1]: Starting Flush Journal to Persistent Storage...
server # [    3.958569] systemd[1]: Starting Apply Kernel Variables...
server # [    3.959681] systemd[1]: Starting Create Static Device Nodes in /dev...
server # [    3.979129] systemd[1]: Finished Load/Save OS Random Seed.
server # [    3.999112] systemd-journald[537]: Received client request to flush runtime journal.
server # [    3.997037] systemd[1]: Finished Flush Journal to Persistent Storage.
server # [    4.007348] systemd[1]: Finished Coldplug All udev Devices.
server # [    4.014535] systemd[1]: Finished Apply Kernel Variables.
server # [    4.016987] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [    4.017925] systemd[1]: Reached target Preparation for Local File Systems.
server # [    4.018908] systemd[1]: Reached target Local File Systems.
server # [    4.036512] systemd[1]: Starting Create Volatile Files and Directories...
server # [    4.038421] systemd[1]: Starting Rule-based Manager for Device Events and Files...
server # [    4.074243] systemd-udevd[589]: Using default interface naming scheme 'v253'.
server # [    4.087776] systemd[1]: Finished Create Volatile Files and Directories.
server # [    4.100513] systemd[1]: Starting Rebuild Journal Catalog...
server # [    4.103593] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
server # [    4.106901] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
server # [    4.138869] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
server # [    4.143168] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [    4.151188] systemd-oomd[599]: No swap; memory pressure usage will be degraded
server # [    4.154399] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
server # [    4.159501] systemd[1]: Finished Rebuild Journal Catalog.
server # [    4.177710] systemd[1]: Starting Update is Completed...
server # [    4.180448] systemd[1]: Found device /dev/ttyAMA0.
server # [    4.189922] systemd[1]: Found device /dev/hvc0.
server # [    4.203484] systemd[1]: Finished Update is Completed.
server # [    4.206675] (udev-worker)[610]: Network interface NamePolicy= disabled on kernel command line.
server # [    4.212784] (udev-worker)[615]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
server # [    4.216717] (udev-worker)[615]: Network interface NamePolicy= disabled on kernel command line.
server # [    4.237699] mousedev: PS/2 mouse device common for all mice
server # [    4.261157] systemd[1]: Found device Virtio network device.
server # [    4.309793] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3
server # [    4.343313] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0
server # [    4.344778] [drm] features: -virgl +edid -resource_blob -host_visible
server # [    4.344781] [drm] features: -context_init
server # [    4.347334] [drm] number of scanouts: 1
server # [    4.348310] [drm] number of cap sets: 0
server # [    4.349872] [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:09.0 on minor 0
server # [    4.360012] fbcon: Deferring console take-over
server # [    4.360986] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device
server # [    4.380443] systemd[1]: Starting Virtual Console Setup...
server # [    4.704347] systemd[1]: Finished Virtual Console Setup.
server # [    4.742486] systemd[1]: Finished Firewall.
server # [    5.602252] w8yz32d54jbh5dlnl3mr061y6flfa0wm-mount-pstore.sh[543]: Persistent Storage backend was not registered in time.
server # [    5.607604] systemd[1]: Finished mount-pstore.service.
server # [    5.610333] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
server # [    5.615771] systemd[1]: Reached target System Initialization.
server # [    5.618237] systemd[1]: Started logrotate.timer.
server # [    5.620148] systemd[1]: Started Daily Cleanup of Temporary Directories.
server # [    5.622755] systemd[1]: Reached target Timer Units.
server # [    5.624787] systemd[1]: Listening on D-Bus System Message Bus Socket.
server # [    5.627318] systemd[1]: Listening on Nix Daemon Socket.
server # [    5.629500] systemd[1]: Starting systemd-vaultd socket...
server # [    5.645482] systemd[1]: Listening on systemd-vaultd socket.
server # [    5.648038] systemd[1]: Reached target Socket Units.
server # [    5.650117] systemd[1]: Reached target Basic System.
server # [    5.652154] systemd[1]: Starting Kernel Auditing...
server # [    5.655847] systemd[1]: Started backdoor.service.
server # [    5.660294] systemd[1]: Starting D-Bus System Message Bus...
server # [    5.663955] systemd[1]: Starting DHCP Client...
server # [    5.668339] systemd[1]: Starting Logrotate configuration check...
server # [    5.671781] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [    5.675856] systemd[1]: Started Reset console on configuration changes.
server # [    5.679563] systemd[1]: Starting resolvconf update...
server # [    5.683722] systemd[1]: Starting service1-envfile.service...
server # [    5.687223] systemd[1]: Starting service2.service...
server # [    5.692298] systemd[1]: Starting setup-vault-agent-approle.service...
server # [    5.699219] systemd[1]: Started systemd-vaultd daemon.
server # [    5.703989] logrotate[772]: warning: logrotate in debug mode does nothing except printing debug messages!  Consider using verbose mode (-v) instead if this is not what you want.
server # [    5.712133] logrotate[772]: reading config file /nix/store/qb85sn99mapdxc37vcclnv3fx3dlqv0q-logrotate.conf
server # connecting to host...
server # [    5.718235] rrll6i65cnvqwxannbj95lzl20a8y5dv-audit-disable[778]: No rules
server # [    5.722677] logrotate[772]: note: 'monthly' overrides previously specified 'weekly'
server # [    5.726553] logrotate[772]: Reading state from file: /var/lib/logrotate.status
server # [    5.731816] logrotate[772]: state file /var/lib/logrotate.status does not exist
server # [    5.736954] logrotate[772]: Allocating hash table for state file, size 64 entries
server # [    5.743805] logrotate[772]: Handling 2 logs
server # [    5.746925] logrotate[772]: rotating pattern: "/var/log/btmp"  monthly (1 rotations)
server # [    5.752240] logrotate[772]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed
server # [    5.759431] logrotate[772]: considering log /var/log/btmp
server # [    5.764841] logrotate[772]: Creating new state
server: Guest shell says: b'Spawning backdoor root shell...\n'
server: connected to guest root shell
server # [    5.768911] logrotate[772]:   Now: 2026-07-29 05:21
server: (connecting took 5.95 seconds)
server # [    5.773669] logrotate[772]:   Last rotated at 2026-07-29 05:00
(finished: waiting for the VM to finish booting, in 5.95 seconds)
server # [    5.777928] logrotate[772]:   log does not need rotating (log has already been rotated)
server # [    5.785441] logrotate[772]: rotating pattern: "/var/log/wtmp"  monthly (1 rotations)
server # [    5.790210] logrotate[772]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed
server # [    5.797432] logrotate[772]: considering log /var/log/wtmp
server # [    5.801040] logrotate[772]: Creating new state
server # [    5.805061] logrotate[772]:   Now: 2026-07-29 05:21
server # [    5.824558] 8021q: 802.1Q VLAN Support v1.8
server # [    5.808851] logrotate[772]:   Last rotated at 2026-07-29 05:00
server # [    5.814881] logrotate[772]:   log does not need rotating (log has already been rotated)
server # [    5.820799] setup-vault-agent-approle-start[779]: + vault status
server # [    5.826206] systemd-vaultd[782]: 2026/07/29 05:21:01 Use unix socket received from systemd
server # [    5.832613] systemd-vaultd[782]: 2026/07/29 05:21:01 Listening on /run/systemd-vaultd/sock
server # [    5.840199] systemd-vaultd[782]: 2026/07/29 05:21:01 Systemd requested secret for service2.service/secret
server # [    5.846849] systemd-vaultd[782]: 2026/07/29 05:21:01 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service
server # [    5.856792] systemd-vaultd[782]: 2026/07/29 05:21:01 Block start until service1.service.EnvironmentFile appears
server # [    5.863645] systemd-vaultd[782]: 2026/07/29 05:21:01 Block start until service2.service.json appears
server # [    5.870565] systemd[1]: Finished Logrotate configuration check.
server # [    5.875618] nsncd[773]: Jul 29 05:21:01.871 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"
server # [    5.882063] dhcpcd[771]: dhcpcd-9.4.1 starting
server # [    5.896444] dbus-daemon[770]: dbus[770]: Unknown username "systemd-timesync" in message bus configuration file
server # [    5.898777] systemd[1]: Finished Kernel Auditing.
server # [    5.900155] dhcpcd[791]: dev: loaded udev
server # [    5.901429] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [    5.902929] dbus-daemon[770]: [system] Successfully activated service 'org.freedesktop.systemd1'
server # [    5.904576] systemd[1]: Reached target Host and Network Name Lookups.
server # [    5.906181] systemd[1]: Reached target User and Group Name Lookups.
server # [    5.907612] systemd[1]: Starting User Login Management...
server # [    5.909038] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[    5.927102] cfg80211: Loading compiled-in X.509 certificates for regulatory database
server # 
server # [    5.914785] systemd[1]: Stopped target Host and Network Name Lookups.
server # [    5.916824] systemd[1]: Stopping Host and Network Name Lookups...
server # [    5.918838] systemd[1]: Stopped target User and Group Name Lookups.
server # [    5.921190] nsncd[858]: Jul 29 05:21:02.026 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"
server # [    5.928332] systemd[1]: Stopping User and Group Name Lookups...
server # [    5.930950] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
server # [    5.934077] systemd-logind[817]: New seat seat0.
server # [    5.935747] systemd-logind[817]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
server # [    5.938956] systemd-logind[817]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)[    5.955803] 8021q: adding VLAN 0 to HW filter on device eth1
server # 
server # [    5.944792] systemd-logind[817]: Watching system buttons on /dev/input/event0 (gpio-keys)
server # [    5.947250] systemd[1]: Started User Login Management.[    5.963290] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
server # 
server # [    5.951052] network-addresses-eth1-start[866]: adding address 192.168.1.1/24... done[    5.968466] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
server # 
server # [    5.971721] cfg80211: failed to load regulatory.db
server # [    5.957972] systemd[1]: nscd.service: Deactivated successfully.
server # [    5.960120] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
server # [    5.961941] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [    5.964022] systemd[1]: Started D-Bus System Message Bus.
server # [    5.965613] systemd[1]: Finished resolvconf update.
server # [    5.966959] systemd[1]: Reached target Preparation for Network.
server # [    5.968713] systemd[1]: Reached target All Network Interfaces (deprecated).
server # [    5.970743] systemd[1]: Starting Address configuration of eth1...
server # [    5.972517] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [    5.974339] systemd[1]: Reached target Host and Network Name Lookups.
server # [    5.976105] systemd[1]: Reached target User and Group Name Lookups.
server # [    5.977858] systemd[1]: Finished Address configuration of eth1.
server # [    5.988572] systemd[1]: Starting Networking Setup...
server # [    6.022464] 8021q: adding VLAN 0 to HW filter on device eth0
server # [    6.007613] dhcpcd[791]: eth0: waiting for carrier
server # [    6.008529] dhcpcd[791]: eth0: carrier acquired
server # [    6.018185] dhcpcd[791]: DUID 00:01:00:01:31:fc:4a:3e:52:54:00:12:34:56
server # [    6.019295] dhcpcd[791]: eth0: IAID 00:12:34:56
server # [    6.020024] dhcpcd[791]: eth0: adding address fe80::5054:ff:fe12:3456
server # [    6.094177] systemd[1]: Finished Networking Setup.
server # [    6.116880] systemd[1]: Starting Extra networking commands....
server # [    6.130045] systemd[1]: Finished Extra networking commands..
server # [    6.130916] systemd[1]: Reached target Network.
server # [    6.133614] systemd[1]: Starting Permit User Sessions...
server # [    6.136276] systemd[1]: Started vault-agent-default.service.
server # [    6.140959] systemd[1]: Started Vault server daemon.
server # [    6.155040] systemd[1]: Finished Permit User Sessions.
server # [    6.158294] systemd[1]: Started Getty on tty1.
server # [    6.161090] systemd[1]: Reached target Login Prompts.
server # [    6.541013] setup-vault-agent-approle-start[798]: 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
server # [    6.545872] vault[946]: ==> Vault server configuration:
server # [    6.547327] vault[946]: Administrative Namespace:
server # [    6.548632] vault[946]:              Api Address: http://127.0.0.1:8200
server # [    6.550392] vault[946]:                      Cgo: enabled
server # [    6.551592] vault[946]:          Cluster Address: https://127.0.0.1:8201
server # [    6.553048] vault[946]:    Environment Variables: GODEBUG, HOME, INVOCATION_ID, JOURNAL_STREAM, LANG, LOCALE_ARCHIVE, LOGNAME, MEMORY_PRESSURE_WATCH, MEMORY_PRESSURE_WRITE, PATH, PWD, SHELL, SHLVL, STATE_DIRECTORY, SYSTEMD_EXEC_PID, TZDIR, USER
server # [    6.556959] vault[946]:               Go Version: go1.21.3
server # [    6.557986] vault[946]:               Listener 1: tcp (addr: "127.0.0.1:8200", cluster address: "127.0.0.1:8201", max_request_duration: "1m30s", max_request_size: "33554432", tls: "disabled")
server # [    6.561859] vault[946]:                Log Level:
server # [    6.562885] vault[946]:                    Mlock: supported: true, enabled: false
server # [    6.564351] vault[946]:            Recovery Mode: false
server # [    6.565549] vault[946]:                  Storage: inmem
server # [    6.566810] vault[946]:                  Version: Vault v1.14.4
server # [    6.568198] setup-vault-agent-approle-start[779]: + sleep 1
server # [    6.659827] dhcpcd[791]: eth0: soliciting a DHCP lease
server # [    6.677105] dhcpcd[791]: eth0: offered 10.0.2.15 from 10.0.2.2
server # [    6.679521] dhcpcd[791]: eth0: leased 10.0.2.15 for 86400 seconds
server # [    6.682511] dhcpcd[791]: eth0: adding route to 10.0.2.0/24
server # [    6.685156] dhcpcd[791]: eth0: adding default route via 10.0.2.2
server # [    6.823975] systemd[1]: Stopped target Host and Network Name Lookups.
server # [    6.826757] systemd[1]: Stopping Host and Network Name Lookups...
server # [    6.830677] systemd[1]: Stopped target User and Group Name Lookups.
server # [    6.834736] systemd[1]: Stopping User and Group Name Lookups...
server # [    6.837910] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
server # [    6.842569] systemd[1]: nscd.service: Deactivated successfully.
server # [    6.844879] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
server # [    6.872983] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [    6.877001] vault[946]: ==> Vault server started! Log data will stream in below:
server # [    6.881854] vault[946]: 2026-07-29T05:21:02.648Z [INFO]  proxy environment: http_proxy="" https_proxy="" no_proxy=""
server # [    6.888664] vault[946]: 2026-07-29T05:21:02.650Z [WARN]  no `api_addr` value specified in config or in VAULT_API_ADDR; falling back to detection if possible, but this value should be manually set
server # [    6.896963] vault[946]: 2026-07-29T05:21:02.653Z [INFO]  core: Initializing version history cache for core
server # [    6.902614] vault[946]: 2026-07-29T05:21:02.654Z [INFO]  core: security barrier not initialized
server # [    6.906602] vault[946]: 2026-07-29T05:21:02.655Z [INFO]  core: security barrier initialized: stored=1 shares=1 threshold=1
server # [    6.910846] vault[946]: 2026-07-29T05:21:02.659Z [INFO]  core: post-unseal setup starting
server # [    6.913982] vault[946]: 2026-07-29T05:21:02.667Z [INFO]  core: loaded wrapping token key
server # [    6.916750] vault[946]: 2026-07-29T05:21:02.667Z [INFO]  core: successfully setup plugin catalog: plugin-directory=""
server # [    6.920092] vault[946]: 2026-07-29T05:21:02.670Z [INFO]  core: no mounts; adding default mount table
server # [    6.922806] vault[946]: 2026-07-29T05:21:02.673Z [INFO]  core: successfully mounted: type=cubbyhole version="v1.14.4+builtin.vault" path=cubbyhole/ namespace="ID: root. Path: "
server # [    6.927283] vault[946]: 2026-07-29T05:21:02.678Z [INFO]  core: successfully mounted: type=system version="v1.14.4+builtin.vault" path=sys/ namespace="ID: root. Path: "
server # [    6.931403] vault[946]: 2026-07-29T05:21:02.680Z [INFO]  core: successfully mounted: type=identity version="v1.14.4+builtin.vault" path=identity/ namespace="ID: root. Path: "
server # [    6.935563] vault[946]: 2026-07-29T05:21:02.684Z [INFO]  core: successfully mounted: type=token version="v1.14.4+builtin.vault" path=token/ namespace="ID: root. Path: "
server # [    6.939288] vault[946]: 2026-07-29T05:21:02.685Z [INFO]  rollback: starting rollback manager
server # [    6.941317] vault[946]: 2026-07-29T05:21:02.687Z [INFO]  core: restoring leases
server # [    6.942956] vault[946]: 2026-07-29T05:21:02.688Z [INFO]  identity: entities restored
server # [    6.944692] vault[946]: 2026-07-29T05:21:02.688Z [INFO]  identity: groups restored
server # [    6.946487] vault[946]: 2026-07-29T05:21:02.689Z [INFO]  expiration: lease restore complete
server # [    6.948817] vault[946]: 2026-07-29T05:21:02.689Z [INFO]  core: Recorded vault version: vault version=1.14.4 upgrade time="2026-07-29 05:21:02.68974152 +0000 UTC" build date=""
server # [    6.954496] vault[946]: 2026-07-29T05:21:02.939Z [INFO]  core: post-unseal setup complete
server # [    6.957522] vault[946]: 2026-07-29T05:21:02.940Z [INFO]  core: root token generated
server # [    6.959407] vault[946]: 2026-07-29T05:21:02.940Z [INFO]  core: pre-seal teardown starting
server # [    6.962013] vault[946]: 2026-07-29T05:21:02.941Z [INFO]  rollback: stopping rollback manager
server # [    6.964363] vault[946]: 2026-07-29T05:21:02.942Z [INFO]  core: pre-seal teardown complete
server # [    6.967008] vault[946]: 2026-07-29T05:21:02.943Z [INFO]  core.cluster-listener.tcp: starting listener: listener_address=127.0.0.1:8201
server # [    6.971408] vault[946]: 2026-07-29T05:21:02.943Z [INFO]  core.cluster-listener: serving cluster requests: cluster_listen_address=127.0.0.1:8201
server # [    6.975047] vault[946]: 2026-07-29T05:21:02.943Z [INFO]  core: post-unseal setup starting
server # [    6.977871] vault[946]: 2026-07-29T05:21:02.943Z [INFO]  core: loaded wrapping token key
server # [    6.979895] vault[946]: 2026-07-29T05:21:02.943Z [INFO]  core: successfully setup plugin catalog: plugin-directory=""
server # [    6.982678] vault[946]: 2026-07-29T05:21:02.945Z [INFO]  core: successfully mounted: type=system version="v1.14.4+builtin.vault" path=sys/ namespace="ID: root. Path: "
server # [    6.987061] vault[946]: 2026-07-29T05:21:02.945Z [INFO]  core: successfully mounted: type=identity version="v1.14.4+builtin.vault" path=identity/ namespace="ID: root. Path: "
server # [    6.992141] vault[946]: 2026-07-29T05:21:02.945Z [INFO]  core: successfully mounted: type=cubbyhole version="v1.14.4+builtin.vault" path=cubbyhole/ namespace="ID: root. Path: "
server # [    6.997494] vault[946]: 2026-07-29T05:21:02.946Z [INFO]  core: successfully mounted: type=token version="v1.14.4+builtin.vault" path=token/ namespace="ID: root. Path: "
server # [    7.002887] vault[946]: 2026-07-29T05:21:02.947Z [INFO]  rollback: starting rollback manager
server # [    7.006413] vault[946]: 2026-07-29T05:21:02.947Z [INFO]  core: restoring leases
server # [    7.008566] vault[946]: 2026-07-29T05:21:02.947Z [INFO]  expiration: lease restore complete
server # [    7.010630] vault[946]: 2026-07-29T05:21:02.947Z [INFO]  identity: entities restored
server # [    7.012814] vault[946]: 2026-07-29T05:21:02.947Z [INFO]  identity: groups restored
server # [    7.014602] vault[946]: 2026-07-29T05:21:02.948Z [INFO]  core: post-unseal setup complete
server # [    7.016558] vault[946]: 2026-07-29T05:21:02.948Z [INFO]  core: vault is unsealed
server # [    7.018341] vault[946]: 2026-07-29T05:21:02.965Z [INFO]  expiration: revoked lease: lease_id=auth/token/root/h9935c16ef683591acfa429d8e0b380b5195df23c50dad65352d0eb1e309d3809
server # [    7.021961] vault[946]: 2026-07-29T05:21:02.982Z [INFO]  core: successful mount: namespace="" path=secret/ type=kv version=""
server # [    7.025246] dhcpcd[791]: eth0: soliciting an IPv6 router
server # [    7.027378] vault[945]: ==> Vault Agent started! Log data will stream in below:
server # [    7.030033] vault[945]: ==> Vault Agent configuration:
(finished: waiting for unit vault.service, in 7.22 seconds)
server # [    7.031327] vault[945]:            Api Address 1: http://bufconn
server: waiting for TCP port 8200 on localhost
server # [    7.032687] vault[945]:                      Cgo: enabled
server # [    7.033846] vault[945]:                Log Level:
server # [    7.034859] vault[945]:                  Version: Vault v1.14.4
server # [    7.036164] vault[945]: 2026-07-29T05:21:02.992Z [INFO]  agent.exec.server: starting exec server
server # [    7.037919] vault[945]: 2026-07-29T05:21:02.992Z [INFO]  agent.exec.server: no env templates or exec config, exiting
server # [    7.039956] vault[945]: 2026-07-29T05:21:02.992Z [INFO]  agent.auth.handler: starting auth handler
server # [    7.041962] vault[945]: 2026-07-29T05:21:02.992Z [INFO]  agent.auth.handler: authenticating
server # [    7.044319] vault[945]: 2026-07-29T05:21:02.992Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1s
server # [    7.049135] vault[945]: 2026-07-29T05:21:02.992Z [INFO]  agent.sink.server: starting sink server
server # [    7.052443] vault[945]: 2026-07-29T05:21:02.992Z [INFO]  agent.template.server: starting template server
server # [    7.055122] vault[945]: 2026-07-29T05:21:02.994Z [INFO] (runner) creating new runner (dry: false, once: false)
server # [    7.058057] vault[945]: 2026-07-29T05:21:02.997Z [INFO] (runner) creating watcher
server # [    7.060289] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [    7.062207] nsncd[1014]: Jul 29 05:21:03.009 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"
server # [    7.066425] dhcpcd[771]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.
server # [    7.068359] dhcpcd[771]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.
server # [    7.070755] dhcpcd[771]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.
server # [    7.073363] vault[946]: WARNING! dev mode is enabled! In this mode, Vault runs entirely in-memory
server # [    7.076156] vault[946]: and starts unsealed with a single unseal key. The root token is already
server # [    7.078722] vault[946]: authenticated to the CLI, so you can immediately begin using Vault.
server # [    7.081489] vault[946]: You may need to set the following environment variables:
server # [    7.083732] vault[946]:     $ export VAULT_ADDR='http://127.0.0.1:8200'
server # [    7.085866] vault[946]: The unseal key and root token are displayed below in case you want to
server # [    7.088636] vault[946]: seal/unseal the Vault or re-authenticate.
server # [    7.090650] vault[946]: Unseal Key: xNUFq2lm8YnE4J1nUBiDcNU6fm0HYryHG0pVV6lKeSo=
server # [    7.092941] vault[946]: Root Token: phony-secret
server # [    7.094208] vault[946]: Development mode should NOT be used in production installations!
server # [    7.096322] systemd[1]: Reached target Host and Network Name Lookups.
server # [    7.098393] systemd[1]: Reached target User and Group Name Lookups.
server # [    7.100438] systemd[1]: Started DHCP Client.
server # [    7.101707] systemd[1]: Reached target Network is Online.
server # Connection to localhost (127.0.0.1) 8200 port [tcp/trivnet1] succeeded!
(finished: waiting for TCP port 8200 on localhost, in 0.08 seconds)
server: waiting for unit setup-vault-agent-approle.service
server # [    7.554886] setup-vault-agent-approle-start[779]: + vault status
server # [    7.684288] setup-vault-agent-approle-start[1045]: Key             Value
server # [    7.686916] setup-vault-agent-approle-start[1045]: ---             -----
server # [    7.689530] setup-vault-agent-approle-start[1045]: Seal Type       shamir
server # [    7.692620] setup-vault-agent-approle-start[1045]: Initialized     true
server # [    7.695667] setup-vault-agent-approle-start[1045]: Sealed          false
server # [    7.698885] setup-vault-agent-approle-start[1045]: Total Shares    1
server # [    7.701933] setup-vault-agent-approle-start[1045]: Threshold       1
server # [    7.704855] setup-vault-agent-approle-start[1045]: Version         1.14.4
server # [    7.708194] setup-vault-agent-approle-start[1045]: Build Date      n/a
server # [    7.710776] setup-vault-agent-approle-start[1045]: Storage Type    inmem
server # [    7.713736] setup-vault-agent-approle-start[1045]: Cluster Name    vault-cluster-14cca698
server # [    7.717599] setup-vault-agent-approle-start[1045]: Cluster ID      4cc08e35-94ae-5164-ffd1-bd93fc877a25
server # [    7.721147] setup-vault-agent-approle-start[1045]: HA Enabled      false
server # [    7.723692] setup-vault-agent-approle-start[779]: + cat
server # [    7.725777] setup-vault-agent-approle-start[779]: + vault policy write demo /tmp/policy-file.hcl
server # [    7.838259] setup-vault-agent-approle-start[1054]: Success! Uploaded policy: demo
server # [    7.846000] setup-vault-agent-approle-start[779]: + vault kv put secret/my-secret foo=bar
server # [    7.885577] vault[945]: 2026-07-29T05:21:03.994Z [INFO]  agent.auth.handler: authenticating
server # [    7.888875] vault[945]: 2026-07-29T05:21:03.994Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1.73s
server # [    7.949117] setup-vault-agent-approle-start[1062]: ==== Secret Path ====
server # [    7.951717] setup-vault-agent-approle-start[1062]: secret/data/my-secret
server # [    7.954598] setup-vault-agent-approle-start[1062]: ======= Metadata =======
server # [    7.957723] setup-vault-agent-approle-start[1062]: Key                Value
server # [    7.960882] setup-vault-agent-approle-start[1062]: ---                -----
server # [    7.963892] setup-vault-agent-approle-start[1062]: created_time       2026-07-29T05:21:04.0546492Z
server # [    7.967765] setup-vault-agent-approle-start[1062]: custom_metadata    <nil>
server # [    7.971227] setup-vault-agent-approle-start[1062]: deletion_time      n/a
server # [    7.974005] setup-vault-agent-approle-start[1062]: destroyed          false
server # [    7.976648] setup-vault-agent-approle-start[1062]: version            1
server # [    7.979143] setup-vault-agent-approle-start[779]: + vault auth enable approle
server # [    8.061302] dhcpcd[791]: eth0: Router Advertisement from fe80::2
server # [    8.063868] dhcpcd[791]: eth0: adding address fec0::5054:ff:fe12:3456/64
server # [    8.066609] dhcpcd[791]: eth0: adding route to fec0::/64
server # [    8.068710] dhcpcd[791]: eth0: adding default route via fe80::2
server # [    8.085404] vault[946]: 2026-07-29T05:21:04.193Z [INFO]  core: enabled credential backend: path=approle/ type=approle version=""
server # [    8.090107] setup-vault-agent-approle-start[1070]: Success! Enabled approle auth method at: approle/
server # [    8.095217] setup-vault-agent-approle-start[779]: + vault write auth/approle/role/role1 bind_secret_id=true token_policies=demo
server # [    8.208821] setup-vault-agent-approle-start[1079]: Success! Data written to: auth/approle/role/role1
server # [    8.222030] setup-vault-agent-approle-start[1091]: ++ vault read -format json auth/approle/role/role1/role-id
server # [    8.228885] setup-vault-agent-approle-start[1092]: ++ jq -r .data.role_id
server # [    8.345067] setup-vault-agent-approle-start[779]: + echo -n e84ee7a2-f221-230a-473b-a442280e1a0b
server # [    8.350101] setup-vault-agent-approle-start[1101]: ++ vault write -force -format json auth/approle/role/role1/secret-id
server # [    8.356112] setup-vault-agent-approle-start[1102]: ++ jq -r .data.secret_id
server # [    8.447713] setup-vault-agent-approle-start[779]: + echo -n 964008b0-0fa2-a4ef-c0c7-e218949c0f3e
server # [    8.452132] systemd[1]: Finished setup-vault-agent-approle.service.
(finished: waiting for unit setup-vault-agent-approle.service, in 2.24 seconds)
server: waiting for unit vault-agent-default.service
(finished: waiting for unit vault-agent-default.service, in 0.08 seconds)
server: waiting for success: grep -q bar /tmp/service1
server # grep: /tmp/service1: No such file or directory
server # [    9.619377] vault[945]: 2026-07-29T05:21:05.727Z [INFO]  agent.auth.handler: authenticating
server # [    9.636042] vault[945]: 2026-07-29T05:21:05.744Z [INFO]  agent.auth.handler: authentication successful, sending token to sinks
server # [    9.641542] vault[945]: 2026-07-29T05:21:05.744Z [INFO]  agent.auth.handler: starting renewal process
server # [    9.645858] vault[945]: 2026-07-29T05:21:05.744Z [INFO]  agent.template.server: template server received new token
server # [    9.650155] vault[945]: 2026-07-29T05:21:05.745Z [INFO] (runner) stopping
server # [    9.652828] vault[945]: 2026-07-29T05:21:05.745Z [INFO] (runner) creating new runner (dry: false, once: false)
server # [    9.656736] vault[945]: 2026-07-29T05:21:05.745Z [INFO] (runner) creating watcher
server # [    9.659576] vault[945]: 2026-07-29T05:21:05.746Z [INFO] (runner) starting
server # [    9.663029] vault[945]: 2026-07-29T05:21:05.753Z [INFO]  agent.auth.handler: renewed auth token
server # [    9.668076] vault[945]: 2026-07-29T05:21:05.767Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 1 after "250ms")
server # [    9.676497] vault[945]: 2026-07-29T05:21:05.769Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json"
server # [    9.682739] vault[945]: 2026-07-29T05:21:05.771Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.EnvironmentFile"
server # [    9.689200] vault[945]: 2026-07-29T05:21:05.771Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service1.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json"
server # [    9.699556] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: 3850182079
server # [    9.703661] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: service1.service.json
server # [    9.708147] systemd-vaultd[782]: 2026/07/29 05:21:05 Ignore unknown file: 3850182079
server # [    9.711166] systemd-vaultd[782]: 2026/07/29 05:21:05 Ignore unknown file: service1.service.json
server # [    9.714798] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: 1786572219
server # [    9.717867] systemd-vaultd[782]: 2026/07/29 05:21:05 Ignore unknown file: 1786572219
server # [    9.720755] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: service1.service.EnvironmentFile
server # [    9.724268] systemd-vaultd[782]: 2026/07/29 05:21:05 Served service1.service.EnvironmentFile to @a58112c0a2e699fd/unit/service1-envfile.service/service1.service.EnvironmentFile
server # [    9.729907] systemd[1]: Finished service1-envfile.service.
server # [    9.731869] vault[945]: 2026-07-29T05:21:05.773Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service1.service'
server # [    9.736107] systemd[1]: Started service1.service.
server # [    9.737871] systemd[1]: Stopping service1.service...
server # [    9.753378] systemd[1]: service1.service: Deactivated successfully.
server # [    9.755640] systemd[1]: Stopped service1.service.
server # [    9.757445] systemd[1]: service1-envfile.service: Deactivated successfully.
server # [    9.759816] systemd[1]: Stopped service1-envfile.service.
server # [    9.761847] systemd[1]: Stopping service1-envfile.service...
server # [    9.763796] systemd[1]: Starting service1-envfile.service...
server # [    9.765986] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.
server # [    9.769254] systemd-vaultd[782]: 2026/07/29 05:21:05 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service
server # [    9.778805] systemd[1]: Finished service1-envfile.service.
server # [    9.783595] systemd[1]: Started service1.service.
server # [    9.786487] systemd-vaultd[782]: 2026/07/29 05:21:05 Systemd requested secret for service1.service/foo
server # [    9.821201] systemd[1]: service1.service: Deactivated successfully.
server # [    9.825378] systemd[1]: run-credentials-service1.service.mount: Deactivated successfully.
server # [    9.828617] systemd[1]: service1-envfile.service: Deactivated successfully.
server # [    9.831283] systemd[1]: Stopped service1-envfile.service.
server # [    9.912874] vault[945]: 2026-07-29T05:21:06.021Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 2 after "500ms")
server # [   10.417403] vault[945]: 2026-07-29T05:21:06.526Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 3 after "1s")
(finished: waiting for success: grep -q bar /tmp/service1, in 1.11 seconds)
server: must succeed: grep -q bar /tmp/service1-env
(finished: must succeed: grep -q bar /tmp/service1-env, in 0.04 seconds)
server: must succeed: systemctl status service2 || :
(finished: must succeed: systemctl status service2 || :, in 0.07 seconds)
server: must succeed: vault kv put secret/blocking-secret foo=bar
server # [   10.676929] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.
(finished: must succeed: vault kv put secret/blocking-secret foo=bar, in 0.20 seconds)
server: waiting for success: grep -q bar /tmp/service2 >&2
server # grep: /tmp/service2: No such file or directory
server # [   11.425320] systemd-vaultd[782]: 2026/07/29 05:21:07 Detected added file: 1567096775
server # [   11.428944] systemd-vaultd[782]: 2026/07/29 05:21:07 Ignore unknown file: 1567096775
server # [   11.433281] systemd-vaultd[782]: 2026/07/29 05:21:07 Detected added file: service2.service.json
server # [   11.437723] systemd-vaultd[782]: 2026/07/29 05:21:07 Served service2.service.json to @c589ffccd7ca25f0/unit/service2.service/secret
server # [   11.443252] vault[945]: 2026-07-29T05:21:07.533Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"
server # [   11.450500] vault[945]: 2026-07-29T05:21:07.535Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service2.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"
server # [   11.459903] vault[945]: 2026-07-29T05:21:07.536Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'
server # [   11.497119] systemd[1]: Started service2.service.
server # [   11.509149] systemd[1]: Reloading service2.service...
server # [   11.513860] systemd-vaultd[782]: 2026/07/29 05:21:07 Systemd requested secret for service2.service/secret
server # [   11.535250] service2-start[1177]: + true
server # [   11.542006] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.545328] systemd-vaultd-update-secrets[1178]: 2026/07/29 05:21:07 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated
server # [   11.551395] service2-start[1184]: bar
server # [   11.553305] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.556290] service2-start[1177]: + sleep 0.1
server # [   11.661237] service2-start[1177]: + true
server # [   11.663116] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.669919] service2-start[1187]: bar
server # [   11.671739] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.678119] service2-start[1177]: + sleep 0.1
server # [   11.786633] service2-start[1177]: + true
server # [   11.788665] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.794661] service2-start[1190]: bar
server # [   11.796426] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.803114] service2-start[1177]: + sleep 0.1
server # [   11.910737] service2-start[1177]: + true
server # [   11.913409] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.919080] service2-start[1196]: bar
server # [   11.921241] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   11.926937] service2-start[1177]: + sleep 0.1
(finished: waiting for success: grep -q bar /tmp/service2 >&2, in 1.10 seconds)
server: must succeed: umount /run/credentials/service2.service
server # [   11.989861] systemd[1]: run-credentials-service2.service.mount: Deactivated successfully.
(finished: must succeed: umount /run/credentials/service2.service, in 0.03 seconds)
server: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json
(finished: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json, in 0.04 seconds)
server: must succeed: vault kv put secret/blocking-secret foo=reload
server # [   12.034874] service2-start[1177]: + true
server # [   12.037377] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.043333] service2-start[1209]: bar
server # [   12.045372] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.053506] service2-start[1177]: + sleep 0.1
server # [   12.161120] service2-start[1177]: + true
server # [   12.163790] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.170821] service2-start[1222]: bar
server # [   12.172998] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.179060] service2-start[1177]: + sleep 0.1
server # [   12.238366] fbcon: Taking over console
server # [   12.240118] virtio-pci 0000:00:09.0: [drm] drm_plane_enable_fb_damage_clips() not called
server # [   12.240216] Console: switching to colour frame buffer device 160x50
(finished: must succeed: vault kv put secret/blocking-secret foo=reload, in 0.22 seconds)
server: must succeed: systemctl restart vault-agent-default
server # [   12.287141] service2-start[1177]: + true
server # [   12.289630] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.295060] service2-start[1232]: bar
server # [   12.298071] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.302202] service2-start[1177]: + sleep 0.1
server # [   12.310318] vault[945]: ==> Vault Agent shutdown triggered
server # [   12.313707] vault[945]: 2026-07-29T05:21:08.418Z [INFO] (runner) stopping
server # [   12.316202] vault[945]: 2026-07-29T05:21:08.419Z [INFO]  agent.template.server: template server stopped
server # [   12.321104] vault[945]: 2026-07-29T05:21:08.419Z [INFO]  agent.auth.handler: shutdown triggered, stopping lifetime watcher
server # [   12.328205] vault[945]: 2026-07-29T05:21:08.419Z [INFO]  agent.auth.handler: auth handler stopped
server # [   12.332041] vault[945]: 2026-07-29T05:21:08.419Z [INFO]  agent.sink.server: sink server stopped
server # [   12.335385] vault[945]: 2026-07-29T05:21:08.419Z [INFO]  agent: sinks finished, exiting
server # [   12.339021] vault[945]: 2026-07-29T05:21:08.419Z [INFO]  agent.exec.server: exec server stopped
server # [   12.342347] systemd[1]: Stopping vault-agent-default.service...
server # [   12.344664] systemd[1]: vault-agent-default.service: Deactivated successfully.
server # [   12.347064] systemd[1]: Stopped vault-agent-default.service.
server # [   12.348990] systemd[1]: vault-agent-default.service: Consumed 199ms CPU time, no IO, received 6.3K IP traffic, sent 4.9K IP traffic.
server # [   12.361265] systemd[1]: Started vault-agent-default.service.
server # [   12.369600] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [   12.372769] systemd[1]: Stopped Virtual Console Setup.
(finished: must succeed: systemctl restart vault-agent-default, in 0.12 seconds)
server: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2
server # [   12.376170] systemd[1]: Stopping Virtual Console Setup...
server # [   12.378733] systemd[1]: Starting Virtual Console Setup...
server # [   12.410405] service2-start[1177]: + true
server # [   12.413111] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.418070] service2-start[1252]: bar
server # [   12.420096] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.424330] service2-start[1177]: + sleep 0.1
server # cat: /run/systemd-vaultd/secrets/service2.service.json: No such file or directory
server # [   12.510976] vault[1239]: ==> Vault Agent started! Log data will stream in below:
server # [   12.514436] vault[1239]: ==> Vault Agent configuration:
server # [   12.517157] vault[1239]:            Api Address 1: http://bufconn
server # [   12.519520] vault[1239]:                      Cgo: enabled
server # [   12.522344] vault[1239]:                Log Level:
server # [   12.524745] vault[1239]:                  Version: Vault v1.14.4
server # [   12.527543] vault[1239]: 2026-07-29T05:21:08.621Z [INFO]  agent.exec.server: starting exec server
server # [   12.532074] vault[1239]: 2026-07-29T05:21:08.621Z [INFO]  agent.exec.server: no env templates or exec config, exiting
server # [   12.538218] vault[1239]: 2026-07-29T05:21:08.621Z [INFO]  agent.auth.handler: starting auth handler
server # [   12.543090] vault[1239]: 2026-07-29T05:21:08.621Z [INFO]  agent.auth.handler: authenticating
server # [   12.547300] vault[1239]: 2026-07-29T05:21:08.622Z [INFO]  agent.sink.server: starting sink server
server # [   12.551810] vault[1239]: 2026-07-29T05:21:08.622Z [INFO]  agent.template.server: starting template server
server # [   12.556662] vault[1239]: 2026-07-29T05:21:08.622Z [INFO] (runner) creating new runner (dry: false, once: false)
server # [   12.562058] systemd-vaultd[782]: 2026/07/29 05:21:08 Detected added file: 1840851771
server # [   12.565847] systemd-vaultd[782]: 2026/07/29 05:21:08 Detected added file: service2.service.json
server # [   12.569794] systemd-vaultd[782]: 2026/07/29 05:21:08 Ignore unknown file: 1840851771
server # [   12.573278] systemd-vaultd[782]: 2026/07/29 05:21:08 Ignore unknown file: service2.service.json
server # [   12.600300] systemd[1]: Reloaded service2.service.
server # [   12.603024] service2-start[1177]: + true
server # [   12.604866] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.607912] vault[1239]: 2026-07-29T05:21:08.623Z [INFO] (runner) creating watcher
server # [   12.611405] vault[1239]: 2026-07-29T05:21:08.624Z [INFO]  agent.auth.handler: authentication successful, sending token to sinks
server # [   12.616332] vault[1239]: 2026-07-29T05:21:08.624Z [INFO]  agent.auth.handler: starting renewal process
server # [   12.620334] vault[1239]: 2026-07-29T05:21:08.624Z [INFO]  agent.template.server: template server received new token
server # [   12.625049] vault[1239]: 2026-07-29T05:21:08.624Z [INFO] (runner) stopping
server # [   12.628267] vault[1239]: 2026-07-29T05:21:08.624Z [INFO] (runner) creating new runner (dry: false, once: false)
server # [   12.632622] vault[1239]: 2026-07-29T05:21:08.625Z [INFO] (runner) creating watcher
server # [   12.635885] vault[1239]: 2026-07-29T05:21:08.625Z [INFO] (runner) starting
server # [   12.638929] vault[1239]: 2026-07-29T05:21:08.628Z [INFO]  agent.auth.handler: renewed auth token
server # [   12.642810] vault[1239]: 2026-07-29T05:21:08.640Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"
server # [   12.648296] vault[1239]: 2026-07-29T05:21:08.641Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service2.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"
server # [   12.656714] vault[1239]: 2026-07-29T05:21:08.641Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'
server # [   12.661893] systemd[1]: Reached target Multi-User System.
server # [   12.664319] systemd[1]: Reloading service2.service...
server # [   12.666595] service2-start[1261]: bar
server # [   12.668232] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.670821] service2-start[1177]: + sleep 0.1
server # [   12.672627] service2-start[1177]: + true
server # [   12.674184] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.677009] systemd-vaultd-update-secrets[1266]: 2026/07/29 05:21:08 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated
server # [   12.681730] service2-start[1272]: reload
server # [   12.683235] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.685429] service2-start[1177]: + sleep 0.1
server # [   12.694867] systemd[1]: Finished Virtual Console Setup.
server # [   12.764774] service2-start[1177]: + true
server # [   12.766445] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.772589] service2-start[1277]: reload
server # [   12.774513] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.780495] service2-start[1177]: + sleep 0.1
server # [   12.888990] service2-start[1177]: + true
server # [   12.890905] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.897166] service2-start[1280]: reload
server # [   12.899027] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   12.905645] service2-start[1177]: + sleep 0.1
server # [   13.013608] service2-start[1177]: + true
server # [   13.015404] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.022131] service2-start[1283]: reload
server # [   13.024083] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.030214] service2-start[1177]: + sleep 0.1
server # [   13.138727] service2-start[1177]: + true
server # [   13.140672] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.146684] service2-start[1286]: reload
server # [   13.148574] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.155185] service2-start[1177]: + sleep 0.1
server # [   13.263163] service2-start[1177]: + true
server # [   13.265046] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.272098] service2-start[1289]: reload
server # [   13.274019] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.280164] service2-start[1177]: + sleep 0.1
server # [   13.388629] service2-start[1177]: + true
server # [   13.390502] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.396932] service2-start[1292]: reload
server # [   13.398795] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.405343] service2-start[1177]: + sleep 0.1
server # 
server # {"secret":"reload"}
(finished: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2, in 1.12 seconds)
server: must succeed: systemctl restart service2
server # [   13.513219] service2-start[1177]: + true
server # [   13.515349] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.521020] service2-start[1303]: reload
server # [   13.523239] service2-start[1177]: + cat /run/service2/secrets/secret
server # [   13.529278] service2-start[1177]: + sleep 0.1
server # [   13.552097] systemd[1]: service2.service: Control process exited, code=killed, status=15/TERM
server # [   13.557245] systemd[1]: service2.service: Failed with result 'signal'.
server # [   13.561179] systemd[1]: Stopped service2.service.
server # [   13.589579] systemd[1]: Starting service2.service...
server # [   13.613129] systemd-vaultd[782]: 2026/07/29 05:21:09 Systemd requested secret for service2.service/secret
server # [   13.660736] systemd[1]: Started service2.service.
server # [   13.662833] systemd[1]: Startup finished in 3.031s (kernel) + 10.631s (userspace) = 13.662s.
(finished: must succeed: systemctl restart service2, in 0.18 seconds)
server: must succeed: rm /tmp/service2
server # [   13.673673] systemd-vaultd[782]: 2026/07/29 05:21:09 Systemd requested secret for service2.service/secret
server # [   13.692120] service2-start[1313]: + true
server # [   13.694181] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   13.700744] service2-start[1318]: reload
server # [   13.703134] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   13.708891] service2-start[1313]: + sleep 0.1
(finished: must succeed: rm /tmp/service2, in 0.04 seconds)
server: waiting for success: grep -q reload /tmp/service2 >&2
server # grep: /tmp/service2: No such file or directory
server # [   13.818503] service2-start[1313]: + true
server # [   13.820554] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   13.827299] service2-start[1326]: reload
server # [   13.829238] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   13.835528] service2-start[1313]: + sleep 0.1
server # [   13.944830] service2-start[1313]: + true
server # [   13.946843] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   13.952952] service2-start[1329]: reload
server # [   13.954753] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   13.961631] service2-start[1313]: + sleep 0.1
server # [   14.069603] service2-start[1313]: + true
server # [   14.071451] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.078229] service2-start[1332]: reload
server # [   14.080161] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.086385] service2-start[1313]: + sleep 0.1
server # [   14.194930] service2-start[1313]: + true
server # [   14.196889] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.203098] service2-start[1335]: reload
server # [   14.205003] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.211622] service2-start[1313]: + sleep 0.1
server # [   14.319590] service2-start[1313]: + true
server # [   14.321584] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.328209] service2-start[1338]: reload
server # [   14.330106] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.336396] service2-start[1313]: + sleep 0.1
server # [   14.445110] service2-start[1313]: + true
server # [   14.447052] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.453230] service2-start[1341]: reload
server # [   14.455051] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.461908] service2-start[1313]: + sleep 0.1
server # [   14.569868] service2-start[1313]: + true
server # [   14.571749] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.578477] service2-start[1344]: reload
server # [   14.580394] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.586665] service2-start[1313]: + sleep 0.1
server # [   14.695670] service2-start[1313]: + true
server # [   14.697748] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.703481] service2-start[1347]: reload
server # [   14.705407] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.711896] service2-start[1313]: + sleep 0.1
(finished: waiting for success: grep -q reload /tmp/service2 >&2, in 1.10 seconds)
server: must succeed: stat -c %u /run/service2/secrets/secret
server # [   14.819699] service2-start[1313]: + true
server # [   14.822337] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.827922] service2-start[1357]: reload
server # [   14.830267] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.836341] service2-start[1313]: + sleep 0.1
(finished: must succeed: stat -c %u /run/service2/secrets/secret, in 0.04 seconds)
server: must succeed: stat -c %g /run/service2/secrets/secret
(finished: must succeed: stat -c %g /run/service2/secrets/secret, in 0.04 seconds)
server: must succeed: stat -c %a /run/service2/secrets/secret
(finished: must succeed: stat -c %a /run/service2/secrets/secret, in 0.03 seconds)
(finished: run the VM test script, in 15.46 seconds)
server # [   14.944401] service2-start[1313]: + true
server # [   14.947007] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.952581] service2-start[1371]: reload
server # [   14.954924] service2-start[1313]: + cat /run/service2/secrets/secret
server # [   14.960437] service2-start[1313]: + sleep 0.1
test script finished in 15.52s
cleanup
kill machine (pid 6)
server # qemu-system-aarch64: terminating on signal 15 from pid 4 (/nix/store/nxxghhhia9x1skpqfak4z407yy0mbnnd-python3-3.11.5/bin/python3.11)
(finished: cleanup, in 0.11 seconds)
additionally exposed symbols:
    server,
    vlan1,
    start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine
● service2.service
     Loaded: loaded (/etc/systemd/system/service2.service; enabled; preset: enabled)
     Active: activating (start-pre) since Wed 2026-07-29 05:21:01 UTC; 4s ago
Cntrl PID: 777 ((re-start))
         IP: 0B in, 0B out
         IO: 0B read, 0B written
      Tasks: 2 (limit: 1086)
     Memory: 372.0K
        CPU: 3ms
     CGroup: /system.slice/service2.service
             ├─777 "(re-start)"
             └─781 "(sd-mkdcreds)"

Jul 29 05:21:01 server systemd[1]: Starting service2.service...

kill vlan (pid 5)