vm-test-run-systemd-vaultd
default.checks.aarch64-linux.systemd-vaultd
· build #8
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-vm-state3start all VLans4start vlan5running vlan (pid 5; ctl /build/vde1.ctl)6(finished: start all VLans, in 0.00 seconds)7run the VM test script8start all VMs9server: starting vm10server: QEMU running (pid 6)11server # Disk image do not exist, creating the virtualisation disk image...12server # Formatting '/build/vm-state-server/tmp.T5Q2LqcCIS', fmt=raw size=107374182413server # mke2fs 1.47.0 (5-Feb-2023)14(finished: start all VMs, in 0.36 seconds)15server # Discarding device blocks: 0/262144 done16server: waiting for unit vault.service17server # Creating filesystem with 262144 4k blocks and 65536 inodes18server: waiting for the VM to finish booting19server # Filesystem UUID: 1beb2050-ce03-4307-ad64-a41f26d1185820server # Superblock backups stored on blocks:21server # 32768, 98304, 163840, 22937622server # 23server # Allocating group tables: 0/8 done24server # Writing inode tables: 0/8 done25server # Creating journal (8192 blocks): done26server # Writing superblocks and filesystem accounting information: 0/8 done27server # 28server # Virtualisation disk image created.29server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]30server # [ 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 202331server # [ 0.000000] random: crng init done32server # [ 0.000000] Machine model: linux,dummy-virt33server # [ 0.000000] efi: UEFI not found.34server # [ 0.000000] NUMA: No NUMA configuration found35server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]36server # [ 0.000000] NUMA: NODE_DATA [mem 0x7fdf49c0-0x7fdf7fff]37server # [ 0.000000] Zone ranges:38server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]39server # [ 0.000000] DMA32 empty40server # [ 0.000000] Normal empty41server # [ 0.000000] Device empty42server # [ 0.000000] Movable zone start for each node43server # [ 0.000000] Early memory node ranges44server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]45server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]46server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000047server # [ 0.000000] psci: probing for conduit method from DT.48server # [ 0.000000] psci: PSCIv1.3 detected in firmware.49server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs50server # [ 0.000000] psci: Trusted OS migration not required51server # [ 0.000000] psci: SMC Calling Convention v1.152server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)53server # [ 0.000000] percpu: Embedded 30 pages/cpu s84008 r8192 d30680 u12288054server # [ 0.000000] Detected PIPT I-cache on CPU055server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)56server # [ 0.000000] CPU features: detected: GIC system register CPU interface57server # [ 0.000000] CPU features: detected: Spectre-v458server # [ 0.000000] alternatives: applying boot alternatives59server # [ 0.000000] Fallback order for Node 0: 060server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 25804861server # [ 0.000000] Policy zone: DMA62server # [ 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=ttyAMA063server # [ 0.000000] clocksource: clock= boot option is deprecated - use clocksource=xyz64server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/sc0vfplxqzhw4khz77zydhyzgfnbifql-closure-info/registration", will be passed to user space.65server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)66server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)67server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off68server # [ 0.000000] Memory: 927548K/1048576K available (20544K kernel code, 5386K rwdata, 18796K rodata, 12032K init, 743K bss, 88260K reserved, 32768K cma-reserved)69server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=170server # [ 0.000000] ftrace: allocating 66065 entries in 259 pages71server # [ 0.000000] ftrace: allocated 259 pages with 3 groups72server # [ 0.000000] trace event string verifier disabled73server # [ 0.000000] rcu: Hierarchical RCU implementation.74server # [ 0.000000] rcu: RCU event tracing is enabled.75server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4.76server # [ 0.000000] Rude variant of Tasks RCU enabled.77server # [ 0.000000] Tracing variant of Tasks RCU enabled.78server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.79server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=480server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 081server # [ 0.000000] GICv3: 256 SPIs implemented82server # [ 0.000000] GICv3: 0 Extended SPIs implemented83server # [ 0.000000] Root IRQ handler: gic_handle_irq84server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI85server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a000086server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]87server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @43e20000 (indirect, esz 8, psz 64K, shr 1)88server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @43e30000 (flat, esz 8, psz 64K, shr 1)89server # [ 0.000000] GICv3: using LPI property table @0x0000000043e4000090server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000043e5000091server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.92server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).93server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns94server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns95server # [ 0.000022] arm-pv: using stolen time PV96server # [ 0.000091] Console: colour dummy device 80x2597server # [ 0.000110] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)98server # [ 0.000113] pid_max: default: 32768 minimum: 30199server # [ 0.000133] LSM: Security Framework initializing100server # [ 0.000138] landlock: Up and running.101server # [ 0.000139] Yama: becoming mindful.102server # [ 0.000146] LSM support for eBPF active103server # [ 0.000162] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)104server # [ 0.000164] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)105server # [ 0.000504] cblist_init_generic: Setting adjustable number of callback queues.106server # [ 0.000505] cblist_init_generic: Setting shift to 2 and lim to 1.107server # [ 0.000516] cblist_init_generic: Setting adjustable number of callback queues.108server # [ 0.000517] cblist_init_generic: Setting shift to 2 and lim to 1.109server # [ 0.000549] rcu: Hierarchical SRCU implementation.110server # [ 0.000549] rcu: Max phase no-delay instances is 1000.111server # [ 0.000856] Platform MSI: its@8080000 domain created112server # [ 0.000864] PCI/MSI: /intc@8000000/its@8080000 domain created113server # [ 0.000873] fsl-mc MSI: its@8080000 domain created114server # [ 0.000969] EFI services will not be available.115server # [ 0.001009] smp: Bringing up secondary CPUs ...116server # [ 0.001458] Detected PIPT I-cache on CPU1117server # [ 0.001597] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000118server # [ 0.001723] GICv3: CPU1: using allocated LPI pending table @0x0000000043e60000119server # [ 0.001836] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40]120server # [ 0.002304] Detected PIPT I-cache on CPU2121server # [ 0.002426] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000122server # [ 0.002551] GICv3: CPU2: using allocated LPI pending table @0x0000000043e70000123server # [ 0.002665] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40]124server # [ 0.003044] Detected PIPT I-cache on CPU3125server # [ 0.003175] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000126server # [ 0.003301] GICv3: CPU3: using allocated LPI pending table @0x0000000043e80000127server # [ 0.003414] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40]128server # [ 0.003532] smp: Brought up 1 node, 4 CPUs129server # [ 0.003534] SMP: Total of 4 processors activated.130server # [ 0.003537] CPU features: detected: Branch Target Identification131server # [ 0.003539] CPU features: detected: ARMv8.4 Translation Table Level132server # [ 0.003540] CPU features: detected: Instruction cache invalidation not required for I/D coherence133server # [ 0.003541] CPU features: detected: Data cache clean to the PoU not required for I/D coherence134server # [ 0.003542] CPU features: detected: Common not Private translations135server # [ 0.003543] CPU features: detected: CRC32 instructions136server # [ 0.003544] CPU features: detected: E0PD137server # [ 0.003545] CPU features: detected: Enhanced Counter Virtualization138server # [ 0.003547] CPU features: detected: Generic authentication (architected QARMA5 algorithm)139server # [ 0.003548] CPU features: detected: RCpc load-acquire (LDAPR)140server # [ 0.003549] CPU features: detected: LSE atomic instructions141server # [ 0.003551] CPU features: detected: Privileged Access Never142server # [ 0.003552] CPU features: detected: RAS Extension Support143server # [ 0.003552] CPU features: detected: Random Number Generator144server # [ 0.003553] CPU features: detected: Speculation barrier (SB)145server # [ 0.003554] CPU features: detected: Stage-2 Force Write-Back146server # [ 0.003555] CPU features: detected: TLB range maintenance instructions147server # [ 0.003557] CPU features: detected: Speculative Store Bypassing Safe (SSBS)148server # [ 0.003660] CPU: All CPU(s) started at EL1149server # [ 0.003665] alternatives: applying system-wide alternatives150server # [ 0.007432] devtmpfs: initialized151server # [ 0.008060] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns152server # [ 0.008064] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)153server # [ 0.008180] pinctrl core: initialized pinctrl subsystem154server # [ 0.008413] DMI not present or invalid.155server # [ 0.008543] NET: Registered PF_NETLINK/PF_ROUTE protocol family156server # [ 0.009195] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations157server # [ 0.009231] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations158server # [ 0.009266] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations159server # [ 0.009279] audit: initializing netlink subsys (disabled)160server # [ 0.009352] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1161server # [ 0.009485] thermal_sys: Registered thermal governor 'step_wise'162server # [ 0.009487] thermal_sys: Registered thermal governor 'power_allocator'163server # [ 0.009497] cpuidle: using governor ladder164server # [ 0.009500] cpuidle: using governor menu165server # [ 0.009562] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.166server # [ 0.009804] ASID allocator initialised with 65536 entries167server # [ 0.010047] Serial: AMBA PL011 UART driver168server # [ 0.011983] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1169server # [ 0.118186] printk: console [ttyAMA0] enabled170server # [ 0.119072] KASLR enabled171server # [ 0.141028] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages172server # [ 0.141848] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page173server # [ 0.142572] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages174server # [ 0.143354] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page175server # [ 0.144098] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages176server # [ 0.144878] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page177server # [ 0.145606] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages178server # [ 0.146410] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page179server # [ 0.149334] ACPI: Interpreter disabled.180server # [ 0.150171] iommu: Default domain type: Translated181server # [ 0.150762] iommu: DMA domain TLB invalidation policy: strict mode182server # [ 0.151563] SCSI subsystem initialized183server # [ 0.152138] usbcore: registered new interface driver usbfs184server # [ 0.152805] usbcore: registered new interface driver hub185server # [ 0.153449] usbcore: registered new device driver usb186server # [ 0.154187] pps_core: LinuxPPS API ver. 1 registered187server # [ 0.154781] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>188server # [ 0.155864] PTP clock support registered189server # [ 0.156391] EDAC MC: Ver: 3.0.0190server # [ 0.157081] FPGA manager framework191server # [ 0.157531] Advanced Linux Sound Architecture Driver Initialized.192server # [ 0.158461] vgaarb: loaded193server # [ 0.159827] clocksource: Switched to clocksource arch_sys_counter194server # [ 0.160677] VFS: Disk quotas dquot_6.6.0195server # [ 0.161166] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)196server # [ 0.162037] pnp: PnP ACPI: disabled197server # [ 0.164463] NET: Registered PF_INET protocol family198server # [ 0.165082] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)199server # [ 0.166220] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)200server # [ 0.167231] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)201server # [ 0.168202] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)202server # [ 0.169319] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)203server # [ 0.170255] TCP: Hash tables configured (established 8192 bind 8192)204server # [ 0.171049] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)205server # [ 0.171946] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)206server # [ 0.172740] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)207server # [ 0.173602] NET: Registered PF_UNIX/PF_LOCAL protocol family208server # [ 0.174467] RPC: Registered named UNIX socket transport module.209server # [ 0.175177] RPC: Registered udp transport module.210server # [ 0.175740] RPC: Registered tcp transport module.211server # [ 0.176353] RPC: Registered tcp NFSv4.1 backchannel transport module.212server # [ 0.177120] NET: Registered PF_XDP protocol family213server # [ 0.177695] PCI: CLS 0 bytes, default 64214server # [ 0.178320] Trying to unpack rootfs image as initramfs...215server # [ 0.190231] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 counters available216server # [ 0.191265] kvm [1]: HYP mode not available217server # [ 0.192107] Initialise system trusted keyrings218server # [ 0.192764] workingset: timestamp_bits=42 max_order=18 bucket_order=0219server # [ 0.194736] zbud: loaded220server # [ 0.195400] squashfs: version 4.0 (2009/01/31) Phillip Lougher221server # [ 0.196338] NFS: Registering the id_resolver key type222server # [ 0.196954] Key type id_resolver registered223server # [ 0.197460] Key type id_legacy registered224server # [ 0.197967] nfs4filelayout_init: NFSv4 File Layout Driver Registering...225server # [ 0.198761] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...226server # [ 0.199723] 9p: Installing v9fs 9p2000 file system support227server # [ 0.210090] Key type asymmetric registered228server # [ 0.210586] Asymmetric key parser 'x509' registered229server # [ 0.291327] Freeing initrd memory: 9012K230server # [ 0.292846] alg: self-tests for CTR-KDF (hmac(sha256)) passed231server # [ 0.294979] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)232server # [ 0.297892] io scheduler mq-deadline registered233server # [ 0.299519] io scheduler kyber registered234server # [ 0.305427] pl061_gpio 9030000.pl061: PL061 GPIO chip registered235server # [ 0.308423] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:236server # [ 0.311087] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000237server # [ 0.314342] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000238server # [ 0.317304] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000239server # [ 0.320018] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits240server # [ 0.322463] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]241server # [ 0.325131] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00242server # [ 0.327012] pci_bus 0000:00: root bus resource [bus 00-ff]243server # [ 0.328479] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]244server # [ 0.330062] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]245server # [ 0.331714] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]246server # [ 0.333629] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000247server # [ 0.335610] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000248server # [ 0.337175] pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f]249server # [ 0.338432] pci 0000:00:01.0: reg 0x14: [mem 0x00000000-0x00000fff]250server # [ 0.339924] pci 0000:00:01.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]251server # [ 0.341484] pci 0000:00:01.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]252server # [ 0.343492] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00253server # [ 0.344853] pci 0000:00:02.0: reg 0x10: [io 0x0000-0x001f]254server # [ 0.345995] pci 0000:00:02.0: reg 0x14: [mem 0x00000000-0x00000fff]255server # [ 0.347321] pci 0000:00:02.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]256server # [ 0.349431] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200257server # [ 0.350643] pci 0000:00:03.0: reg 0x10: [io 0x0000-0x003f]258server # [ 0.351705] pci 0000:00:03.0: reg 0x14: [mem 0x00000000-0x00000fff]259server # [ 0.353072] pci 0000:00:03.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]260server # [ 0.354962] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200261server # [ 0.356120] pci 0000:00:04.0: reg 0x10: [io 0x0000-0x001f]262server # [ 0.357134] pci 0000:00:04.0: reg 0x14: [mem 0x00000000-0x00000fff]263server # [ 0.358345] pci 0000:00:04.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]264server # [ 0.360140] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200265server # [ 0.361232] pci 0000:00:05.0: reg 0x10: [io 0x0000-0x001f]266server # [ 0.362191] pci 0000:00:05.0: reg 0x14: [mem 0x00000000-0x00000fff]267server # [ 0.363322] pci 0000:00:05.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]268server # [ 0.365086] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000269server # [ 0.366116] pci 0000:00:06.0: reg 0x10: [io 0x0000-0x007f]270server # [ 0.367010] pci 0000:00:06.0: reg 0x14: [mem 0x00000000-0x00000fff]271server # [ 0.368111] pci 0000:00:06.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]272server # [ 0.369743] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000273server # [ 0.370713] pci 0000:00:07.0: reg 0x10: [io 0x0000-0x001f]274server # [ 0.371580] pci 0000:00:07.0: reg 0x14: [mem 0x00000000-0x00000fff]275server # [ 0.372692] pci 0000:00:07.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]276server # [ 0.373802] pci 0000:00:07.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]277server # [ 0.375225] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000278server # [ 0.376208] pci 0000:00:08.0: reg 0x14: [mem 0x00000000-0x00000fff]279server # [ 0.377209] pci 0000:00:08.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]280server # [ 0.378676] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000281server # [ 0.379574] pci 0000:00:09.0: reg 0x14: [mem 0x00000000-0x00000fff]282server # [ 0.380577] pci 0000:00:09.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]283server # [ 0.381934] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320284server # [ 0.382778] pci 0000:00:0a.0: reg 0x10: [mem 0x00000000-0x00000fff]285server # [ 0.383926] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000286server # [ 0.384851] pci 0000:00:0b.0: reg 0x10: [io 0x0000-0x003f]287server # [ 0.385615] pci 0000:00:0b.0: reg 0x14: [mem 0x00000000-0x00000fff]288server # [ 0.386528] pci 0000:00:0b.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]289server # [ 0.387925] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00290server # [ 0.388793] pci 0000:00:0c.0: reg 0x10: [io 0x0000-0x001f]291server # [ 0.389571] pci 0000:00:0c.0: reg 0x14: [mem 0x00000000-0x00000fff]292server # [ 0.390506] pci 0000:00:0c.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]293server # [ 0.392035] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref]294server # [ 0.393002] pci 0000:00:07.0: BAR 6: assigned [mem 0x10040000-0x1007ffff pref]295server # [ 0.393971] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref]296server # [ 0.395084] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref]297server # [ 0.396235] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref]298server # [ 0.397350] pci 0000:00:04.0: BAR 4: assigned [mem 0x800000c000-0x800000ffff 64bit pref]299server # [ 0.398473] pci 0000:00:05.0: BAR 4: assigned [mem 0x8000010000-0x8000013fff 64bit pref]300server # [ 0.399588] pci 0000:00:06.0: BAR 4: assigned [mem 0x8000014000-0x8000017fff 64bit pref]301server # [ 0.400784] pci 0000:00:07.0: BAR 4: assigned [mem 0x8000018000-0x800001bfff 64bit pref]302server # [ 0.401914] pci 0000:00:08.0: BAR 4: assigned [mem 0x800001c000-0x800001ffff 64bit pref]303server # [ 0.403041] pci 0000:00:09.0: BAR 4: assigned [mem 0x8000020000-0x8000023fff 64bit pref]304server # [ 0.404211] pci 0000:00:0b.0: BAR 4: assigned [mem 0x8000024000-0x8000027fff 64bit pref]305server # [ 0.405364] pci 0000:00:0c.0: BAR 4: assigned [mem 0x8000028000-0x800002bfff 64bit pref]306server # [ 0.406494] pci 0000:00:01.0: BAR 1: assigned [mem 0x10080000-0x10080fff]307server # [ 0.407413] pci 0000:00:02.0: BAR 1: assigned [mem 0x10081000-0x10081fff]308server # [ 0.408380] pci 0000:00:03.0: BAR 1: assigned [mem 0x10082000-0x10082fff]309server # [ 0.409300] pci 0000:00:04.0: BAR 1: assigned [mem 0x10083000-0x10083fff]310server # [ 0.410222] pci 0000:00:05.0: BAR 1: assigned [mem 0x10084000-0x10084fff]311server # [ 0.411142] pci 0000:00:06.0: BAR 1: assigned [mem 0x10085000-0x10085fff]312server # [ 0.412088] pci 0000:00:07.0: BAR 1: assigned [mem 0x10086000-0x10086fff]313server # [ 0.413011] pci 0000:00:08.0: BAR 1: assigned [mem 0x10087000-0x10087fff]314server # [ 0.413936] pci 0000:00:09.0: BAR 1: assigned [mem 0x10088000-0x10088fff]315server # [ 0.414857] pci 0000:00:0a.0: BAR 0: assigned [mem 0x10089000-0x10089fff]316server # [ 0.415784] pci 0000:00:0b.0: BAR 1: assigned [mem 0x1008a000-0x1008afff]317server # [ 0.416792] pci 0000:00:0c.0: BAR 1: assigned [mem 0x1008b000-0x1008bfff]318server # [ 0.417724] pci 0000:00:06.0: BAR 0: assigned [io 0x1000-0x107f]319server # [ 0.418560] pci 0000:00:03.0: BAR 0: assigned [io 0x1080-0x10bf]320server # [ 0.419397] pci 0000:00:0b.0: BAR 0: assigned [io 0x10c0-0x10ff]321server # [ 0.420272] pci 0000:00:01.0: BAR 0: assigned [io 0x1100-0x111f]322server # [ 0.421101] pci 0000:00:02.0: BAR 0: assigned [io 0x1120-0x113f]323server # [ 0.421932] pci 0000:00:04.0: BAR 0: assigned [io 0x1140-0x115f]324server # [ 0.422760] pci 0000:00:05.0: BAR 0: assigned [io 0x1160-0x117f]325server # [ 0.423589] pci 0000:00:07.0: BAR 0: assigned [io 0x1180-0x119f]326server # [ 0.424475] pci 0000:00:0c.0: BAR 0: assigned [io 0x11a0-0x11bf]327server # [ 0.425335] pci 0000:00:0a.0: enabling device (0000 -> 0002)328server # [ 0.427051] EINJ: ACPI disabled.329server # [ 0.430914] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)330server # [ 0.433371] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)331server # [ 0.435746] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)332server # [ 0.438331] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)333server # [ 0.440827] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)334server # [ 0.443326] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)335server # [ 0.446025] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)336server # [ 0.449036] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)337server # [ 0.451259] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)338server # [ 0.453804] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)339server # [ 0.456223] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)340server # [ 0.460170] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled341server # [ 0.461785] SuperH (H)SCI(F) driver initialized342server # [ 0.462465] msm_serial: driver initialized343server # [ 0.473385] loop: module loaded344server # [ 0.473958] virtio_blk virtio5: 4/0/0 default/read/poll queues345server # [ 0.475816] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)346server # [ 0.478835] megasas: 07.719.03.00-rc1347server # [ 0.479579] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]348server # [ 0.483871] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000349server # [ 0.485041] Intel/Sharp Extended Query Table at 0x0031350server # [ 0.487737] Using buffer write method351server # [ 0.488279] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]352server # [ 0.491410] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000353server # [ 0.493530] Intel/Sharp Extended Query Table at 0x0031354server # [ 0.496014] Using buffer write method355server # [ 0.496487] Concatenating MTD devices:356server # [ 0.496944] (0): "0.flash"357server # [ 0.497277] (1): "0.flash"358server # [ 0.497618] into device "0.flash"359server # [ 0.500020] tun: Universal TUN/TAP device driver, 1.6360server # [ 0.503036] thunder_xcv, ver 1.0361server # [ 0.503459] thunder_bgx, ver 1.0362server # [ 0.503892] nicpf, ver 1.0363server # [ 0.504449] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version364server # [ 0.505316] hns3: Copyright (c) 2017 Huawei Corporation.365server # [ 0.505980] hclge is initializing366server # [ 0.506390] e1000: Intel(R) PRO/1000 Network Driver367server # [ 0.506976] e1000: Copyright (c) 1999-2006 Intel Corporation.368server # [ 0.507676] e1000e: Intel(R) PRO/1000 Network Driver369server # [ 0.508317] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370server # [ 0.509044] igb: Intel(R) Gigabit Ethernet Network Driver371server # [ 0.509697] igb: Copyright (c) 2007-2014 Intel Corporation.372server # [ 0.510379] igbvf: Intel(R) Gigabit Virtual Function Network Driver373server # [ 0.511134] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374server # [ 0.511921] sky2: driver version 1.30375server # [ 0.512606] VFIO - User Level meta-driver version: 0.3376server # [ 0.514145] ehci-pci 0000:00:0a.0: EHCI Host Controller377server # [ 0.514795] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1378server # [ 0.515817] ehci-pci 0000:00:0a.0: irq 16, io mem 0x10089000379server # [ 0.531877] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00380server # [ 0.532776] hub 1-0:1.0: USB hub found381server # [ 0.533242] hub 1-0:1.0: 6 ports detected382server # [ 0.534099] usbcore: registered new interface driver usb-storage383server # [ 0.535386] rtc-pl031 9010000.pl031: registered as rtc0384server # [ 0.536065] rtc-pl031 9010000.pl031: setting system clock to 2026-07-29T05:20:56 UTC (1785302456)385server # [ 0.537245] i2c_dev: i2c /dev entries driver386server # [ 0.538867] sdhci: Secure Digital Host Controller Interface driver387server # [ 0.539617] sdhci: Copyright(c) Pierre Ossman388server # [ 0.540316] Synopsys Designware Multimedia Card Interface Driver389server # [ 0.541195] sdhci-pltfm: SDHCI platform and OF driver helper390server # [ 0.542342] ledtrig-cpu: registered to indicate activity on CPUs391server # [ 0.543378] hid: raw HID events driver (C) Jiri Kosina392server # [ 0.544100] usbcore: registered new interface driver usbhid393server # [ 0.544767] usbhid: USB HID core driver394server # [ 0.546127] drop_monitor: Initializing network drop monitor service395server # [ 0.560874] NET: Registered PF_INET6 protocol family396server # [ 0.562900] Segment Routing with IPv6397server # [ 0.563358] In-situ OAM (IOAM) with IPv6398server # [ 0.563869] NET: Registered PF_PACKET protocol family399server # [ 0.564639] 9pnet: Installing 9P2000 support400server # [ 0.566961] Key type dns_resolver registered401server # [ 0.567954] registered taskstats version 1402server # [ 0.568471] Loading compiled-in X.509 certificates403server # [ 0.569283] zswap: loaded using pool lzo/zbud404server # [ 0.570147] Key type .fscrypt registered405server # [ 0.570623] Key type fscrypt-provisioning registered406server # [ 0.575187] input: gpio-keys as /devices/platform/gpio-keys/input/input0407server # [ 0.576363] ALSA device list:408server # [ 0.576749] No soundcards found.409server # [ 0.577261] uart-pl011 9000000.pl011: no DMA platform data410server # [ 0.581787] Freeing unused kernel memory: 12032K411server # [ 0.596135] Run /init as init process412server # 413server # <<< NixOS Stage 1 >>>414server # 415server # loading module virtio_balloon...416server # loading module virtio_console...417server # loading module virtio_rng...418server # loading module dm_mod...419server # [ 0.697090] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com420server # running udev...421server # Starting systemd-udevd version 254.3422server # [ 0.799862] usb 1-1: new high-speed USB device number 2 using ehci-pci423server # kbd_mode: KDSKBMODE: Inappropriate ioctl for device424server # Gstarting device mapper and LVM...425server # checking /dev/disk/by-label/nixos...426server # fsck (busybox 1.36.1)427server # [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/disk/by-label/nixos428server # nixos: clean, 11/65536 files, 12955/262144 blocks429server # mounting /dev/disk/by-label/nixos on /...430server # [ 0.944330] EXT4-fs (vda): mounted filesystem with ordered data mode. Quota mode: none.431server # [ 0.949735] EXT4-fs (vda): re-mounted. Quota mode: none.432server # [ 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/input1433server # mounting nix-store on /nix/.ro-store...434server # mounting tmpfs on /nix/.rw-store...435server # mounting shared on /tmp/shared...436server # [ 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/input0437server # mounting xchg on /tmp/xchg...438server # mounting overlay filesystem on /nix/store...439server # [ 1.171922] usb 1-2: new high-speed USB device number 3 using ehci-pci440server # [ 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/input2441server # 442server # <<< 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/input0443server # 444server # 445server # [ 1.369209] EXT4-fs (vda): re-mounted. Quota mode: none.446server # [ 1.373000] booting system configuration /nix/store/frrpvxcpgflaskajs4v60085nq9f8qd9-nixos-system-server-23.11pre-git447server # running activation script...448server # setting up /etc...449server # starting systemd...450server # [ 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)451server # [ 3.151674] systemd[1]: Detected virtualization qemu.452server # [ 3.152675] systemd[1]: Detected architecture arm64.453server # [ 3.157207] systemd[1]: Initializing machine ID from random generator.454server # [ 3.246958] systemd[1]: bpf-lsm: Failed to link program; assuming BPF LSM is not available455server # [ 3.757619] systemd[1]: Queued start job for default target Multi-User System.456server # [ 3.781611] systemd[1]: Created slice Slice /system/getty.457server # [ 3.782745] systemd[1]: Created slice Slice /system/modprobe.458server # [ 3.783803] systemd[1]: Created slice User and Session Slice.459server # [ 3.784647] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.460server # [ 3.785665] systemd[1]: Started Forward Password Requests to Wall Directory Watch.461server # [ 3.786777] systemd[1]: Reached target Local Encrypted Volumes.462server # [ 3.787521] systemd[1]: Reached target Containers.463server # [ 3.788166] systemd[1]: Reached target Path Units.464server # [ 3.788763] systemd[1]: Reached target Remote File Systems.465server # [ 3.789444] systemd[1]: Reached target Slice Units.466server # [ 3.790059] systemd[1]: Reached target Swaps.467server # [ 3.793716] systemd[1]: Listening on Process Core Dump Socket.468server # [ 3.794846] systemd[1]: Listening on Journal Socket (/dev/log).469server # [ 3.795671] systemd[1]: Listening on Journal Socket.470server # [ 3.796790] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.471server # [ 3.798642] systemd[1]: Listening on udev Control Socket.472server # [ 3.799503] systemd[1]: Listening on udev Kernel Socket.473server # [ 3.801475] systemd[1]: Mounting Huge Pages File System...474server # [ 3.803445] systemd[1]: Mounting POSIX Message Queue File System...475server # [ 3.805641] systemd[1]: Mounting Kernel Debug File System...476server # [ 3.808561] systemd[1]: Starting Create List of Static Device Nodes...477server # [ 3.810682] systemd[1]: Starting Load Kernel Module configfs...478server # [ 3.812757] systemd[1]: Starting Load Kernel Module drm...479server # [ 3.814824] systemd[1]: Starting Load Kernel Module efi_pstore...480server # [ 3.817003] systemd[1]: Starting Load Kernel Module fuse...481server # [ 3.818962] systemd[1]: Starting mount-pstore.service...482server # [ 3.819119] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).483server # [ 3.824557] systemd[1]: Starting Journal Service...484server # [ 3.826633] systemd[1]: Starting Load Kernel Modules...485server # [ 3.828725] systemd[1]: Starting Remount Root and Kernel File Systems...486server # [ 3.832574] systemd[1]: Starting Coldplug All udev Devices...487server # [ 3.844514] systemd[1]: Finished Create List of Static Device Nodes.488server # [ 3.846139] systemd[1]: Mounted Huge Pages File System.489server # [ 3.847339] systemd[1]: Mounted POSIX Message Queue File System.490server # [ 3.848526] systemd[1]: Mounted Kernel Debug File System.491server # [ 3.850860] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...492server # [ 3.856877] systemd[1]: modprobe@configfs.service: Deactivated successfully.493server # [ 3.859001] systemd[1]: Finished Load Kernel Module configfs.494server # [ 3.860499] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.495server # [ 3.861723] systemd[1]: Finished Load Kernel Module efi_pstore.496server # [ 3.864358] systemd[1]: Mounting Kernel Configuration File System...497server # [ 3.877466] systemd[1]: Mounted Kernel Configuration File System.498server # [ 3.879441] EXT4-fs (vda): re-mounted. Quota mode: none.499server # [ 3.890884] fuse: init (API version 7.37)500server # [ 3.893941] systemd-journald[537]: Collecting audit messages is disabled.501server # [ 3.896095] systemd[1]: Finished Remount Root and Kernel File Systems.502server # [ 3.901573] systemd[1]: modprobe@fuse.service: Deactivated successfully.503server # [ 3.905299] systemd[1]: Finished Load Kernel Module fuse.504server # [ 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.505server # [ 3.938690] systemd[1]: Mounting FUSE Control File System...506server # [ 3.941626] systemd[1]: Starting Load/Save OS Random Seed...507server # [ 3.944307] systemd[1]: modprobe@drm.service: Deactivated successfully.508server # [ 3.946586] systemd[1]: Finished Load Kernel Module drm.509server # [ 3.929397] systemd-modules-load[538]: Inserted module 'bridge'[ 3.949735] systemd[1]: Started Journal Service.510server # 511server # [ 3.938847] systemd-modules-load[538]: Inserted module 'macvlan'512server # [ 3.941576] systemd-modules-load[538]: Inserted module 'tap'513server # [ 3.944042] systemd-modules-load[538]: Module 'tun' is built in514server # [ 3.946737] systemd-modules-load[538]: Module 'loop' is built in515server # [ 3.949425] systemd-modules-load[538]: Module 'atkbd' is built in516server # [ 3.953156] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.517server # [ 3.954496] systemd[1]: Finished Load Kernel Modules.518server # [ 3.955457] systemd[1]: Mounted FUSE Control File System.519server # [ 3.956443] systemd[1]: Starting Firewall...520server # [ 3.957449] systemd[1]: Starting Flush Journal to Persistent Storage...521server # [ 3.958569] systemd[1]: Starting Apply Kernel Variables...522server # [ 3.959681] systemd[1]: Starting Create Static Device Nodes in /dev...523server # [ 3.979129] systemd[1]: Finished Load/Save OS Random Seed.524server # [ 3.999112] systemd-journald[537]: Received client request to flush runtime journal.525server # [ 3.997037] systemd[1]: Finished Flush Journal to Persistent Storage.526server # [ 4.007348] systemd[1]: Finished Coldplug All udev Devices.527server # [ 4.014535] systemd[1]: Finished Apply Kernel Variables.528server # [ 4.016987] systemd[1]: Finished Create Static Device Nodes in /dev.529server # [ 4.017925] systemd[1]: Reached target Preparation for Local File Systems.530server # [ 4.018908] systemd[1]: Reached target Local File Systems.531server # [ 4.036512] systemd[1]: Starting Create Volatile Files and Directories...532server # [ 4.038421] systemd[1]: Starting Rule-based Manager for Device Events and Files...533server # [ 4.074243] systemd-udevd[589]: Using default interface naming scheme 'v253'.534server # [ 4.087776] systemd[1]: Finished Create Volatile Files and Directories.535server # [ 4.100513] systemd[1]: Starting Rebuild Journal Catalog...536server # [ 4.103593] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...537server # [ 4.106901] systemd[1]: Starting Record System Boot/Shutdown in UTMP...538server # [ 4.138869] systemd[1]: Finished Record System Boot/Shutdown in UTMP.539server # [ 4.143168] systemd[1]: Started Rule-based Manager for Device Events and Files.540server # [ 4.151188] systemd-oomd[599]: No swap; memory pressure usage will be degraded541server # [ 4.154399] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.542server # [ 4.159501] systemd[1]: Finished Rebuild Journal Catalog.543server # [ 4.177710] systemd[1]: Starting Update is Completed...544server # [ 4.180448] systemd[1]: Found device /dev/ttyAMA0.545server # [ 4.189922] systemd[1]: Found device /dev/hvc0.546server # [ 4.203484] systemd[1]: Finished Update is Completed.547server # [ 4.206675] (udev-worker)[610]: Network interface NamePolicy= disabled on kernel command line.548server # [ 4.212784] (udev-worker)[615]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.549server # [ 4.216717] (udev-worker)[615]: Network interface NamePolicy= disabled on kernel command line.550server # [ 4.237699] mousedev: PS/2 mouse device common for all mice551server # [ 4.261157] systemd[1]: Found device Virtio network device.552server # [ 4.309793] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3553server # [ 4.343313] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0554server # [ 4.344778] [drm] features: -virgl +edid -resource_blob -host_visible555server # [ 4.344781] [drm] features: -context_init556server # [ 4.347334] [drm] number of scanouts: 1557server # [ 4.348310] [drm] number of cap sets: 0558server # [ 4.349872] [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:09.0 on minor 0559server # [ 4.360012] fbcon: Deferring console take-over560server # [ 4.360986] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device561server # [ 4.380443] systemd[1]: Starting Virtual Console Setup...562server # [ 4.704347] systemd[1]: Finished Virtual Console Setup.563server # [ 4.742486] systemd[1]: Finished Firewall.564server # [ 5.602252] w8yz32d54jbh5dlnl3mr061y6flfa0wm-mount-pstore.sh[543]: Persistent Storage backend was not registered in time.565server # [ 5.607604] systemd[1]: Finished mount-pstore.service.566server # [ 5.610333] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).567server # [ 5.615771] systemd[1]: Reached target System Initialization.568server # [ 5.618237] systemd[1]: Started logrotate.timer.569server # [ 5.620148] systemd[1]: Started Daily Cleanup of Temporary Directories.570server # [ 5.622755] systemd[1]: Reached target Timer Units.571server # [ 5.624787] systemd[1]: Listening on D-Bus System Message Bus Socket.572server # [ 5.627318] systemd[1]: Listening on Nix Daemon Socket.573server # [ 5.629500] systemd[1]: Starting systemd-vaultd socket...574server # [ 5.645482] systemd[1]: Listening on systemd-vaultd socket.575server # [ 5.648038] systemd[1]: Reached target Socket Units.576server # [ 5.650117] systemd[1]: Reached target Basic System.577server # [ 5.652154] systemd[1]: Starting Kernel Auditing...578server # [ 5.655847] systemd[1]: Started backdoor.service.579server # [ 5.660294] systemd[1]: Starting D-Bus System Message Bus...580server # [ 5.663955] systemd[1]: Starting DHCP Client...581server # [ 5.668339] systemd[1]: Starting Logrotate configuration check...582server # [ 5.671781] systemd[1]: Starting Name Service Cache Daemon (nsncd)...583server # [ 5.675856] systemd[1]: Started Reset console on configuration changes.584server # [ 5.679563] systemd[1]: Starting resolvconf update...585server # [ 5.683722] systemd[1]: Starting service1-envfile.service...586server # [ 5.687223] systemd[1]: Starting service2.service...587server # [ 5.692298] systemd[1]: Starting setup-vault-agent-approle.service...588server # [ 5.699219] systemd[1]: Started systemd-vaultd daemon.589server # [ 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.590server # [ 5.712133] logrotate[772]: reading config file /nix/store/qb85sn99mapdxc37vcclnv3fx3dlqv0q-logrotate.conf591server # connecting to host...592server # [ 5.718235] rrll6i65cnvqwxannbj95lzl20a8y5dv-audit-disable[778]: No rules593server # [ 5.722677] logrotate[772]: note: 'monthly' overrides previously specified 'weekly'594server # [ 5.726553] logrotate[772]: Reading state from file: /var/lib/logrotate.status595server # [ 5.731816] logrotate[772]: state file /var/lib/logrotate.status does not exist596server # [ 5.736954] logrotate[772]: Allocating hash table for state file, size 64 entries597server # [ 5.743805] logrotate[772]: Handling 2 logs598server # [ 5.746925] logrotate[772]: rotating pattern: "/var/log/btmp" monthly (1 rotations)599server # [ 5.752240] logrotate[772]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed600server # [ 5.759431] logrotate[772]: considering log /var/log/btmp601server # [ 5.764841] logrotate[772]: Creating new state602server: Guest shell says: b'Spawning backdoor root shell...\n'603server: connected to guest root shell604server # [ 5.768911] logrotate[772]: Now: 2026-07-29 05:21605server: (connecting took 5.95 seconds)606server # [ 5.773669] logrotate[772]: Last rotated at 2026-07-29 05:00607(finished: waiting for the VM to finish booting, in 5.95 seconds)608server # [ 5.777928] logrotate[772]: log does not need rotating (log has already been rotated)609server # [ 5.785441] logrotate[772]: rotating pattern: "/var/log/wtmp" monthly (1 rotations)610server # [ 5.790210] logrotate[772]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed611server # [ 5.797432] logrotate[772]: considering log /var/log/wtmp612server # [ 5.801040] logrotate[772]: Creating new state613server # [ 5.805061] logrotate[772]: Now: 2026-07-29 05:21614server # [ 5.824558] 8021q: 802.1Q VLAN Support v1.8615server # [ 5.808851] logrotate[772]: Last rotated at 2026-07-29 05:00616server # [ 5.814881] logrotate[772]: log does not need rotating (log has already been rotated)617server # [ 5.820799] setup-vault-agent-approle-start[779]: + vault status618server # [ 5.826206] systemd-vaultd[782]: 2026/07/29 05:21:01 Use unix socket received from systemd619server # [ 5.832613] systemd-vaultd[782]: 2026/07/29 05:21:01 Listening on /run/systemd-vaultd/sock620server # [ 5.840199] systemd-vaultd[782]: 2026/07/29 05:21:01 Systemd requested secret for service2.service/secret621server # [ 5.846849] systemd-vaultd[782]: 2026/07/29 05:21:01 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service622server # [ 5.856792] systemd-vaultd[782]: 2026/07/29 05:21:01 Block start until service1.service.EnvironmentFile appears623server # [ 5.863645] systemd-vaultd[782]: 2026/07/29 05:21:01 Block start until service2.service.json appears624server # [ 5.870565] systemd[1]: Finished Logrotate configuration check.625server # [ 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"626server # [ 5.882063] dhcpcd[771]: dhcpcd-9.4.1 starting627server # [ 5.896444] dbus-daemon[770]: dbus[770]: Unknown username "systemd-timesync" in message bus configuration file628server # [ 5.898777] systemd[1]: Finished Kernel Auditing.629server # [ 5.900155] dhcpcd[791]: dev: loaded udev630server # [ 5.901429] systemd[1]: Started Name Service Cache Daemon (nsncd).631server # [ 5.902929] dbus-daemon[770]: [system] Successfully activated service 'org.freedesktop.systemd1'632server # [ 5.904576] systemd[1]: Reached target Host and Network Name Lookups.633server # [ 5.906181] systemd[1]: Reached target User and Group Name Lookups.634server # [ 5.907612] systemd[1]: Starting User Login Management...635server # [ 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 database636server # 637server # [ 5.914785] systemd[1]: Stopped target Host and Network Name Lookups.638server # [ 5.916824] systemd[1]: Stopping Host and Network Name Lookups...639server # [ 5.918838] systemd[1]: Stopped target User and Group Name Lookups.640server # [ 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"641server # [ 5.928332] systemd[1]: Stopping User and Group Name Lookups...642server # [ 5.930950] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...643server # [ 5.934077] systemd-logind[817]: New seat seat0.644server # [ 5.935747] systemd-logind[817]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)645server # [ 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 eth1646server # 647server # [ 5.944792] systemd-logind[817]: Watching system buttons on /dev/input/event0 (gpio-keys)648server # [ 5.947250] systemd[1]: Started User Login Management.[ 5.963290] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'649server # 650server # [ 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 -2651server # 652server # [ 5.971721] cfg80211: failed to load regulatory.db653server # [ 5.957972] systemd[1]: nscd.service: Deactivated successfully.654server # [ 5.960120] systemd[1]: Stopped Name Service Cache Daemon (nsncd).655server # [ 5.961941] systemd[1]: Starting Name Service Cache Daemon (nsncd)...656server # [ 5.964022] systemd[1]: Started D-Bus System Message Bus.657server # [ 5.965613] systemd[1]: Finished resolvconf update.658server # [ 5.966959] systemd[1]: Reached target Preparation for Network.659server # [ 5.968713] systemd[1]: Reached target All Network Interfaces (deprecated).660server # [ 5.970743] systemd[1]: Starting Address configuration of eth1...661server # [ 5.972517] systemd[1]: Started Name Service Cache Daemon (nsncd).662server # [ 5.974339] systemd[1]: Reached target Host and Network Name Lookups.663server # [ 5.976105] systemd[1]: Reached target User and Group Name Lookups.664server # [ 5.977858] systemd[1]: Finished Address configuration of eth1.665server # [ 5.988572] systemd[1]: Starting Networking Setup...666server # [ 6.022464] 8021q: adding VLAN 0 to HW filter on device eth0667server # [ 6.007613] dhcpcd[791]: eth0: waiting for carrier668server # [ 6.008529] dhcpcd[791]: eth0: carrier acquired669server # [ 6.018185] dhcpcd[791]: DUID 00:01:00:01:31:fc:4a:3e:52:54:00:12:34:56670server # [ 6.019295] dhcpcd[791]: eth0: IAID 00:12:34:56671server # [ 6.020024] dhcpcd[791]: eth0: adding address fe80::5054:ff:fe12:3456672server # [ 6.094177] systemd[1]: Finished Networking Setup.673server # [ 6.116880] systemd[1]: Starting Extra networking commands....674server # [ 6.130045] systemd[1]: Finished Extra networking commands..675server # [ 6.130916] systemd[1]: Reached target Network.676server # [ 6.133614] systemd[1]: Starting Permit User Sessions...677server # [ 6.136276] systemd[1]: Started vault-agent-default.service.678server # [ 6.140959] systemd[1]: Started Vault server daemon.679server # [ 6.155040] systemd[1]: Finished Permit User Sessions.680server # [ 6.158294] systemd[1]: Started Getty on tty1.681server # [ 6.161090] systemd[1]: Reached target Login Prompts.682server # [ 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 refused683server # [ 6.545872] vault[946]: ==> Vault server configuration:684server # [ 6.547327] vault[946]: Administrative Namespace:685server # [ 6.548632] vault[946]: Api Address: http://127.0.0.1:8200686server # [ 6.550392] vault[946]: Cgo: enabled687server # [ 6.551592] vault[946]: Cluster Address: https://127.0.0.1:8201688server # [ 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, USER689server # [ 6.556959] vault[946]: Go Version: go1.21.3690server # [ 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")691server # [ 6.561859] vault[946]: Log Level:692server # [ 6.562885] vault[946]: Mlock: supported: true, enabled: false693server # [ 6.564351] vault[946]: Recovery Mode: false694server # [ 6.565549] vault[946]: Storage: inmem695server # [ 6.566810] vault[946]: Version: Vault v1.14.4696server # [ 6.568198] setup-vault-agent-approle-start[779]: + sleep 1697server # [ 6.659827] dhcpcd[791]: eth0: soliciting a DHCP lease698server # [ 6.677105] dhcpcd[791]: eth0: offered 10.0.2.15 from 10.0.2.2699server # [ 6.679521] dhcpcd[791]: eth0: leased 10.0.2.15 for 86400 seconds700server # [ 6.682511] dhcpcd[791]: eth0: adding route to 10.0.2.0/24701server # [ 6.685156] dhcpcd[791]: eth0: adding default route via 10.0.2.2702server # [ 6.823975] systemd[1]: Stopped target Host and Network Name Lookups.703server # [ 6.826757] systemd[1]: Stopping Host and Network Name Lookups...704server # [ 6.830677] systemd[1]: Stopped target User and Group Name Lookups.705server # [ 6.834736] systemd[1]: Stopping User and Group Name Lookups...706server # [ 6.837910] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...707server # [ 6.842569] systemd[1]: nscd.service: Deactivated successfully.708server # [ 6.844879] systemd[1]: Stopped Name Service Cache Daemon (nsncd).709server # [ 6.872983] systemd[1]: Starting Name Service Cache Daemon (nsncd)...710server # [ 6.877001] vault[946]: ==> Vault server started! Log data will stream in below:711server # [ 6.881854] vault[946]: 2026-07-29T05:21:02.648Z [INFO] proxy environment: http_proxy="" https_proxy="" no_proxy=""712server # [ 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 set713server # [ 6.896963] vault[946]: 2026-07-29T05:21:02.653Z [INFO] core: Initializing version history cache for core714server # [ 6.902614] vault[946]: 2026-07-29T05:21:02.654Z [INFO] core: security barrier not initialized715server # [ 6.906602] vault[946]: 2026-07-29T05:21:02.655Z [INFO] core: security barrier initialized: stored=1 shares=1 threshold=1716server # [ 6.910846] vault[946]: 2026-07-29T05:21:02.659Z [INFO] core: post-unseal setup starting717server # [ 6.913982] vault[946]: 2026-07-29T05:21:02.667Z [INFO] core: loaded wrapping token key718server # [ 6.916750] vault[946]: 2026-07-29T05:21:02.667Z [INFO] core: successfully setup plugin catalog: plugin-directory=""719server # [ 6.920092] vault[946]: 2026-07-29T05:21:02.670Z [INFO] core: no mounts; adding default mount table720server # [ 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: "721server # [ 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: "722server # [ 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: "723server # [ 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: "724server # [ 6.939288] vault[946]: 2026-07-29T05:21:02.685Z [INFO] rollback: starting rollback manager725server # [ 6.941317] vault[946]: 2026-07-29T05:21:02.687Z [INFO] core: restoring leases726server # [ 6.942956] vault[946]: 2026-07-29T05:21:02.688Z [INFO] identity: entities restored727server # [ 6.944692] vault[946]: 2026-07-29T05:21:02.688Z [INFO] identity: groups restored728server # [ 6.946487] vault[946]: 2026-07-29T05:21:02.689Z [INFO] expiration: lease restore complete729server # [ 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=""730server # [ 6.954496] vault[946]: 2026-07-29T05:21:02.939Z [INFO] core: post-unseal setup complete731server # [ 6.957522] vault[946]: 2026-07-29T05:21:02.940Z [INFO] core: root token generated732server # [ 6.959407] vault[946]: 2026-07-29T05:21:02.940Z [INFO] core: pre-seal teardown starting733server # [ 6.962013] vault[946]: 2026-07-29T05:21:02.941Z [INFO] rollback: stopping rollback manager734server # [ 6.964363] vault[946]: 2026-07-29T05:21:02.942Z [INFO] core: pre-seal teardown complete735server # [ 6.967008] vault[946]: 2026-07-29T05:21:02.943Z [INFO] core.cluster-listener.tcp: starting listener: listener_address=127.0.0.1:8201736server # [ 6.971408] vault[946]: 2026-07-29T05:21:02.943Z [INFO] core.cluster-listener: serving cluster requests: cluster_listen_address=127.0.0.1:8201737server # [ 6.975047] vault[946]: 2026-07-29T05:21:02.943Z [INFO] core: post-unseal setup starting738server # [ 6.977871] vault[946]: 2026-07-29T05:21:02.943Z [INFO] core: loaded wrapping token key739server # [ 6.979895] vault[946]: 2026-07-29T05:21:02.943Z [INFO] core: successfully setup plugin catalog: plugin-directory=""740server # [ 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: "741server # [ 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: "742server # [ 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: "743server # [ 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: "744server # [ 7.002887] vault[946]: 2026-07-29T05:21:02.947Z [INFO] rollback: starting rollback manager745server # [ 7.006413] vault[946]: 2026-07-29T05:21:02.947Z [INFO] core: restoring leases746server # [ 7.008566] vault[946]: 2026-07-29T05:21:02.947Z [INFO] expiration: lease restore complete747server # [ 7.010630] vault[946]: 2026-07-29T05:21:02.947Z [INFO] identity: entities restored748server # [ 7.012814] vault[946]: 2026-07-29T05:21:02.947Z [INFO] identity: groups restored749server # [ 7.014602] vault[946]: 2026-07-29T05:21:02.948Z [INFO] core: post-unseal setup complete750server # [ 7.016558] vault[946]: 2026-07-29T05:21:02.948Z [INFO] core: vault is unsealed751server # [ 7.018341] vault[946]: 2026-07-29T05:21:02.965Z [INFO] expiration: revoked lease: lease_id=auth/token/root/h9935c16ef683591acfa429d8e0b380b5195df23c50dad65352d0eb1e309d3809752server # [ 7.021961] vault[946]: 2026-07-29T05:21:02.982Z [INFO] core: successful mount: namespace="" path=secret/ type=kv version=""753server # [ 7.025246] dhcpcd[791]: eth0: soliciting an IPv6 router754server # [ 7.027378] vault[945]: ==> Vault Agent started! Log data will stream in below:755server # [ 7.030033] vault[945]: ==> Vault Agent configuration:756(finished: waiting for unit vault.service, in 7.22 seconds)757server # [ 7.031327] vault[945]: Api Address 1: http://bufconn758server: waiting for TCP port 8200 on localhost759server # [ 7.032687] vault[945]: Cgo: enabled760server # [ 7.033846] vault[945]: Log Level:761server # [ 7.034859] vault[945]: Version: Vault v1.14.4762server # [ 7.036164] vault[945]: 2026-07-29T05:21:02.992Z [INFO] agent.exec.server: starting exec server763server # [ 7.037919] vault[945]: 2026-07-29T05:21:02.992Z [INFO] agent.exec.server: no env templates or exec config, exiting764server # [ 7.039956] vault[945]: 2026-07-29T05:21:02.992Z [INFO] agent.auth.handler: starting auth handler765server # [ 7.041962] vault[945]: 2026-07-29T05:21:02.992Z [INFO] agent.auth.handler: authenticating766server # [ 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=1s767server # [ 7.049135] vault[945]: 2026-07-29T05:21:02.992Z [INFO] agent.sink.server: starting sink server768server # [ 7.052443] vault[945]: 2026-07-29T05:21:02.992Z [INFO] agent.template.server: starting template server769server # [ 7.055122] vault[945]: 2026-07-29T05:21:02.994Z [INFO] (runner) creating new runner (dry: false, once: false)770server # [ 7.058057] vault[945]: 2026-07-29T05:21:02.997Z [INFO] (runner) creating watcher771server # [ 7.060289] systemd[1]: Started Name Service Cache Daemon (nsncd).772server # [ 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"773server # [ 7.066425] dhcpcd[771]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.774server # [ 7.068359] dhcpcd[771]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.775server # [ 7.070755] dhcpcd[771]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.776server # [ 7.073363] vault[946]: WARNING! dev mode is enabled! In this mode, Vault runs entirely in-memory777server # [ 7.076156] vault[946]: and starts unsealed with a single unseal key. The root token is already778server # [ 7.078722] vault[946]: authenticated to the CLI, so you can immediately begin using Vault.779server # [ 7.081489] vault[946]: You may need to set the following environment variables:780server # [ 7.083732] vault[946]: $ export VAULT_ADDR='http://127.0.0.1:8200'781server # [ 7.085866] vault[946]: The unseal key and root token are displayed below in case you want to782server # [ 7.088636] vault[946]: seal/unseal the Vault or re-authenticate.783server # [ 7.090650] vault[946]: Unseal Key: xNUFq2lm8YnE4J1nUBiDcNU6fm0HYryHG0pVV6lKeSo=784server # [ 7.092941] vault[946]: Root Token: phony-secret785server # [ 7.094208] vault[946]: Development mode should NOT be used in production installations!786server # [ 7.096322] systemd[1]: Reached target Host and Network Name Lookups.787server # [ 7.098393] systemd[1]: Reached target User and Group Name Lookups.788server # [ 7.100438] systemd[1]: Started DHCP Client.789server # [ 7.101707] systemd[1]: Reached target Network is Online.790server # Connection to localhost (127.0.0.1) 8200 port [tcp/trivnet1] succeeded!791(finished: waiting for TCP port 8200 on localhost, in 0.08 seconds)792server: waiting for unit setup-vault-agent-approle.service793server # [ 7.554886] setup-vault-agent-approle-start[779]: + vault status794server # [ 7.684288] setup-vault-agent-approle-start[1045]: Key Value795server # [ 7.686916] setup-vault-agent-approle-start[1045]: --- -----796server # [ 7.689530] setup-vault-agent-approle-start[1045]: Seal Type shamir797server # [ 7.692620] setup-vault-agent-approle-start[1045]: Initialized true798server # [ 7.695667] setup-vault-agent-approle-start[1045]: Sealed false799server # [ 7.698885] setup-vault-agent-approle-start[1045]: Total Shares 1800server # [ 7.701933] setup-vault-agent-approle-start[1045]: Threshold 1801server # [ 7.704855] setup-vault-agent-approle-start[1045]: Version 1.14.4802server # [ 7.708194] setup-vault-agent-approle-start[1045]: Build Date n/a803server # [ 7.710776] setup-vault-agent-approle-start[1045]: Storage Type inmem804server # [ 7.713736] setup-vault-agent-approle-start[1045]: Cluster Name vault-cluster-14cca698805server # [ 7.717599] setup-vault-agent-approle-start[1045]: Cluster ID 4cc08e35-94ae-5164-ffd1-bd93fc877a25806server # [ 7.721147] setup-vault-agent-approle-start[1045]: HA Enabled false807server # [ 7.723692] setup-vault-agent-approle-start[779]: + cat808server # [ 7.725777] setup-vault-agent-approle-start[779]: + vault policy write demo /tmp/policy-file.hcl809server # [ 7.838259] setup-vault-agent-approle-start[1054]: Success! Uploaded policy: demo810server # [ 7.846000] setup-vault-agent-approle-start[779]: + vault kv put secret/my-secret foo=bar811server # [ 7.885577] vault[945]: 2026-07-29T05:21:03.994Z [INFO] agent.auth.handler: authenticating812server # [ 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.73s813server # [ 7.949117] setup-vault-agent-approle-start[1062]: ==== Secret Path ====814server # [ 7.951717] setup-vault-agent-approle-start[1062]: secret/data/my-secret815server # [ 7.954598] setup-vault-agent-approle-start[1062]: ======= Metadata =======816server # [ 7.957723] setup-vault-agent-approle-start[1062]: Key Value817server # [ 7.960882] setup-vault-agent-approle-start[1062]: --- -----818server # [ 7.963892] setup-vault-agent-approle-start[1062]: created_time 2026-07-29T05:21:04.0546492Z819server # [ 7.967765] setup-vault-agent-approle-start[1062]: custom_metadata <nil>820server # [ 7.971227] setup-vault-agent-approle-start[1062]: deletion_time n/a821server # [ 7.974005] setup-vault-agent-approle-start[1062]: destroyed false822server # [ 7.976648] setup-vault-agent-approle-start[1062]: version 1823server # [ 7.979143] setup-vault-agent-approle-start[779]: + vault auth enable approle824server # [ 8.061302] dhcpcd[791]: eth0: Router Advertisement from fe80::2825server # [ 8.063868] dhcpcd[791]: eth0: adding address fec0::5054:ff:fe12:3456/64826server # [ 8.066609] dhcpcd[791]: eth0: adding route to fec0::/64827server # [ 8.068710] dhcpcd[791]: eth0: adding default route via fe80::2828server # [ 8.085404] vault[946]: 2026-07-29T05:21:04.193Z [INFO] core: enabled credential backend: path=approle/ type=approle version=""829server # [ 8.090107] setup-vault-agent-approle-start[1070]: Success! Enabled approle auth method at: approle/830server # [ 8.095217] setup-vault-agent-approle-start[779]: + vault write auth/approle/role/role1 bind_secret_id=true token_policies=demo831server # [ 8.208821] setup-vault-agent-approle-start[1079]: Success! Data written to: auth/approle/role/role1832server # [ 8.222030] setup-vault-agent-approle-start[1091]: ++ vault read -format json auth/approle/role/role1/role-id833server # [ 8.228885] setup-vault-agent-approle-start[1092]: ++ jq -r .data.role_id834server # [ 8.345067] setup-vault-agent-approle-start[779]: + echo -n e84ee7a2-f221-230a-473b-a442280e1a0b835server # [ 8.350101] setup-vault-agent-approle-start[1101]: ++ vault write -force -format json auth/approle/role/role1/secret-id836server # [ 8.356112] setup-vault-agent-approle-start[1102]: ++ jq -r .data.secret_id837server # [ 8.447713] setup-vault-agent-approle-start[779]: + echo -n 964008b0-0fa2-a4ef-c0c7-e218949c0f3e838server # [ 8.452132] systemd[1]: Finished setup-vault-agent-approle.service.839(finished: waiting for unit setup-vault-agent-approle.service, in 2.24 seconds)840server: waiting for unit vault-agent-default.service841(finished: waiting for unit vault-agent-default.service, in 0.08 seconds)842server: waiting for success: grep -q bar /tmp/service1843server # grep: /tmp/service1: No such file or directory844server # [ 9.619377] vault[945]: 2026-07-29T05:21:05.727Z [INFO] agent.auth.handler: authenticating845server # [ 9.636042] vault[945]: 2026-07-29T05:21:05.744Z [INFO] agent.auth.handler: authentication successful, sending token to sinks846server # [ 9.641542] vault[945]: 2026-07-29T05:21:05.744Z [INFO] agent.auth.handler: starting renewal process847server # [ 9.645858] vault[945]: 2026-07-29T05:21:05.744Z [INFO] agent.template.server: template server received new token848server # [ 9.650155] vault[945]: 2026-07-29T05:21:05.745Z [INFO] (runner) stopping849server # [ 9.652828] vault[945]: 2026-07-29T05:21:05.745Z [INFO] (runner) creating new runner (dry: false, once: false)850server # [ 9.656736] vault[945]: 2026-07-29T05:21:05.745Z [INFO] (runner) creating watcher851server # [ 9.659576] vault[945]: 2026-07-29T05:21:05.746Z [INFO] (runner) starting852server # [ 9.663029] vault[945]: 2026-07-29T05:21:05.753Z [INFO] agent.auth.handler: renewed auth token853server # [ 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")854server # [ 9.676497] vault[945]: 2026-07-29T05:21:05.769Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json"855server # [ 9.682739] vault[945]: 2026-07-29T05:21:05.771Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.EnvironmentFile"856server # [ 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"857server # [ 9.699556] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: 3850182079858server # [ 9.703661] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: service1.service.json859server # [ 9.708147] systemd-vaultd[782]: 2026/07/29 05:21:05 Ignore unknown file: 3850182079860server # [ 9.711166] systemd-vaultd[782]: 2026/07/29 05:21:05 Ignore unknown file: service1.service.json861server # [ 9.714798] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: 1786572219862server # [ 9.717867] systemd-vaultd[782]: 2026/07/29 05:21:05 Ignore unknown file: 1786572219863server # [ 9.720755] systemd-vaultd[782]: 2026/07/29 05:21:05 Detected added file: service1.service.EnvironmentFile864server # [ 9.724268] systemd-vaultd[782]: 2026/07/29 05:21:05 Served service1.service.EnvironmentFile to @a58112c0a2e699fd/unit/service1-envfile.service/service1.service.EnvironmentFile865server # [ 9.729907] systemd[1]: Finished service1-envfile.service.866server # [ 9.731869] vault[945]: 2026-07-29T05:21:05.773Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service1.service'867server # [ 9.736107] systemd[1]: Started service1.service.868server # [ 9.737871] systemd[1]: Stopping service1.service...869server # [ 9.753378] systemd[1]: service1.service: Deactivated successfully.870server # [ 9.755640] systemd[1]: Stopped service1.service.871server # [ 9.757445] systemd[1]: service1-envfile.service: Deactivated successfully.872server # [ 9.759816] systemd[1]: Stopped service1-envfile.service.873server # [ 9.761847] systemd[1]: Stopping service1-envfile.service...874server # [ 9.763796] systemd[1]: Starting service1-envfile.service...875server # [ 9.765986] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.876server # [ 9.769254] systemd-vaultd[782]: 2026/07/29 05:21:05 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service877server # [ 9.778805] systemd[1]: Finished service1-envfile.service.878server # [ 9.783595] systemd[1]: Started service1.service.879server # [ 9.786487] systemd-vaultd[782]: 2026/07/29 05:21:05 Systemd requested secret for service1.service/foo880server # [ 9.821201] systemd[1]: service1.service: Deactivated successfully.881server # [ 9.825378] systemd[1]: run-credentials-service1.service.mount: Deactivated successfully.882server # [ 9.828617] systemd[1]: service1-envfile.service: Deactivated successfully.883server # [ 9.831283] systemd[1]: Stopped service1-envfile.service.884server # [ 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")885server # [ 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")886(finished: waiting for success: grep -q bar /tmp/service1, in 1.11 seconds)887server: must succeed: grep -q bar /tmp/service1-env888(finished: must succeed: grep -q bar /tmp/service1-env, in 0.04 seconds)889server: must succeed: systemctl status service2 || :890(finished: must succeed: systemctl status service2 || :, in 0.07 seconds)891server: must succeed: vault kv put secret/blocking-secret foo=bar892server # [ 10.676929] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.893(finished: must succeed: vault kv put secret/blocking-secret foo=bar, in 0.20 seconds)894server: waiting for success: grep -q bar /tmp/service2 >&2895server # grep: /tmp/service2: No such file or directory896server # [ 11.425320] systemd-vaultd[782]: 2026/07/29 05:21:07 Detected added file: 1567096775897server # [ 11.428944] systemd-vaultd[782]: 2026/07/29 05:21:07 Ignore unknown file: 1567096775898server # [ 11.433281] systemd-vaultd[782]: 2026/07/29 05:21:07 Detected added file: service2.service.json899server # [ 11.437723] systemd-vaultd[782]: 2026/07/29 05:21:07 Served service2.service.json to @c589ffccd7ca25f0/unit/service2.service/secret900server # [ 11.443252] vault[945]: 2026-07-29T05:21:07.533Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"901server # [ 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"902server # [ 11.459903] vault[945]: 2026-07-29T05:21:07.536Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'903server # [ 11.497119] systemd[1]: Started service2.service.904server # [ 11.509149] systemd[1]: Reloading service2.service...905server # [ 11.513860] systemd-vaultd[782]: 2026/07/29 05:21:07 Systemd requested secret for service2.service/secret906server # [ 11.535250] service2-start[1177]: + true907server # [ 11.542006] service2-start[1177]: + cat /run/service2/secrets/secret908server # [ 11.545328] systemd-vaultd-update-secrets[1178]: 2026/07/29 05:21:07 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated909server # [ 11.551395] service2-start[1184]: bar910server # [ 11.553305] service2-start[1177]: + cat /run/service2/secrets/secret911server # [ 11.556290] service2-start[1177]: + sleep 0.1912server # [ 11.661237] service2-start[1177]: + true913server # [ 11.663116] service2-start[1177]: + cat /run/service2/secrets/secret914server # [ 11.669919] service2-start[1187]: bar915server # [ 11.671739] service2-start[1177]: + cat /run/service2/secrets/secret916server # [ 11.678119] service2-start[1177]: + sleep 0.1917server # [ 11.786633] service2-start[1177]: + true918server # [ 11.788665] service2-start[1177]: + cat /run/service2/secrets/secret919server # [ 11.794661] service2-start[1190]: bar920server # [ 11.796426] service2-start[1177]: + cat /run/service2/secrets/secret921server # [ 11.803114] service2-start[1177]: + sleep 0.1922server # [ 11.910737] service2-start[1177]: + true923server # [ 11.913409] service2-start[1177]: + cat /run/service2/secrets/secret924server # [ 11.919080] service2-start[1196]: bar925server # [ 11.921241] service2-start[1177]: + cat /run/service2/secrets/secret926server # [ 11.926937] service2-start[1177]: + sleep 0.1927(finished: waiting for success: grep -q bar /tmp/service2 >&2, in 1.10 seconds)928server: must succeed: umount /run/credentials/service2.service929server # [ 11.989861] systemd[1]: run-credentials-service2.service.mount: Deactivated successfully.930(finished: must succeed: umount /run/credentials/service2.service, in 0.03 seconds)931server: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json932(finished: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json, in 0.04 seconds)933server: must succeed: vault kv put secret/blocking-secret foo=reload934server # [ 12.034874] service2-start[1177]: + true935server # [ 12.037377] service2-start[1177]: + cat /run/service2/secrets/secret936server # [ 12.043333] service2-start[1209]: bar937server # [ 12.045372] service2-start[1177]: + cat /run/service2/secrets/secret938server # [ 12.053506] service2-start[1177]: + sleep 0.1939server # [ 12.161120] service2-start[1177]: + true940server # [ 12.163790] service2-start[1177]: + cat /run/service2/secrets/secret941server # [ 12.170821] service2-start[1222]: bar942server # [ 12.172998] service2-start[1177]: + cat /run/service2/secrets/secret943server # [ 12.179060] service2-start[1177]: + sleep 0.1944server # [ 12.238366] fbcon: Taking over console945server # [ 12.240118] virtio-pci 0000:00:09.0: [drm] drm_plane_enable_fb_damage_clips() not called946server # [ 12.240216] Console: switching to colour frame buffer device 160x50947(finished: must succeed: vault kv put secret/blocking-secret foo=reload, in 0.22 seconds)948server: must succeed: systemctl restart vault-agent-default949server # [ 12.287141] service2-start[1177]: + true950server # [ 12.289630] service2-start[1177]: + cat /run/service2/secrets/secret951server # [ 12.295060] service2-start[1232]: bar952server # [ 12.298071] service2-start[1177]: + cat /run/service2/secrets/secret953server # [ 12.302202] service2-start[1177]: + sleep 0.1954server # [ 12.310318] vault[945]: ==> Vault Agent shutdown triggered955server # [ 12.313707] vault[945]: 2026-07-29T05:21:08.418Z [INFO] (runner) stopping956server # [ 12.316202] vault[945]: 2026-07-29T05:21:08.419Z [INFO] agent.template.server: template server stopped957server # [ 12.321104] vault[945]: 2026-07-29T05:21:08.419Z [INFO] agent.auth.handler: shutdown triggered, stopping lifetime watcher958server # [ 12.328205] vault[945]: 2026-07-29T05:21:08.419Z [INFO] agent.auth.handler: auth handler stopped959server # [ 12.332041] vault[945]: 2026-07-29T05:21:08.419Z [INFO] agent.sink.server: sink server stopped960server # [ 12.335385] vault[945]: 2026-07-29T05:21:08.419Z [INFO] agent: sinks finished, exiting961server # [ 12.339021] vault[945]: 2026-07-29T05:21:08.419Z [INFO] agent.exec.server: exec server stopped962server # [ 12.342347] systemd[1]: Stopping vault-agent-default.service...963server # [ 12.344664] systemd[1]: vault-agent-default.service: Deactivated successfully.964server # [ 12.347064] systemd[1]: Stopped vault-agent-default.service.965server # [ 12.348990] systemd[1]: vault-agent-default.service: Consumed 199ms CPU time, no IO, received 6.3K IP traffic, sent 4.9K IP traffic.966server # [ 12.361265] systemd[1]: Started vault-agent-default.service.967server # [ 12.369600] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.968server # [ 12.372769] systemd[1]: Stopped Virtual Console Setup.969(finished: must succeed: systemctl restart vault-agent-default, in 0.12 seconds)970server: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2971server # [ 12.376170] systemd[1]: Stopping Virtual Console Setup...972server # [ 12.378733] systemd[1]: Starting Virtual Console Setup...973server # [ 12.410405] service2-start[1177]: + true974server # [ 12.413111] service2-start[1177]: + cat /run/service2/secrets/secret975server # [ 12.418070] service2-start[1252]: bar976server # [ 12.420096] service2-start[1177]: + cat /run/service2/secrets/secret977server # [ 12.424330] service2-start[1177]: + sleep 0.1978server # cat: /run/systemd-vaultd/secrets/service2.service.json: No such file or directory979server # [ 12.510976] vault[1239]: ==> Vault Agent started! Log data will stream in below:980server # [ 12.514436] vault[1239]: ==> Vault Agent configuration:981server # [ 12.517157] vault[1239]: Api Address 1: http://bufconn982server # [ 12.519520] vault[1239]: Cgo: enabled983server # [ 12.522344] vault[1239]: Log Level:984server # [ 12.524745] vault[1239]: Version: Vault v1.14.4985server # [ 12.527543] vault[1239]: 2026-07-29T05:21:08.621Z [INFO] agent.exec.server: starting exec server986server # [ 12.532074] vault[1239]: 2026-07-29T05:21:08.621Z [INFO] agent.exec.server: no env templates or exec config, exiting987server # [ 12.538218] vault[1239]: 2026-07-29T05:21:08.621Z [INFO] agent.auth.handler: starting auth handler988server # [ 12.543090] vault[1239]: 2026-07-29T05:21:08.621Z [INFO] agent.auth.handler: authenticating989server # [ 12.547300] vault[1239]: 2026-07-29T05:21:08.622Z [INFO] agent.sink.server: starting sink server990server # [ 12.551810] vault[1239]: 2026-07-29T05:21:08.622Z [INFO] agent.template.server: starting template server991server # [ 12.556662] vault[1239]: 2026-07-29T05:21:08.622Z [INFO] (runner) creating new runner (dry: false, once: false)992server # [ 12.562058] systemd-vaultd[782]: 2026/07/29 05:21:08 Detected added file: 1840851771993server # [ 12.565847] systemd-vaultd[782]: 2026/07/29 05:21:08 Detected added file: service2.service.json994server # [ 12.569794] systemd-vaultd[782]: 2026/07/29 05:21:08 Ignore unknown file: 1840851771995server # [ 12.573278] systemd-vaultd[782]: 2026/07/29 05:21:08 Ignore unknown file: service2.service.json996server # [ 12.600300] systemd[1]: Reloaded service2.service.997server # [ 12.603024] service2-start[1177]: + true998server # [ 12.604866] service2-start[1177]: + cat /run/service2/secrets/secret999server # [ 12.607912] vault[1239]: 2026-07-29T05:21:08.623Z [INFO] (runner) creating watcher1000server # [ 12.611405] vault[1239]: 2026-07-29T05:21:08.624Z [INFO] agent.auth.handler: authentication successful, sending token to sinks1001server # [ 12.616332] vault[1239]: 2026-07-29T05:21:08.624Z [INFO] agent.auth.handler: starting renewal process1002server # [ 12.620334] vault[1239]: 2026-07-29T05:21:08.624Z [INFO] agent.template.server: template server received new token1003server # [ 12.625049] vault[1239]: 2026-07-29T05:21:08.624Z [INFO] (runner) stopping1004server # [ 12.628267] vault[1239]: 2026-07-29T05:21:08.624Z [INFO] (runner) creating new runner (dry: false, once: false)1005server # [ 12.632622] vault[1239]: 2026-07-29T05:21:08.625Z [INFO] (runner) creating watcher1006server # [ 12.635885] vault[1239]: 2026-07-29T05:21:08.625Z [INFO] (runner) starting1007server # [ 12.638929] vault[1239]: 2026-07-29T05:21:08.628Z [INFO] agent.auth.handler: renewed auth token1008server # [ 12.642810] vault[1239]: 2026-07-29T05:21:08.640Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"1009server # [ 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"1010server # [ 12.656714] vault[1239]: 2026-07-29T05:21:08.641Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'1011server # [ 12.661893] systemd[1]: Reached target Multi-User System.1012server # [ 12.664319] systemd[1]: Reloading service2.service...1013server # [ 12.666595] service2-start[1261]: bar1014server # [ 12.668232] service2-start[1177]: + cat /run/service2/secrets/secret1015server # [ 12.670821] service2-start[1177]: + sleep 0.11016server # [ 12.672627] service2-start[1177]: + true1017server # [ 12.674184] service2-start[1177]: + cat /run/service2/secrets/secret1018server # [ 12.677009] systemd-vaultd-update-secrets[1266]: 2026/07/29 05:21:08 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated1019server # [ 12.681730] service2-start[1272]: reload1020server # [ 12.683235] service2-start[1177]: + cat /run/service2/secrets/secret1021server # [ 12.685429] service2-start[1177]: + sleep 0.11022server # [ 12.694867] systemd[1]: Finished Virtual Console Setup.1023server # [ 12.764774] service2-start[1177]: + true1024server # [ 12.766445] service2-start[1177]: + cat /run/service2/secrets/secret1025server # [ 12.772589] service2-start[1277]: reload1026server # [ 12.774513] service2-start[1177]: + cat /run/service2/secrets/secret1027server # [ 12.780495] service2-start[1177]: + sleep 0.11028server # [ 12.888990] service2-start[1177]: + true1029server # [ 12.890905] service2-start[1177]: + cat /run/service2/secrets/secret1030server # [ 12.897166] service2-start[1280]: reload1031server # [ 12.899027] service2-start[1177]: + cat /run/service2/secrets/secret1032server # [ 12.905645] service2-start[1177]: + sleep 0.11033server # [ 13.013608] service2-start[1177]: + true1034server # [ 13.015404] service2-start[1177]: + cat /run/service2/secrets/secret1035server # [ 13.022131] service2-start[1283]: reload1036server # [ 13.024083] service2-start[1177]: + cat /run/service2/secrets/secret1037server # [ 13.030214] service2-start[1177]: + sleep 0.11038server # [ 13.138727] service2-start[1177]: + true1039server # [ 13.140672] service2-start[1177]: + cat /run/service2/secrets/secret1040server # [ 13.146684] service2-start[1286]: reload1041server # [ 13.148574] service2-start[1177]: + cat /run/service2/secrets/secret1042server # [ 13.155185] service2-start[1177]: + sleep 0.11043server # [ 13.263163] service2-start[1177]: + true1044server # [ 13.265046] service2-start[1177]: + cat /run/service2/secrets/secret1045server # [ 13.272098] service2-start[1289]: reload1046server # [ 13.274019] service2-start[1177]: + cat /run/service2/secrets/secret1047server # [ 13.280164] service2-start[1177]: + sleep 0.11048server # [ 13.388629] service2-start[1177]: + true1049server # [ 13.390502] service2-start[1177]: + cat /run/service2/secrets/secret1050server # [ 13.396932] service2-start[1292]: reload1051server # [ 13.398795] service2-start[1177]: + cat /run/service2/secrets/secret1052server # [ 13.405343] service2-start[1177]: + sleep 0.11053server # 1054server # {"secret":"reload"}1055(finished: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2, in 1.12 seconds)1056server: must succeed: systemctl restart service21057server # [ 13.513219] service2-start[1177]: + true1058server # [ 13.515349] service2-start[1177]: + cat /run/service2/secrets/secret1059server # [ 13.521020] service2-start[1303]: reload1060server # [ 13.523239] service2-start[1177]: + cat /run/service2/secrets/secret1061server # [ 13.529278] service2-start[1177]: + sleep 0.11062server # [ 13.552097] systemd[1]: service2.service: Control process exited, code=killed, status=15/TERM1063server # [ 13.557245] systemd[1]: service2.service: Failed with result 'signal'.1064server # [ 13.561179] systemd[1]: Stopped service2.service.1065server # [ 13.589579] systemd[1]: Starting service2.service...1066server # [ 13.613129] systemd-vaultd[782]: 2026/07/29 05:21:09 Systemd requested secret for service2.service/secret1067server # [ 13.660736] systemd[1]: Started service2.service.1068server # [ 13.662833] systemd[1]: Startup finished in 3.031s (kernel) + 10.631s (userspace) = 13.662s.1069(finished: must succeed: systemctl restart service2, in 0.18 seconds)1070server: must succeed: rm /tmp/service21071server # [ 13.673673] systemd-vaultd[782]: 2026/07/29 05:21:09 Systemd requested secret for service2.service/secret1072server # [ 13.692120] service2-start[1313]: + true1073server # [ 13.694181] service2-start[1313]: + cat /run/service2/secrets/secret1074server # [ 13.700744] service2-start[1318]: reload1075server # [ 13.703134] service2-start[1313]: + cat /run/service2/secrets/secret1076server # [ 13.708891] service2-start[1313]: + sleep 0.11077(finished: must succeed: rm /tmp/service2, in 0.04 seconds)1078server: waiting for success: grep -q reload /tmp/service2 >&21079server # grep: /tmp/service2: No such file or directory1080server # [ 13.818503] service2-start[1313]: + true1081server # [ 13.820554] service2-start[1313]: + cat /run/service2/secrets/secret1082server # [ 13.827299] service2-start[1326]: reload1083server # [ 13.829238] service2-start[1313]: + cat /run/service2/secrets/secret1084server # [ 13.835528] service2-start[1313]: + sleep 0.11085server # [ 13.944830] service2-start[1313]: + true1086server # [ 13.946843] service2-start[1313]: + cat /run/service2/secrets/secret1087server # [ 13.952952] service2-start[1329]: reload1088server # [ 13.954753] service2-start[1313]: + cat /run/service2/secrets/secret1089server # [ 13.961631] service2-start[1313]: + sleep 0.11090server # [ 14.069603] service2-start[1313]: + true1091server # [ 14.071451] service2-start[1313]: + cat /run/service2/secrets/secret1092server # [ 14.078229] service2-start[1332]: reload1093server # [ 14.080161] service2-start[1313]: + cat /run/service2/secrets/secret1094server # [ 14.086385] service2-start[1313]: + sleep 0.11095server # [ 14.194930] service2-start[1313]: + true1096server # [ 14.196889] service2-start[1313]: + cat /run/service2/secrets/secret1097server # [ 14.203098] service2-start[1335]: reload1098server # [ 14.205003] service2-start[1313]: + cat /run/service2/secrets/secret1099server # [ 14.211622] service2-start[1313]: + sleep 0.11100server # [ 14.319590] service2-start[1313]: + true1101server # [ 14.321584] service2-start[1313]: + cat /run/service2/secrets/secret1102server # [ 14.328209] service2-start[1338]: reload1103server # [ 14.330106] service2-start[1313]: + cat /run/service2/secrets/secret1104server # [ 14.336396] service2-start[1313]: + sleep 0.11105server # [ 14.445110] service2-start[1313]: + true1106server # [ 14.447052] service2-start[1313]: + cat /run/service2/secrets/secret1107server # [ 14.453230] service2-start[1341]: reload1108server # [ 14.455051] service2-start[1313]: + cat /run/service2/secrets/secret1109server # [ 14.461908] service2-start[1313]: + sleep 0.11110server # [ 14.569868] service2-start[1313]: + true1111server # [ 14.571749] service2-start[1313]: + cat /run/service2/secrets/secret1112server # [ 14.578477] service2-start[1344]: reload1113server # [ 14.580394] service2-start[1313]: + cat /run/service2/secrets/secret1114server # [ 14.586665] service2-start[1313]: + sleep 0.11115server # [ 14.695670] service2-start[1313]: + true1116server # [ 14.697748] service2-start[1313]: + cat /run/service2/secrets/secret1117server # [ 14.703481] service2-start[1347]: reload1118server # [ 14.705407] service2-start[1313]: + cat /run/service2/secrets/secret1119server # [ 14.711896] service2-start[1313]: + sleep 0.11120(finished: waiting for success: grep -q reload /tmp/service2 >&2, in 1.10 seconds)1121server: must succeed: stat -c %u /run/service2/secrets/secret1122server # [ 14.819699] service2-start[1313]: + true1123server # [ 14.822337] service2-start[1313]: + cat /run/service2/secrets/secret1124server # [ 14.827922] service2-start[1357]: reload1125server # [ 14.830267] service2-start[1313]: + cat /run/service2/secrets/secret1126server # [ 14.836341] service2-start[1313]: + sleep 0.11127(finished: must succeed: stat -c %u /run/service2/secrets/secret, in 0.04 seconds)1128server: must succeed: stat -c %g /run/service2/secrets/secret1129(finished: must succeed: stat -c %g /run/service2/secrets/secret, in 0.04 seconds)1130server: must succeed: stat -c %a /run/service2/secrets/secret1131(finished: must succeed: stat -c %a /run/service2/secrets/secret, in 0.03 seconds)1132(finished: run the VM test script, in 15.46 seconds)1133server # [ 14.944401] service2-start[1313]: + true1134server # [ 14.947007] service2-start[1313]: + cat /run/service2/secrets/secret1135server # [ 14.952581] service2-start[1371]: reload1136server # [ 14.954924] service2-start[1313]: + cat /run/service2/secrets/secret1137server # [ 14.960437] service2-start[1313]: + sleep 0.11138test script finished in 15.52s1139cleanup1140kill machine (pid 6)1141server # qemu-system-aarch64: terminating on signal 15 from pid 4 (/nix/store/nxxghhhia9x1skpqfak4z407yy0mbnnd-python3-3.11.5/bin/python3.11)1142(finished: cleanup, in 0.11 seconds)1143additionally exposed symbols:1144 server,1145 vlan1,1146 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, Machine1147● service2.service1148 Loaded: loaded (/etc/systemd/system/service2.service; enabled; preset: enabled)1149 Active: activating (start-pre) since Wed 2026-07-29 05:21:01 UTC; 4s ago1150Cntrl PID: 777 ((re-start))1151 IP: 0B in, 0B out1152 IO: 0B read, 0B written1153 Tasks: 2 (limit: 1086)1154 Memory: 372.0K1155 CPU: 3ms1156 CGroup: /system.slice/service2.service1157 ├─777 "(re-start)"1158 └─781 "(sd-mkdcreds)"11591160Jul 29 05:21:01 server systemd[1]: Starting service2.service...11611162kill vlan (pid 5)