vm-test-run-systemd-vaultd
default.checks.aarch64-linux.systemd-vaultd
· build #6
· 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 # Disk image do not exist, creating the virtualisation disk image...11server: QEMU running (pid 6)12server # Formatting '/build/vm-state-server/tmp.Pm1LU3mQIQ', fmt=raw size=107374182413server # mke2fs 1.47.0 (5-Feb-2023)14server # Discarding device blocks: 0/262144 done15(finished: start all VMs, in 0.39 seconds)16server # Creating filesystem with 262144 4k blocks and 65536 inodes17server: waiting for unit vault.service18server # Filesystem UUID: 6ced853a-2d04-42e6-896c-fc7f0990c74919server: waiting for the VM to finish booting20server # 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 0x7fdf59c0-0x7fdf8fff]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/41f4b25x4sb0mhs0xcphpqx53lfx6l8f-nixos-system-server-23.11pre-git/init regInfo=/nix/store/fq4ddkzv89mrxw9pyf40i0c3iy41ivbj-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/fq4ddkzv89mrxw9pyf40i0c3iy41ivbj-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: 927552K/1048576K available (20544K kernel code, 5386K rwdata, 18796K rodata, 12032K init, 743K bss, 88256K 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.000097] Console: colour dummy device 80x2597server # [ 0.000120] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)98server # [ 0.000123] pid_max: default: 32768 minimum: 30199server # [ 0.000143] LSM: Security Framework initializing100server # [ 0.000149] landlock: Up and running.101server # [ 0.000150] Yama: becoming mindful.102server # [ 0.000159] LSM support for eBPF active103server # [ 0.000177] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)104server # [ 0.000179] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)105server # [ 0.000535] cblist_init_generic: Setting adjustable number of callback queues.106server # [ 0.000537] cblist_init_generic: Setting shift to 2 and lim to 1.107server # [ 0.000548] cblist_init_generic: Setting adjustable number of callback queues.108server # [ 0.000549] cblist_init_generic: Setting shift to 2 and lim to 1.109server # [ 0.000584] rcu: Hierarchical SRCU implementation.110server # [ 0.000585] rcu: Max phase no-delay instances is 1000.111server # [ 0.000928] Platform MSI: its@8080000 domain created112server # [ 0.000937] PCI/MSI: /intc@8000000/its@8080000 domain created113server # [ 0.000946] fsl-mc MSI: its@8080000 domain created114server # [ 0.001045] EFI services will not be available.115server # [ 0.001087] smp: Bringing up secondary CPUs ...116server # [ 0.001553] Detected PIPT I-cache on CPU1117server # [ 0.001667] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000118server # [ 0.001794] GICv3: CPU1: using allocated LPI pending table @0x0000000043e60000119server # [ 0.001907] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40]120server # [ 0.002415] Detected PIPT I-cache on CPU2121server # [ 0.002541] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000122server # [ 0.002670] GICv3: CPU2: using allocated LPI pending table @0x0000000043e70000123server # [ 0.002791] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40]124server # [ 0.003183] Detected PIPT I-cache on CPU3125server # [ 0.003319] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000126server # [ 0.003444] GICv3: CPU3: using allocated LPI pending table @0x0000000043e80000127server # [ 0.003560] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40]128server # [ 0.003681] smp: Brought up 1 node, 4 CPUs129server # [ 0.003684] SMP: Total of 4 processors activated.130server # [ 0.003686] CPU features: detected: Branch Target Identification131server # [ 0.003688] CPU features: detected: ARMv8.4 Translation Table Level132server # [ 0.003689] CPU features: detected: Instruction cache invalidation not required for I/D coherence133server # [ 0.003690] CPU features: detected: Data cache clean to the PoU not required for I/D coherence134server # [ 0.003692] CPU features: detected: Common not Private translations135server # [ 0.003693] CPU features: detected: CRC32 instructions136server # [ 0.003694] CPU features: detected: E0PD137server # [ 0.003695] CPU features: detected: Enhanced Counter Virtualization138server # [ 0.003696] CPU features: detected: Generic authentication (architected QARMA5 algorithm)139server # [ 0.003698] CPU features: detected: RCpc load-acquire (LDAPR)140server # [ 0.003699] CPU features: detected: LSE atomic instructions141server # [ 0.003700] CPU features: detected: Privileged Access Never142server # [ 0.003701] CPU features: detected: RAS Extension Support143server # [ 0.003702] CPU features: detected: Random Number Generator144server # [ 0.003703] CPU features: detected: Speculation barrier (SB)145server # [ 0.003704] CPU features: detected: Stage-2 Force Write-Back146server # [ 0.003705] CPU features: detected: TLB range maintenance instructions147server # [ 0.003706] CPU features: detected: Speculative Store Bypassing Safe (SSBS)148server # [ 0.003813] CPU: All CPU(s) started at EL1149server # [ 0.003818] alternatives: applying system-wide alternatives150server # [ 0.007873] devtmpfs: initialized151server # [ 0.008509] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns152server # [ 0.008513] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)153server # [ 0.008652] pinctrl core: initialized pinctrl subsystem154server # [ 0.008923] DMI not present or invalid.155server # [ 0.009061] NET: Registered PF_NETLINK/PF_ROUTE protocol family156server # [ 0.009754] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations157server # [ 0.009788] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations158server # [ 0.009821] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations159server # [ 0.009834] audit: initializing netlink subsys (disabled)160server # [ 0.009908] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1161server # [ 0.010042] thermal_sys: Registered thermal governor 'step_wise'162server # [ 0.010043] thermal_sys: Registered thermal governor 'power_allocator'163server # [ 0.010053] cpuidle: using governor ladder164server # [ 0.010055] cpuidle: using governor menu165server # [ 0.010104] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.166server # [ 0.010273] ASID allocator initialised with 65536 entries167server # [ 0.010507] Serial: AMBA PL011 UART driver168server # [ 0.012329] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1169server # [ 0.113970] printk: console [ttyAMA0] enabled170server # [ 0.115019] KASLR enabled171server # [ 0.137099] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages172server # [ 0.137914] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page173server # [ 0.138662] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages174server # [ 0.139455] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page175server # [ 0.140247] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages176server # [ 0.141053] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page177server # [ 0.141782] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages178server # [ 0.142596] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page179server # [ 0.146340] ACPI: Interpreter disabled.180server # [ 0.147174] iommu: Default domain type: Translated181server # [ 0.147759] iommu: DMA domain TLB invalidation policy: strict mode182server # [ 0.148612] SCSI subsystem initialized183server # [ 0.149206] usbcore: registered new interface driver usbfs184server # [ 0.149888] usbcore: registered new interface driver hub185server # [ 0.150530] usbcore: registered new device driver usb186server # [ 0.151273] pps_core: LinuxPPS API ver. 1 registered187server # [ 0.151863] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>188server # [ 0.152986] PTP clock support registered189server # [ 0.153486] EDAC MC: Ver: 3.0.0190server # [ 0.154225] FPGA manager framework191server # [ 0.154661] Advanced Linux Sound Architecture Driver Initialized.192server # [ 0.156825] vgaarb: loaded193server # [ 0.160501] clocksource: Switched to clocksource arch_sys_counter194server # [ 0.161336] VFS: Disk quotas dquot_6.6.0195server # [ 0.161827] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)196server # [ 0.162685] pnp: PnP ACPI: disabled197server # [ 0.167015] NET: Registered PF_INET protocol family198server # [ 0.167649] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)199server # [ 0.168835] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)200server # [ 0.169823] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)201server # [ 0.170733] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)202server # [ 0.171672] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)203server # [ 0.172610] TCP: Hash tables configured (established 8192 bind 8192)204server # [ 0.173419] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)205server # [ 0.174308] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)206server # [ 0.175161] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)207server # [ 0.176021] NET: Registered PF_UNIX/PF_LOCAL protocol family208server # [ 0.176892] RPC: Registered named UNIX socket transport module.209server # [ 0.177605] RPC: Registered udp transport module.210server # [ 0.178162] RPC: Registered tcp transport module.211server # [ 0.178719] RPC: Registered tcp NFSv4.1 backchannel transport module.212server # [ 0.179488] NET: Registered PF_XDP protocol family213server # [ 0.180057] PCI: CLS 0 bytes, default 64214server # [ 0.180693] Trying to unpack rootfs image as initramfs...215server # [ 0.216589] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 counters available216server # [ 0.219545] kvm [1]: HYP mode not available217server # [ 0.221781] Initialise system trusted keyrings218server # [ 0.223585] workingset: timestamp_bits=42 max_order=18 bucket_order=0219server # [ 0.228796] zbud: loaded220server # [ 0.230625] squashfs: version 4.0 (2009/01/31) Phillip Lougher221server # [ 0.233274] NFS: Registering the id_resolver key type222server # [ 0.235156] Key type id_resolver registered223server # [ 0.236787] Key type id_legacy registered224server # [ 0.238322] nfs4filelayout_init: NFSv4 File Layout Driver Registering...225server # [ 0.240815] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...226server # [ 0.243583] 9p: Installing v9fs 9p2000 file system support227server # [ 0.275230] Key type asymmetric registered228server # [ 0.276399] Asymmetric key parser 'x509' registered229server # [ 0.302266] Freeing initrd memory: 9012K230server # [ 0.303850] alg: self-tests for CTR-KDF (hmac(sha256)) passed231server # [ 0.305490] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)232server # [ 0.307559] io scheduler mq-deadline registered233server # [ 0.308820] io scheduler kyber registered234server # [ 0.313258] pl061_gpio 9030000.pl061: PL061 GPIO chip registered235server # [ 0.315464] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:236server # [ 0.317526] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000237server # [ 0.319846] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000238server # [ 0.322291] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000239server # [ 0.324640] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits240server # [ 0.326831] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]241server # [ 0.329351] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00242server # [ 0.331199] pci_bus 0000:00: root bus resource [bus 00-ff]243server # [ 0.332657] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]244server # [ 0.334287] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]245server # [ 0.336086] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]246server # [ 0.338156] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000247server # [ 0.340453] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000248server # [ 0.342163] pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f]249server # [ 0.343568] pci 0000:00:01.0: reg 0x14: [mem 0x00000000-0x00000fff]250server # [ 0.345281] pci 0000:00:01.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]251server # [ 0.346964] pci 0000:00:01.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]252server # [ 0.349255] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00253server # [ 0.350655] pci 0000:00:02.0: reg 0x10: [io 0x0000-0x001f]254server # [ 0.351925] pci 0000:00:02.0: reg 0x14: [mem 0x00000000-0x00000fff]255server # [ 0.353502] pci 0000:00:02.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]256server # [ 0.355718] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200257server # [ 0.357104] pci 0000:00:03.0: reg 0x10: [io 0x0000-0x003f]258server # [ 0.358242] pci 0000:00:03.0: reg 0x14: [mem 0x00000000-0x00000fff]259server # [ 0.359626] pci 0000:00:03.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]260server # [ 0.361754] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200261server # [ 0.362965] pci 0000:00:04.0: reg 0x10: [io 0x0000-0x001f]262server # [ 0.364059] pci 0000:00:04.0: reg 0x14: [mem 0x00000000-0x00000fff]263server # [ 0.365428] pci 0000:00:04.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]264server # [ 0.367337] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200265server # [ 0.368487] pci 0000:00:05.0: reg 0x10: [io 0x0000-0x001f]266server # [ 0.369576] pci 0000:00:05.0: reg 0x14: [mem 0x00000000-0x00000fff]267server # [ 0.370769] pci 0000:00:05.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]268server # [ 0.372612] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000269server # [ 0.373693] pci 0000:00:06.0: reg 0x10: [io 0x0000-0x007f]270server # [ 0.374639] pci 0000:00:06.0: reg 0x14: [mem 0x00000000-0x00000fff]271server # [ 0.375790] pci 0000:00:06.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]272server # [ 0.377569] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000273server # [ 0.378592] pci 0000:00:07.0: reg 0x10: [io 0x0000-0x001f]274server # [ 0.379515] pci 0000:00:07.0: reg 0x14: [mem 0x00000000-0x00000fff]275server # [ 0.380636] pci 0000:00:07.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]276server # [ 0.381778] pci 0000:00:07.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]277server # [ 0.383297] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000278server # [ 0.384311] pci 0000:00:08.0: reg 0x14: [mem 0x00000000-0x00000fff]279server # [ 0.385428] pci 0000:00:08.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]280server # [ 0.386986] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000281server # [ 0.387967] pci 0000:00:09.0: reg 0x14: [mem 0x00000000-0x00000fff]282server # [ 0.389035] pci 0000:00:09.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]283server # [ 0.390478] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320284server # [ 0.391400] pci 0000:00:0a.0: reg 0x10: [mem 0x00000000-0x00000fff]285server # [ 0.392654] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000286server # [ 0.393639] pci 0000:00:0b.0: reg 0x10: [io 0x0000-0x003f]287server # [ 0.394435] pci 0000:00:0b.0: reg 0x14: [mem 0x00000000-0x00000fff]288server # [ 0.395405] pci 0000:00:0b.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]289server # [ 0.396898] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00290server # [ 0.397789] pci 0000:00:0c.0: reg 0x10: [io 0x0000-0x001f]291server # [ 0.398582] pci 0000:00:0c.0: reg 0x14: [mem 0x00000000-0x00000fff]292server # [ 0.399535] pci 0000:00:0c.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]293server # [ 0.401127] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref]294server # [ 0.402077] pci 0000:00:07.0: BAR 6: assigned [mem 0x10040000-0x1007ffff pref]295server # [ 0.403032] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref]296server # [ 0.404127] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref]297server # [ 0.405580] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref]298server # [ 0.406717] pci 0000:00:04.0: BAR 4: assigned [mem 0x800000c000-0x800000ffff 64bit pref]299server # [ 0.407847] pci 0000:00:05.0: BAR 4: assigned [mem 0x8000010000-0x8000013fff 64bit pref]300server # [ 0.409035] pci 0000:00:06.0: BAR 4: assigned [mem 0x8000014000-0x8000017fff 64bit pref]301server # [ 0.410165] pci 0000:00:07.0: BAR 4: assigned [mem 0x8000018000-0x800001bfff 64bit pref]302server # [ 0.411305] pci 0000:00:08.0: BAR 4: assigned [mem 0x800001c000-0x800001ffff 64bit pref]303server # [ 0.412441] pci 0000:00:09.0: BAR 4: assigned [mem 0x8000020000-0x8000023fff 64bit pref]304server # [ 0.413655] pci 0000:00:0b.0: BAR 4: assigned [mem 0x8000024000-0x8000027fff 64bit pref]305server # [ 0.414815] pci 0000:00:0c.0: BAR 4: assigned [mem 0x8000028000-0x800002bfff 64bit pref]306server # [ 0.415929] pci 0000:00:01.0: BAR 1: assigned [mem 0x10080000-0x10080fff]307server # [ 0.416887] pci 0000:00:02.0: BAR 1: assigned [mem 0x10081000-0x10081fff]308server # [ 0.417811] pci 0000:00:03.0: BAR 1: assigned [mem 0x10082000-0x10082fff]309server # [ 0.418738] pci 0000:00:04.0: BAR 1: assigned [mem 0x10083000-0x10083fff]310server # [ 0.419658] pci 0000:00:05.0: BAR 1: assigned [mem 0x10084000-0x10084fff]311server # [ 0.420608] pci 0000:00:06.0: BAR 1: assigned [mem 0x10085000-0x10085fff]312server # [ 0.421540] pci 0000:00:07.0: BAR 1: assigned [mem 0x10086000-0x10086fff]313server # [ 0.422467] pci 0000:00:08.0: BAR 1: assigned [mem 0x10087000-0x10087fff]314server # [ 0.423401] pci 0000:00:09.0: BAR 1: assigned [mem 0x10088000-0x10088fff]315server # [ 0.424324] pci 0000:00:0a.0: BAR 0: assigned [mem 0x10089000-0x10089fff]316server # [ 0.425320] pci 0000:00:0b.0: BAR 1: assigned [mem 0x1008a000-0x1008afff]317server # [ 0.426241] pci 0000:00:0c.0: BAR 1: assigned [mem 0x1008b000-0x1008bfff]318server # [ 0.427167] pci 0000:00:06.0: BAR 0: assigned [io 0x1000-0x107f]319server # [ 0.427996] pci 0000:00:03.0: BAR 0: assigned [io 0x1080-0x10bf]320server # [ 0.428863] pci 0000:00:0b.0: BAR 0: assigned [io 0x10c0-0x10ff]321server # [ 0.429693] pci 0000:00:01.0: BAR 0: assigned [io 0x1100-0x111f]322server # [ 0.430497] pci 0000:00:02.0: BAR 0: assigned [io 0x1120-0x113f]323server # [ 0.431306] pci 0000:00:04.0: BAR 0: assigned [io 0x1140-0x115f]324server # [ 0.432111] pci 0000:00:05.0: BAR 0: assigned [io 0x1160-0x117f]325server # [ 0.432966] pci 0000:00:07.0: BAR 0: assigned [io 0x1180-0x119f]326server # [ 0.433770] pci 0000:00:0c.0: BAR 0: assigned [io 0x11a0-0x11bf]327server # [ 0.434631] pci 0000:00:0a.0: enabling device (0000 -> 0002)328server # [ 0.436455] EINJ: ACPI disabled.329server # [ 0.440575] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)330server # [ 0.443069] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)331server # [ 0.445557] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)332server # [ 0.448080] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)333server # [ 0.450716] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)334server # [ 0.453374] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)335server # [ 0.457166] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)336server # [ 0.459538] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)337server # [ 0.461855] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)338server # [ 0.464499] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)339server # [ 0.467113] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)340server # [ 0.470511] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled341server # [ 0.471931] SuperH (H)SCI(F) driver initialized342server # [ 0.472664] msm_serial: driver initialized343server # [ 0.484276] loop: module loaded344server # [ 0.484967] virtio_blk virtio5: 4/0/0 default/read/poll queues345server # [ 0.486870] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)346server # [ 0.489833] megasas: 07.719.03.00-rc1347server # [ 0.490600] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]348server # [ 0.498956] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000349server # [ 0.500234] Intel/Sharp Extended Query Table at 0x0031350server # [ 0.503387] Using buffer write method351server # [ 0.503891] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]352server # [ 0.507145] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000353server # [ 0.508372] Intel/Sharp Extended Query Table at 0x0031354server # [ 0.511114] Using buffer write method355server # [ 0.511586] Concatenating MTD devices:356server # [ 0.512035] (0): "0.flash"357server # [ 0.513415] (1): "0.flash"358server # [ 0.513745] into device "0.flash"359server # [ 0.516049] tun: Universal TUN/TAP device driver, 1.6360server # [ 0.519058] thunder_xcv, ver 1.0361server # [ 0.519468] thunder_bgx, ver 1.0362server # [ 0.519874] nicpf, ver 1.0363server # [ 0.520422] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version364server # [ 0.521371] hns3: Copyright (c) 2017 Huawei Corporation.365server # [ 0.522018] hclge is initializing366server # [ 0.522422] e1000: Intel(R) PRO/1000 Network Driver367server # [ 0.522998] e1000: Copyright (c) 1999-2006 Intel Corporation.368server # [ 0.523686] e1000e: Intel(R) PRO/1000 Network Driver369server # [ 0.524272] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370server # [ 0.525066] igb: Intel(R) Gigabit Ethernet Network Driver371server # [ 0.525706] igb: Copyright (c) 2007-2014 Intel Corporation.372server # [ 0.526378] igbvf: Intel(R) Gigabit Virtual Function Network Driver373server # [ 0.527119] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374server # [ 0.527886] sky2: driver version 1.30375server # [ 0.528495] VFIO - User Level meta-driver version: 0.3376server # [ 0.530046] ehci-pci 0000:00:0a.0: EHCI Host Controller377server # [ 0.530691] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1378server # [ 0.531723] ehci-pci 0000:00:0a.0: irq 16, io mem 0x10089000379server # [ 0.548539] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00380server # [ 0.549376] hub 1-0:1.0: USB hub found381server # [ 0.549858] hub 1-0:1.0: 6 ports detected382server # [ 0.550783] usbcore: registered new interface driver usb-storage383server # [ 0.552131] rtc-pl031 9010000.pl031: registered as rtc0384server # [ 0.552868] rtc-pl031 9010000.pl031: setting system clock to 2026-07-29T05:20:22 UTC (1785302422)385server # [ 0.554141] i2c_dev: i2c /dev entries driver386server # [ 0.555849] sdhci: Secure Digital Host Controller Interface driver387server # [ 0.556662] sdhci: Copyright(c) Pierre Ossman388server # [ 0.557379] Synopsys Designware Multimedia Card Interface Driver389server # [ 0.558336] sdhci-pltfm: SDHCI platform and OF driver helper390server # [ 0.559585] ledtrig-cpu: registered to indicate activity on CPUs391server # [ 0.560737] hid: raw HID events driver (C) Jiri Kosina392server # [ 0.561493] usbcore: registered new interface driver usbhid393server # [ 0.562207] usbhid: USB HID core driver394server # [ 0.563647] drop_monitor: Initializing network drop monitor service395server # [ 0.579489] NET: Registered PF_INET6 protocol family396server # [ 0.581462] Segment Routing with IPv6397server # [ 0.581918] In-situ OAM (IOAM) with IPv6398server # [ 0.582402] NET: Registered PF_PACKET protocol family399server # [ 0.583167] 9pnet: Installing 9P2000 support400server # [ 0.585527] Key type dns_resolver registered401server # [ 0.586247] registered taskstats version 1402server # [ 0.586748] Loading compiled-in X.509 certificates403server # [ 0.587543] zswap: loaded using pool lzo/zbud404server # [ 0.588374] Key type .fscrypt registered405server # [ 0.588925] Key type fscrypt-provisioning registered406server # [ 0.593378] input: gpio-keys as /devices/platform/gpio-keys/input/input0407server # [ 0.594501] ALSA device list:408server # [ 0.594876] No soundcards found.409server # [ 0.595399] uart-pl011 9000000.pl011: no DMA platform data410server # [ 0.600091] Freeing unused kernel memory: 12032K411server # [ 0.624662] 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.727252] 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.808558] 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.965672] EXT4-fs (vda): mounted filesystem with ordered data mode. Quota mode: none.431server # [ 0.971680] EXT4-fs (vda): re-mounted. Quota mode: none.432server # [ 0.976876] 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 # [ 1.041865] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0436server # mounting shared on /tmp/shared...437server # mounting xchg on /tmp/xchg...438server # mounting overlay filesystem on /nix/store...439server # [ 1.172606] usb 1-2: new high-speed USB device number 3 using ehci-pci440server # [ 1.334392] 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 # [ 1.341506] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0442server # 443server # <<< NixOS Stage 2 >>>444server # 445server # [ 1.379558] EXT4-fs (vda): re-mounted. Quota mode: none.446server # [ 1.383423] booting system configuration /nix/store/41f4b25x4sb0mhs0xcphpqx53lfx6l8f-nixos-system-server-23.11pre-git447server # running activation script...448server # setting up /etc...449server # starting systemd...450server # [ 3.178739] 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.190484] systemd[1]: Detected virtualization qemu.452server # [ 3.192664] systemd[1]: Detected architecture arm64.453server # [ 3.198679] systemd[1]: Initializing machine ID from random generator.454server # [ 3.314634] systemd[1]: bpf-lsm: Failed to link program; assuming BPF LSM is not available455server # [ 3.860625] systemd[1]: Queued start job for default target Multi-User System.456server # [ 3.878458] systemd[1]: Created slice Slice /system/getty.457server # [ 3.879594] systemd[1]: Created slice Slice /system/modprobe.458server # [ 3.880695] systemd[1]: Created slice User and Session Slice.459server # [ 3.881487] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.460server # [ 3.882475] systemd[1]: Started Forward Password Requests to Wall Directory Watch.461server # [ 3.883597] systemd[1]: Reached target Local Encrypted Volumes.462server # [ 3.884343] systemd[1]: Reached target Containers.463server # [ 3.885093] systemd[1]: Reached target Path Units.464server # [ 3.885689] systemd[1]: Reached target Remote File Systems.465server # [ 3.886368] systemd[1]: Reached target Slice Units.466server # [ 3.886975] systemd[1]: Reached target Swaps.467server # [ 3.890884] systemd[1]: Listening on Process Core Dump Socket.468server # [ 3.892032] systemd[1]: Listening on Journal Socket (/dev/log).469server # [ 3.892943] systemd[1]: Listening on Journal Socket.470server # [ 3.894028] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.471server # [ 3.896088] systemd[1]: Listening on udev Control Socket.472server # [ 3.897145] systemd[1]: Listening on udev Kernel Socket.473server # [ 3.899198] systemd[1]: Mounting Huge Pages File System...474server # [ 3.901193] systemd[1]: Mounting POSIX Message Queue File System...475server # [ 3.903765] systemd[1]: Mounting Kernel Debug File System...476server # [ 3.907126] systemd[1]: Starting Create List of Static Device Nodes...477server # [ 3.909569] systemd[1]: Starting Load Kernel Module configfs...478server # [ 3.912068] systemd[1]: Starting Load Kernel Module drm...479server # [ 3.914569] systemd[1]: Starting Load Kernel Module efi_pstore...480server # [ 3.917377] systemd[1]: Starting Load Kernel Module fuse...481server # [ 3.920079] systemd[1]: Starting mount-pstore.service...482server # [ 3.921943] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).483server # [ 3.927347] systemd[1]: Starting Journal Service...484server # [ 3.930309] systemd[1]: Starting Load Kernel Modules...485server # [ 3.933181] systemd[1]: Starting Remount Root and Kernel File Systems...486server # [ 3.935805] systemd[1]: Starting Coldplug All udev Devices...487server # [ 3.939140] systemd[1]: Mounted Huge Pages File System.488server # [ 3.940360] systemd[1]: Mounted POSIX Message Queue File System.489server # [ 3.941689] systemd[1]: Mounted Kernel Debug File System.490server # [ 3.943104] systemd[1]: Finished Create List of Static Device Nodes.491server # [ 3.943384] fuse: init (API version 7.37)492server # [ 3.944983] systemd[1]: modprobe@configfs.service: Deactivated successfully.493server # [ 3.946688] systemd[1]: Finished Load Kernel Module configfs.494server # [ 3.948389] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.495server # [ 3.950512] systemd[1]: Finished Load Kernel Module efi_pstore.496server # [ 3.952242] systemd[1]: modprobe@fuse.service: Deactivated successfully.497server # [ 3.953943] systemd[1]: Finished Load Kernel Module fuse.498server # [ 3.957048] systemd[1]: Mounting FUSE Control File System...499server # [ 3.959607] systemd[1]: Mounting Kernel Configuration File System...500server # [ 3.963081] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...501server # [ 3.968662] systemd[1]: Mounted FUSE Control File System.502server # [ 3.969502] EXT4-fs (vda): re-mounted. Quota mode: none.503server # [ 3.975872] systemd[1]: Mounted Kernel Configuration File System.504server # [ 3.981313] systemd[1]: Finished Remount Root and Kernel File Systems.505server # [ 3.983988] systemd[1]: Starting Load/Save OS Random Seed...506server # [ 3.992551] systemd-journald[538]: Collecting audit messages is disabled.507server # [ 3.995321] systemd[1]: modprobe@drm.service: Deactivated successfully.508server # [ 3.995370] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.509server # [ 3.996446] systemd[1]: Finished Load Kernel Module drm.510server # [ 4.009346] systemd[1]: Finished Load Kernel Modules.511server # [ 4.011715] systemd[1]: Starting Firewall...512server # [ 3.995962] systemd-modules-load[539]: Inserted module 'bridge'513server # [ 3.997143] systemd-modules-load[539]: Inserted module 'macvlan'[ 4.014233] systemd[1]: Starting Apply Kernel Variables...514server # 515server # [ 3.998890] systemd-modules-load[539]: Inserted module 'tap'516server # [ 3.999727] systemd-modules-load[539]: Module 'tun' is built in[ 4.017018] systemd[1]: Started Journal Service.517server # 518server # [ 4.002662] systemd-modules-load[539]: Module 'loop' is built in519server # [ 4.003897] systemd-modules-load[539]: Module 'atkbd' is built in520server # [ 4.009560] systemd[1]: Finished Load/Save OS Random Seed.521server # [ 4.017708] systemd[1]: Starting Flush Journal to Persistent Storage...522server # [ 4.030739] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.523server # [ 4.034793] systemd[1]: Starting Create Static Device Nodes in /dev...524server # [ 4.054277] systemd-journald[538]: Received client request to flush runtime journal.525server # [ 4.050467] systemd[1]: Finished Flush Journal to Persistent Storage.526server # [ 4.057341] systemd[1]: Finished Apply Kernel Variables.527server # [ 4.072743] systemd[1]: Finished Create Static Device Nodes in /dev.528server # [ 4.074589] systemd[1]: Reached target Preparation for Local File Systems.529server # [ 4.076349] systemd[1]: Reached target Local File Systems.530server # [ 4.101436] systemd[1]: Starting Create Volatile Files and Directories...531server # [ 4.105390] systemd[1]: Starting Rule-based Manager for Device Events and Files...532server # [ 4.106569] systemd[1]: Finished Coldplug All udev Devices.533server # [ 4.149651] systemd-udevd[590]: Using default interface naming scheme 'v253'.534server # [ 4.153370] systemd[1]: Finished Create Volatile Files and Directories.535server # [ 4.168437] systemd[1]: Starting Rebuild Journal Catalog...536server # [ 4.170706] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...537server # [ 4.172672] systemd[1]: Starting Record System Boot/Shutdown in UTMP...538server # [ 4.196919] systemd[1]: Finished Record System Boot/Shutdown in UTMP.539server # [ 4.214936] systemd-oomd[604]: No swap; memory pressure usage will be degraded540server # [ 4.216509] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.541server # [ 4.224104] systemd[1]: Finished Rebuild Journal Catalog.542server # [ 4.248581] systemd[1]: Starting Update is Completed...543server # [ 4.250757] systemd[1]: Started Rule-based Manager for Device Events and Files.544server # [ 4.271251] systemd[1]: Finished Update is Completed.545server # [ 4.283193] systemd[1]: Found device /dev/ttyAMA0.546server # [ 4.286083] systemd[1]: Found device /dev/hvc0.547server # [ 4.310770] (udev-worker)[623]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.548server # [ 4.314580] (udev-worker)[623]: Network interface NamePolicy= disabled on kernel command line.549server # [ 4.317776] (udev-worker)[627]: Network interface NamePolicy= disabled on kernel command line.550server # [ 4.347363] mousedev: PS/2 mouse device common for all mice551server # [ 4.351279] systemd[1]: Found device Virtio network device.552server # [ 4.411928] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3553server # [ 4.434331] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0554server # [ 4.435439] [drm] features: -virgl +edid -resource_blob -host_visible555server # [ 4.435441] [drm] features: -context_init556server # [ 4.438499] [drm] number of scanouts: 1557server # [ 4.439564] [drm] number of cap sets: 0558server # [ 4.440818] [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:09.0 on minor 0559server # [ 4.450878] fbcon: Deferring console take-over560server # [ 4.452745] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device561server # [ 4.488840] systemd[1]: Starting Virtual Console Setup...562server # [ 4.802004] systemd[1]: Finished Firewall.563server # [ 4.804984] systemd[1]: Finished Virtual Console Setup.564server # [ 5.702347] w8yz32d54jbh5dlnl3mr061y6flfa0wm-mount-pstore.sh[542]: Persistent Storage backend was not registered in time.565server # [ 5.707638] systemd[1]: Finished mount-pstore.service.566server # [ 5.710448] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).567server # [ 5.715824] systemd[1]: Reached target System Initialization.568server # [ 5.718280] systemd[1]: Started logrotate.timer.569server # [ 5.720241] systemd[1]: Started Daily Cleanup of Temporary Directories.570server # [ 5.722832] systemd[1]: Reached target Timer Units.571server # [ 5.724917] systemd[1]: Listening on D-Bus System Message Bus Socket.572server # [ 5.727443] systemd[1]: Listening on Nix Daemon Socket.573server # [ 5.729645] systemd[1]: Starting systemd-vaultd socket...574server # [ 5.753619] systemd[1]: Listening on systemd-vaultd socket.575server # [ 5.756159] systemd[1]: Reached target Socket Units.576server # [ 5.758217] systemd[1]: Reached target Basic System.577server # [ 5.760299] systemd[1]: Starting Kernel Auditing...578server # [ 5.763922] systemd[1]: Started backdoor.service.579server # [ 5.767419] systemd[1]: Starting D-Bus System Message Bus...580server # [ 5.772073] systemd[1]: Starting DHCP Client...581server # [ 5.775525] systemd[1]: Starting Logrotate configuration check...582server # [ 5.781031] systemd[1]: Starting Name Service Cache Daemon (nsncd)...583server # [ 5.785387] systemd[1]: Started Reset console on configuration changes.584server # [ 5.791983] systemd[1]: Starting resolvconf update...585server # [ 5.796892] systemd[1]: Starting service1-envfile.service...586server # [ 5.803280] systemd[1]: Starting service2.service...587server # [ 5.809677] logrotate[771]: warning: logrotate in debug mode does nothing except printing debug messages! Consider using verbose mode (-v) instead if this is not what you want.588server # [ 5.818704] dhcpcd[770]: dhcpcd-9.4.1 starting589server # [ 5.821444] systemd[1]: Starting setup-vault-agent-approle.service...590server # connecting to host...591server # [ 5.826773] rrll6i65cnvqwxannbj95lzl20a8y5dv-audit-disable[774]: No rules592server # [ 5.833497] logrotate[771]: reading config file /nix/store/qb85sn99mapdxc37vcclnv3fx3dlqv0q-logrotate.conf593server # [ 5.841545] logrotate[771]: note: 'monthly' overrides previously specified 'weekly'594server # [ 5.848257] logrotate[771]: Reading state from file: /var/lib/logrotate.status595server # [ 5.855044] logrotate[771]: state file /var/lib/logrotate.status does not exist596server: Guest shell says: b'Spawning backdoor root shell...\n'597server: connected to guest root shell598server: (connecting took 6.05 seconds)599(finished: waiting for the VM to finish booting, in 6.05 seconds)600server # [ 5.862861] logrotate[771]: Allocating hash table for state file, size 64 entries601server # [ 5.869284] logrotate[771]: Handling 2 logs602server # [ 5.872736] logrotate[771]: rotating pattern: "/var/log/btmp" monthly (1 rotations)603server # [ 5.877169] logrotate[771]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed604server # [ 5.885472] logrotate[771]: considering log /var/log/btmp[ 5.902044] 8021q: 802.1Q VLAN Support v1.8605server # 606server # [ 5.892889] logrotate[771]: Creating new state607server # [ 5.896490] logrotate[771]: Now: 2026-07-29 05:20608server # [ 5.900219] logrotate[771]: Last rotated at 2026-07-29 05:00609server # [ 5.904788] logrotate[771]: log does not need rotating (log has already been rotated)610server # [ 5.910244] logrotate[771]: rotating pattern: "/var/log/wtmp" monthly (1 rotations)611server # [ 5.915641] logrotate[771]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed612server # [ 5.925238] logrotate[771]: considering log /var/log/wtmp613server # [ 5.926173] logrotate[771]: Creating new state614server # [ 5.926924] logrotate[771]: Now: 2026-07-29 05:20615server # [ 5.927957] logrotate[771]: Last rotated at 2026-07-29 05:00616server # [ 5.929259] logrotate[771]: log does not need rotating (log has already been rotated)617server # [ 5.932283] systemd[1]: Started systemd-vaultd daemon.618server # [ 5.933436] dhcpcd[786]: dev: loaded udev619server # [ 5.934349] nsncd[772]: Jul 29 05:20:27.949 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"620server # [ 5.936770] dbus-daemon[769]: dbus[769]: Unknown username "systemd-timesync" in message bus configuration file621server # [ 5.938249] systemd[1]: Finished Logrotate configuration check.622server # [ 5.939334] setup-vault-agent-approle-start[779]: + vault status623server # [ 5.940354] systemd[1]: Finished Kernel Auditing.624server # [ 5.941351] systemd-vaultd[789]: 2026/07/29 05:20:27 Use unix socket received from systemd625server # [ 5.942635] systemd-vaultd[789]: 2026/07/29 05:20:27 Listening on /run/systemd-vaultd/sock626server # [ 5.943868] systemd-vaultd[789]: 2026/07/29 05:20:27 Systemd requested secret for service2.service/secret627server # [ 5.945403] systemd-vaultd[789]: 2026/07/29 05:20:27 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service628server # [ 5.947316] systemd-vaultd[789]: 2026/07/29 05:20:27 Block start until service1.service.EnvironmentFile appears629server # [ 5.948747] systemd-vaultd[789]: 2026/07/29 05:20:27 Block start until service2.service.json appears630server # [ 5.950034] systemd[1]: Started Name Service Cache Daemon (nsncd).631server # [ 5.951035] systemd[1]: Reached target Host and Network Name Lookups.632server # [ 5.952156] systemd[1]: Reached target User and Group Name Lookups.633server # [ 5.953145] systemd[1]: Starting User Login Management...634server # [ 5.953945] systemd[1]: Started D-Bus System Message Bus.635server # [ 5.954762] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.636server # [ 5.975445] systemd[1]: Stopped target Host and Network Name Lookups.637server # [ 5.976768] systemd[1]: Stopping Host and Network Name Lookups...638server # [ 5.977701] systemd[1]: Stopped target User and Group Name Lookups.639server # [ 5.978646] systemd[1]: Stopping User and Group Name Lookups...640server # [ 5.979636] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...641server # [ 5.982338] systemd-logind[817]: New seat seat0.642server # [ 5.983109] systemd-logind[817]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)643server # [ 5.985002] systemd-logind[817]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)644server # [ 5.986196] systemd-logind[817]: Watching system buttons on /dev/input/event0 (gpio-keys)645server # [ 5.987358] systemd[1]: Started User Login Management.646server # [ 5.988097] systemd[1]: nscd.service: Deactivated successfully.647server # [ 5.989005] systemd[1]: Stopped Name Service Cache Daemon (nsncd).648server # [ 6.008567] cfg80211: Loading compiled-in X.509 certificates for regulatory database649server # [ 6.013090] systemd[1]: Starting Name Service Cache Daemon (nsncd)...650server # [ 6.027390] nsncd[852]: Jul 29 05:20:28.115 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"651server # [ 6.047340] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'652server # [ 6.050271] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2653server # [ 6.051396] cfg80211: failed to load regulatory.db654server # [ 6.051579] systemd[1]: Started Name Service Cache Daemon (nsncd).655server # [ 6.054260] systemd[1]: Finished resolvconf update.656server # [ 6.056490] systemd[1]: Reached target Preparation for Network.657server # [ 6.058597] systemd[1]: Reached target All Network Interfaces (deprecated).658server # [ 6.061432] systemd[1]: Reached target Host and Network Name Lookups.659server # [ 6.063225] systemd[1]: Reached target User and Group Name Lookups.660server # [ 6.065692] systemd[1]: Starting Address configuration of eth1...661server # [ 6.110566] 8021q: adding VLAN 0 to HW filter on device eth1662server # [ 6.104642] network-addresses-eth1-start[877]: adding address 192.168.1.1/24... done[ 6.122959] 8021q: adding VLAN 0 to HW filter on device eth0663server # 664server # [ 6.110015] dhcpcd[786]: eth0: waiting for carrier665server # [ 6.111746] dhcpcd[786]: eth0: carrier acquired666server # [ 6.120741] systemd[1]: Finished Address configuration of eth1.667server # [ 6.125132] dhcpcd[786]: DUID 00:01:00:01:31:fc:4a:1c:52:54:00:12:34:56668server # [ 6.127112] dhcpcd[786]: eth0: IAID 00:12:34:56669server # [ 6.128461] dhcpcd[786]: eth0: adding address fe80::5054:ff:fe12:3456670server # [ 6.144865] systemd[1]: Starting Networking Setup...671server # [ 6.251734] systemd[1]: Finished Networking Setup.672server # [ 6.269101] systemd[1]: Starting Extra networking commands....673server # [ 6.277679] systemd[1]: Finished Extra networking commands..674server # [ 6.278685] systemd[1]: Reached target Network.675server # [ 6.281539] systemd[1]: Starting Permit User Sessions...676server # [ 6.284465] systemd[1]: Started vault-agent-default.service.677server # [ 6.288829] systemd[1]: Started Vault server daemon.678server # [ 6.309504] systemd[1]: Finished Permit User Sessions.679server # [ 6.313513] systemd[1]: Started Getty on tty1.680server # [ 6.315763] systemd[1]: Reached target Login Prompts.681server # [ 6.466905] dhcpcd[786]: eth0: soliciting an IPv6 router682server # [ 6.682556] setup-vault-agent-approle-start[795]: 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.690335] setup-vault-agent-approle-start[779]: + sleep 1684server # [ 6.691408] vault[944]: ==> Vault Agent started! Log data will stream in below:685server # [ 6.692687] vault[945]: ==> Vault server configuration:686server # [ 6.693442] vault[945]: Administrative Namespace:687server # [ 6.694114] vault[945]: Api Address: http://127.0.0.1:8200688server # [ 6.695073] vault[945]: Cgo: enabled689server # [ 6.695876] vault[945]: Cluster Address: https://127.0.0.1:8201690server # [ 6.696887] vault[945]: 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, USER691server # [ 6.699622] vault[945]: Go Version: go1.21.3692server # [ 6.700485] vault[945]: 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")693server # [ 6.702688] vault[945]: Log Level:694server # [ 6.703398] vault[945]: Mlock: supported: true, enabled: false695server # [ 6.704482] vault[945]: Recovery Mode: false696server # [ 6.705248] vault[945]: Storage: inmem697server # [ 6.706003] vault[945]: Version: Vault v1.14.4698server # [ 6.706835] vault[944]: ==> Vault Agent configuration:699server # [ 6.707602] vault[944]: Api Address 1: http://bufconn700server # [ 6.708496] vault[944]: Cgo: enabled701server # [ 6.709265] vault[944]: Log Level:702server # [ 6.709998] vault[944]: Version: Vault v1.14.4703server # [ 6.710847] vault[944]: 2026-07-29T05:20:28.788Z [INFO] agent.auth.handler: starting auth handler704server # [ 6.712075] vault[944]: 2026-07-29T05:20:28.789Z [INFO] agent.auth.handler: authenticating705server # [ 6.713225] vault[944]: 2026-07-29T05:20:28.789Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1s706server # [ 6.715023] vault[944]: 2026-07-29T05:20:28.788Z [INFO] agent.exec.server: starting exec server707server # [ 6.716213] vault[944]: 2026-07-29T05:20:28.791Z [INFO] agent.template.server: starting template server708server # [ 6.717464] vault[944]: 2026-07-29T05:20:28.792Z [INFO] agent.sink.server: starting sink server709server # [ 6.718643] vault[944]: 2026-07-29T05:20:28.792Z [INFO] agent.exec.server: no env templates or exec config, exiting710server # [ 6.720038] vault[944]: 2026-07-29T05:20:28.806Z [INFO] (runner) creating new runner (dry: false, once: false)711server # [ 6.725136] vault[944]: 2026-07-29T05:20:28.813Z [INFO] (runner) creating watcher712server # [ 7.041917] dhcpcd[786]: eth0: soliciting a DHCP lease713server # [ 7.057136] dhcpcd[786]: eth0: offered 10.0.2.15 from 10.0.2.2714server # [ 7.060600] dhcpcd[786]: eth0: leased 10.0.2.15 for 86400 seconds715server # [ 7.063372] dhcpcd[786]: eth0: adding route to 10.0.2.0/24716server # [ 7.066113] dhcpcd[786]: eth0: adding default route via 10.0.2.2717server # [ 7.182395] systemd[1]: Stopped target Host and Network Name Lookups.718server # [ 7.183478] systemd[1]: Stopping Host and Network Name Lookups...719server # [ 7.184489] systemd[1]: Stopped target User and Group Name Lookups.720server # [ 7.185436] systemd[1]: Stopping User and Group Name Lookups...721server # [ 7.186277] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...722server # [ 7.190962] systemd[1]: nscd.service: Deactivated successfully.723server # [ 7.191914] systemd[1]: Stopped Name Service Cache Daemon (nsncd).724(finished: waiting for unit vault.service, in 7.38 seconds)725server: waiting for TCP port 8200 on localhost726server # [ 7.213436] systemd[1]: Starting Name Service Cache Daemon (nsncd)...727server # [ 7.230020] nsncd[1014]: Jul 29 05:20:29.317 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"728server # [ 7.233293] systemd[1]: Started Name Service Cache Daemon (nsncd).729server # [ 7.235072] systemd[1]: Reached target Host and Network Name Lookups.730server # [ 7.237209] systemd[1]: Reached target User and Group Name Lookups.731server # [ 7.260257] dhcpcd[770]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.732server # [ 7.262853] dhcpcd[770]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.733server # Connection to localhost (127.0.0.1) 8200 port [tcp/trivnet1] succeeded!734server # [ 7.266611] dhcpcd[770]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.735server # [ 7.273186] systemd[1]: Started DHCP Client.736(finished: waiting for TCP port 8200 on localhost, in 0.07 seconds)737server: waiting for unit setup-vault-agent-approle.service738server # [ 7.274321] systemd[1]: Reached target Network is Online.739server # [ 7.489471] vault[945]: ==> Vault server started! Log data will stream in below:740server # [ 7.493109] vault[945]: 2026-07-29T05:20:28.770Z [INFO] proxy environment: http_proxy="" https_proxy="" no_proxy=""741server # [ 7.497052] vault[945]: 2026-07-29T05:20:28.775Z [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 set742server # [ 7.504002] vault[945]: 2026-07-29T05:20:28.778Z [INFO] core: Initializing version history cache for core743server # [ 7.507678] vault[945]: 2026-07-29T05:20:28.781Z [INFO] core: security barrier not initialized744server # [ 7.511098] vault[945]: 2026-07-29T05:20:28.783Z [INFO] core: security barrier initialized: stored=1 shares=1 threshold=1745server # [ 7.515427] vault[945]: 2026-07-29T05:20:28.812Z [INFO] core: post-unseal setup starting746server # [ 7.518635] vault[945]: 2026-07-29T05:20:28.824Z [INFO] core: loaded wrapping token key747server # [ 7.521825] vault[945]: 2026-07-29T05:20:28.824Z [INFO] core: successfully setup plugin catalog: plugin-directory=""748server # [ 7.525912] vault[945]: 2026-07-29T05:20:28.827Z [INFO] core: no mounts; adding default mount table749server # [ 7.529453] vault[945]: 2026-07-29T05:20:28.832Z [INFO] core: successfully mounted: type=cubbyhole version="v1.14.4+builtin.vault" path=cubbyhole/ namespace="ID: root. Path: "750server # [ 7.535130] vault[945]: 2026-07-29T05:20:28.839Z [INFO] core: successfully mounted: type=system version="v1.14.4+builtin.vault" path=sys/ namespace="ID: root. Path: "751server # [ 7.540340] vault[945]: 2026-07-29T05:20:28.842Z [INFO] core: successfully mounted: type=identity version="v1.14.4+builtin.vault" path=identity/ namespace="ID: root. Path: "752server # [ 7.545131] vault[945]: 2026-07-29T05:20:28.850Z [INFO] core: successfully mounted: type=token version="v1.14.4+builtin.vault" path=token/ namespace="ID: root. Path: "753server # [ 7.549436] vault[945]: 2026-07-29T05:20:28.853Z [INFO] core: restoring leases754server # [ 7.551431] vault[945]: 2026-07-29T05:20:28.853Z [INFO] rollback: starting rollback manager755server # [ 7.553726] vault[945]: 2026-07-29T05:20:28.855Z [INFO] expiration: lease restore complete756server # [ 7.555849] vault[945]: 2026-07-29T05:20:28.857Z [INFO] identity: entities restored757server # [ 7.557849] vault[945]: 2026-07-29T05:20:28.857Z [INFO] identity: groups restored758server # [ 7.559694] vault[945]: 2026-07-29T05:20:28.859Z [INFO] core: Recorded vault version: vault version=1.14.4 upgrade time="2026-07-29 05:20:28.85897426 +0000 UTC" build date=""759server # [ 7.563357] vault[945]: 2026-07-29T05:20:29.549Z [INFO] core: post-unseal setup complete760server # [ 7.565240] vault[945]: 2026-07-29T05:20:29.550Z [INFO] core: root token generated761server # [ 7.566846] vault[945]: 2026-07-29T05:20:29.550Z [INFO] core: pre-seal teardown starting762server # [ 7.568593] vault[945]: 2026-07-29T05:20:29.552Z [INFO] rollback: stopping rollback manager763server # [ 7.570262] vault[945]: 2026-07-29T05:20:29.552Z [INFO] core: pre-seal teardown complete764server # [ 7.571885] vault[945]: 2026-07-29T05:20:29.553Z [INFO] core.cluster-listener.tcp: starting listener: listener_address=127.0.0.1:8201765server # [ 7.574461] vault[945]: 2026-07-29T05:20:29.553Z [INFO] core.cluster-listener: serving cluster requests: cluster_listen_address=127.0.0.1:8201766server # [ 7.576996] vault[945]: 2026-07-29T05:20:29.554Z [INFO] core: post-unseal setup starting767server # [ 7.578524] vault[945]: 2026-07-29T05:20:29.554Z [INFO] core: loaded wrapping token key768server # [ 7.580055] vault[945]: 2026-07-29T05:20:29.554Z [INFO] core: successfully setup plugin catalog: plugin-directory=""769server # [ 7.581948] vault[945]: 2026-07-29T05:20:29.555Z [INFO] core: successfully mounted: type=system version="v1.14.4+builtin.vault" path=sys/ namespace="ID: root. Path: "770server # [ 7.584583] vault[945]: 2026-07-29T05:20:29.556Z [INFO] core: successfully mounted: type=identity version="v1.14.4+builtin.vault" path=identity/ namespace="ID: root. Path: "771server # [ 7.587177] vault[945]: 2026-07-29T05:20:29.556Z [INFO] core: successfully mounted: type=cubbyhole version="v1.14.4+builtin.vault" path=cubbyhole/ namespace="ID: root. Path: "772server # [ 7.589918] vault[945]: 2026-07-29T05:20:29.558Z [INFO] core: successfully mounted: type=token version="v1.14.4+builtin.vault" path=token/ namespace="ID: root. Path: "773server # [ 7.592529] vault[945]: 2026-07-29T05:20:29.559Z [INFO] rollback: starting rollback manager774server # [ 7.593918] vault[945]: 2026-07-29T05:20:29.559Z [INFO] core: restoring leases775server # [ 7.595134] vault[945]: 2026-07-29T05:20:29.559Z [INFO] expiration: lease restore complete776server # [ 7.596583] vault[945]: 2026-07-29T05:20:29.560Z [INFO] identity: entities restored777server # [ 7.597857] vault[945]: 2026-07-29T05:20:29.560Z [INFO] identity: groups restored778server # [ 7.599103] vault[945]: 2026-07-29T05:20:29.560Z [INFO] core: post-unseal setup complete779server # [ 7.600488] vault[945]: 2026-07-29T05:20:29.560Z [INFO] core: vault is unsealed780server # [ 7.601707] vault[945]: 2026-07-29T05:20:29.569Z [INFO] expiration: revoked lease: lease_id=auth/token/root/ha99e125c6b0eb3ddd34e9dad5032b83ede8b8270106e6dd18d555f6649b1f4d6781server # [ 7.604231] vault[945]: 2026-07-29T05:20:29.577Z [INFO] core: successful mount: namespace="" path=secret/ type=kv version=""782server # [ 7.606103] vault[945]: WARNING! dev mode is enabled! In this mode, Vault runs entirely in-memory783server # [ 7.607556] vault[945]: and starts unsealed with a single unseal key. The root token is already784server # [ 7.609074] vault[945]: authenticated to the CLI, so you can immediately begin using Vault.785server # [ 7.610454] vault[945]: You may need to set the following environment variables:786server # [ 7.611682] vault[945]: $ export VAULT_ADDR='http://127.0.0.1:8200'787server # [ 7.612909] vault[945]: The unseal key and root token are displayed below in case you want to788server # [ 7.614526] vault[945]: seal/unseal the Vault or re-authenticate.789server # [ 7.615618] vault[945]: Unseal Key: UdotMYUVdMGRzRH0gtP9x0Wg2nwEGK40rnJ8OVMEEts=790server # [ 7.616912] vault[945]: Root Token: phony-secret791server # [ 7.617703] vault[945]: Development mode should NOT be used in production installations!792server # [ 7.619052] dhcpcd[786]: eth0: Router Advertisement from fe80::2793server # [ 7.620102] dhcpcd[786]: eth0: adding address fec0::5054:ff:fe12:3456/64794server # [ 7.621320] dhcpcd[786]: eth0: adding route to fec0::/64795server # [ 7.622292] dhcpcd[786]: eth0: adding default route via fe80::2796server # [ 7.704361] vault[944]: 2026-07-29T05:20:29.792Z [INFO] agent.auth.handler: authenticating797server # [ 7.707583] vault[944]: 2026-07-29T05:20:29.792Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1.92s798server # [ 7.717842] setup-vault-agent-approle-start[779]: + vault status799server # [ 7.858445] setup-vault-agent-approle-start[1043]: Key Value800server # [ 7.861184] setup-vault-agent-approle-start[1043]: --- -----801server # [ 7.863704] setup-vault-agent-approle-start[1043]: Seal Type shamir802server # [ 7.866394] setup-vault-agent-approle-start[1043]: Initialized true803server # [ 7.869962] setup-vault-agent-approle-start[1043]: Sealed false804server # [ 7.873254] setup-vault-agent-approle-start[1043]: Total Shares 1805server # [ 7.876439] setup-vault-agent-approle-start[1043]: Threshold 1806server # [ 7.879331] setup-vault-agent-approle-start[1043]: Version 1.14.4807server # [ 7.882831] setup-vault-agent-approle-start[1043]: Build Date n/a808server # [ 7.886156] setup-vault-agent-approle-start[1043]: Storage Type inmem809server # [ 7.888825] setup-vault-agent-approle-start[1043]: Cluster Name vault-cluster-04fe6094810server # [ 7.892230] setup-vault-agent-approle-start[1043]: Cluster ID d47cde74-e440-e629-c65b-fb507b9f3f92811server # [ 7.896627] setup-vault-agent-approle-start[1043]: HA Enabled false812server # [ 7.899506] setup-vault-agent-approle-start[779]: + cat813server # [ 7.901684] setup-vault-agent-approle-start[779]: + vault policy write demo /tmp/policy-file.hcl814server # [ 8.006646] setup-vault-agent-approle-start[1052]: Success! Uploaded policy: demo815server # [ 8.017352] setup-vault-agent-approle-start[779]: + vault kv put secret/my-secret foo=bar816server # [ 8.127367] setup-vault-agent-approle-start[1059]: ==== Secret Path ====817server # [ 8.130229] setup-vault-agent-approle-start[1059]: secret/data/my-secret818server # [ 8.133076] setup-vault-agent-approle-start[1059]: ======= Metadata =======819server # [ 8.136204] setup-vault-agent-approle-start[1059]: Key Value820server # [ 8.139332] setup-vault-agent-approle-start[1059]: --- -----821server # [ 8.142671] setup-vault-agent-approle-start[1059]: created_time 2026-07-29T05:20:30.21118198Z822server # [ 8.146546] setup-vault-agent-approle-start[1059]: custom_metadata <nil>823server # [ 8.150063] setup-vault-agent-approle-start[1059]: deletion_time n/a824server # [ 8.153169] setup-vault-agent-approle-start[1059]: destroyed false825server # [ 8.155813] setup-vault-agent-approle-start[1059]: version 1826server # [ 8.158545] setup-vault-agent-approle-start[779]: + vault auth enable approle827server # [ 8.282146] vault[945]: 2026-07-29T05:20:30.370Z [INFO] core: enabled credential backend: path=approle/ type=approle version=""828server # [ 8.287138] setup-vault-agent-approle-start[1067]: Success! Enabled approle auth method at: approle/829server # [ 8.296306] setup-vault-agent-approle-start[779]: + vault write auth/approle/role/role1 bind_secret_id=true token_policies=demo830server # [ 8.405778] setup-vault-agent-approle-start[1075]: Success! Data written to: auth/approle/role/role1831server # [ 8.417449] setup-vault-agent-approle-start[1088]: ++ vault read -format json auth/approle/role/role1/role-id832server # [ 8.423761] setup-vault-agent-approle-start[1089]: ++ jq -r .data.role_id833server # [ 8.537562] setup-vault-agent-approle-start[779]: + echo -n 29621f82-c81e-5fe6-c05a-4aa2099c67bf834server # [ 8.542667] setup-vault-agent-approle-start[1098]: ++ vault write -force -format json auth/approle/role/role1/secret-id835server # [ 8.548623] setup-vault-agent-approle-start[1099]: ++ jq -r .data.secret_id836server # [ 8.639140] setup-vault-agent-approle-start[779]: + echo -n a5065243-f252-0bb1-3100-5a64814bd067837server # [ 8.643537] systemd[1]: Finished setup-vault-agent-approle.service.838(finished: waiting for unit setup-vault-agent-approle.service, in 2.25 seconds)839server: waiting for unit vault-agent-default.service840(finished: waiting for unit vault-agent-default.service, in 0.10 seconds)841server: waiting for success: grep -q bar /tmp/service1842server # [ 9.633909] vault[944]: 2026-07-29T05:20:31.721Z [INFO] agent.auth.handler: authenticating843server # [ 9.649500] vault[944]: 2026-07-29T05:20:31.738Z [INFO] agent.auth.handler: authentication successful, sending token to sinks844server # [ 9.655510] vault[944]: 2026-07-29T05:20:31.738Z [INFO] agent.auth.handler: starting renewal process845server # [ 9.660212] vault[944]: 2026-07-29T05:20:31.738Z [INFO] agent.template.server: template server received new token846server # [ 9.665675] vault[944]: 2026-07-29T05:20:31.738Z [INFO] (runner) stopping847server # [ 9.669012] vault[944]: 2026-07-29T05:20:31.738Z [INFO] (runner) creating new runner (dry: false, once: false)848server # [ 9.674830] vault[944]: 2026-07-29T05:20:31.738Z [INFO] (runner) creating watcher849server # [ 9.679216] vault[944]: 2026-07-29T05:20:31.739Z [INFO] (runner) starting850server # grep: [ 9.683088] vault[944]: 2026-07-29T05:20:31.744Z [INFO] agent.auth.handler: renewed auth token851server # [ 9.687014] vault[944]: 2026-07-29T05:20:31.757Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json"852server # /tmp/service1: No such file or directory[ 9.691963] vault[944]: 2026-07-29T05:20:31.758Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.EnvironmentFile"853server # 854server # [ 9.699511] vault[944]: 2026-07-29T05:20:31.758Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service1.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json"855server # [ 9.709927] vault[944]: 2026-07-29T05:20:31.759Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 1 after "250ms")856server # [ 9.717364] vault[944]: 2026-07-29T05:20:31.760Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service1.service'857server # [ 9.722334] systemd[1]: Finished service1-envfile.service.858server # [ 9.724868] systemd-vaultd[789]: 2026/07/29 05:20:31 Detected added file: 24388618859server # [ 9.727584] systemd-vaultd[789]: 2026/07/29 05:20:31 Detected added file: service1.service.json860server # [ 9.730679] systemd-vaultd[789]: 2026/07/29 05:20:31 Ignore unknown file: 24388618861server # [ 9.733283] systemd-vaultd[789]: 2026/07/29 05:20:31 Ignore unknown file: service1.service.json862server # [ 9.736139] systemd-vaultd[789]: 2026/07/29 05:20:31 Detected added file: 1792960456863server # [ 9.738457] systemd-vaultd[789]: 2026/07/29 05:20:31 Detected added file: service1.service.EnvironmentFile864server # [ 9.741343] systemd-vaultd[789]: 2026/07/29 05:20:31 Ignore unknown file: 1792960456865server # [ 9.743619] systemd-vaultd[789]: 2026/07/29 05:20:31 Served service1.service.EnvironmentFile to @6e92879846049e7c/unit/service1-envfile.service/service1.service.EnvironmentFile866server # [ 9.748227] systemd[1]: Started service1.service.867server # [ 9.749658] systemd[1]: Stopping service1.service...868server # [ 9.765890] systemd[1]: service1.service: Deactivated successfully.869server # [ 9.768597] systemd[1]: Stopped service1.service.870server # [ 9.770194] systemd[1]: service1-envfile.service: Deactivated successfully.871server # [ 9.772582] systemd[1]: Stopped service1-envfile.service.872server # [ 9.774389] systemd[1]: Stopping service1-envfile.service...873server # [ 9.778182] systemd[1]: Starting service1-envfile.service...874server # [ 9.780242] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.875server # [ 9.783683] systemd-vaultd[789]: 2026/07/29 05:20:31 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service876server # [ 9.798661] systemd[1]: Finished service1-envfile.service.877server # [ 9.817447] systemd[1]: Started service1.service.878server # [ 9.819833] systemd-vaultd[789]: 2026/07/29 05:20:31 Systemd requested secret for service1.service/foo879server # [ 9.857677] systemd[1]: service1.service: Deactivated successfully.880server # [ 9.862239] systemd[1]: run-credentials-service1.service.mount: Deactivated successfully.881server # [ 9.865558] systemd[1]: service1-envfile.service: Deactivated successfully.882server # [ 9.868309] systemd[1]: Stopped service1-envfile.service.883server # [ 9.926399] vault[944]: 2026-07-29T05:20:32.014Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 2 after "500ms")884server # [ 10.431849] vault[944]: 2026-07-29T05:20:32.520Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 3 after "1s")885server # [ 10.673424] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.886(finished: waiting for success: grep -q bar /tmp/service1, in 1.15 seconds)887server: must succeed: grep -q bar /tmp/service1-env888(finished: must succeed: grep -q bar /tmp/service1-env, in 0.03 seconds)889server: must succeed: systemctl status service2 || :890(finished: must succeed: systemctl status service2 || :, in 0.09 seconds)891server: must succeed: vault kv put secret/blocking-secret foo=bar892(finished: must succeed: vault kv put secret/blocking-secret foo=bar, in 0.25 seconds)893server: waiting for success: grep -q bar /tmp/service2 >&2894server # grep: /tmp/service2: No such file or directory895server # [ 11.442188] systemd-vaultd[789]: 2026/07/29 05:20:33 Detected added file: 3871370344896server # [ 11.445641] systemd-vaultd[789]: 2026/07/29 05:20:33 Detected added file: service2.service.json897server # [ 11.450955] systemd-vaultd[789]: 2026/07/29 05:20:33 Ignore unknown file: 3871370344898server # [ 11.455261] systemd-vaultd[789]: 2026/07/29 05:20:33 Served service2.service.json to @d1ef82ba66cbde54/unit/service2.service/secret899server # [ 11.461655] vault[944]: 2026-07-29T05:20:33.530Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"900server # [ 11.468300] vault[944]: 2026-07-29T05:20:33.533Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service2.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"901server # [ 11.477847] vault[944]: 2026-07-29T05:20:33.535Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'902server # [ 11.501251] systemd[1]: Started service2.service.903server # [ 11.508802] systemd[1]: Reached target Multi-User System.904server # [ 11.512712] systemd[1]: Startup finished in 3.059s (kernel) + 8.449s (userspace) = 11.509s.905server # [ 11.516356] systemd[1]: Reloading service2.service...906server # [ 11.522476] systemd-vaultd[789]: 2026/07/29 05:20:33 Systemd requested secret for service2.service/secret907server # [ 11.559456] fbcon: Taking over console908server # [ 11.561955] virtio-pci 0000:00:09.0: [drm] drm_plane_enable_fb_damage_clips() not called909server # [ 11.562099] Console: switching to colour frame buffer device 160x50910server # [ 11.543195] service2-start[1174]: + true911server # [ 11.575692] service2-start[1174]: + cat /run/service2/secrets/secret912server # [ 11.580949] systemd-vaultd-update-secrets[1175]: 2026/07/29 05:20:33 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated913server # [ 11.586441] service2-start[1182]: bar914server # [ 11.587977] service2-start[1174]: + cat /run/service2/secrets/secret915server # [ 11.590645] service2-start[1174]: + sleep 0.1916server # [ 11.592534] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.917server # [ 11.595768] systemd[1]: Stopped Virtual Console Setup.918server # [ 11.597996] systemd[1]: Stopping Virtual Console Setup...919server # [ 11.621450] systemd[1]: Starting Virtual Console Setup...920server # [ 11.684509] service2-start[1174]: + true921server # [ 11.687189] service2-start[1174]: + cat /run/service2/secrets/secret922server # [ 11.693070] service2-start[1189]: bar923server # [ 11.695288] service2-start[1174]: + cat /run/service2/secrets/secret924server # [ 11.702263] service2-start[1174]: + sleep 0.1925server # [ 11.810851] service2-start[1174]: + true926server # [ 11.813359] service2-start[1174]: + cat /run/service2/secrets/secret927server # [ 11.818106] service2-start[1193]: bar928server # [ 11.820391] service2-start[1174]: + cat /run/service2/secrets/secret929server # [ 11.825336] service2-start[1174]: + sleep 0.1930server # [ 11.931575] service2-start[1174]: + true931server # [ 11.934017] service2-start[1174]: + cat /run/service2/secrets/secret932server # [ 11.937523] service2-start[1197]: bar933server # [ 11.939236] service2-start[1174]: + cat /run/service2/secrets/secret934server # [ 11.943471] systemd[1]: Finished Virtual Console Setup.935server # [ 11.946737] service2-start[1174]: + sleep 0.1936server # [ 12.053067] service2-start[1174]: + true937server # [ 12.055078] service2-start[1174]: + cat /run/service2/secrets/secret938server # [ 12.063181] service2-start[1201]: bar939server # [ 12.065144] service2-start[1174]: + cat /run/service2/secrets/secret940server # [ 12.071513] service2-start[1174]: + sleep 0.1941server # [ 12.180873] service2-start[1174]: + true942server # [ 12.182956] service2-start[1174]: + cat /run/service2/secrets/secret943server # [ 12.189541] service2-start[1204]: bar944server # [ 12.191366] service2-start[1174]: + cat /run/service2/secrets/secret945server # [ 12.202061] service2-start[1174]: + sleep 0.1946(finished: waiting for success: grep -q bar /tmp/service2 >&2, in 1.11 seconds)947server: must succeed: umount /run/credentials/service2.service948server # [ 12.288200] systemd[1]: run-credentials-service2.service.mount: Deactivated successfully.949(finished: must succeed: umount /run/credentials/service2.service, in 0.04 seconds)950server: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json951server # [ 12.312275] service2-start[1174]: + true952server # [ 12.314549] service2-start[1174]: + cat /run/service2/secrets/secret953server # [ 12.321029] service2-start[1218]: bar954server # [ 12.323193] service2-start[1174]: + cat /run/service2/secrets/secret955server # [ 12.328826] service2-start[1174]: + sleep 0.1956(finished: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json, in 0.04 seconds)957server: must succeed: vault kv put secret/blocking-secret foo=reload958server # [ 12.438740] service2-start[1174]: + true959server # [ 12.439965] service2-start[1174]: + cat /run/service2/secrets/secret960server # [ 12.447910] service2-start[1229]: bar961server # [ 12.449086] service2-start[1174]: + cat /run/service2/secrets/secret962server # [ 12.457045] service2-start[1174]: + sleep 0.1963server # [ 12.557584] systemd-vaultd-update-secrets[1175]: 2026/07/29 05:20:34 waiting for /run/systemd-vaultd/secrets/service2.service.json to be created964server # [ 12.565689] service2-start[1174]: + true965server # [ 12.566943] service2-start[1174]: + cat /run/service2/secrets/secret966server # [ 12.576356] service2-start[1239]: bar967server # [ 12.577819] service2-start[1174]: + cat /run/service2/secrets/secret968server # [ 12.586079] service2-start[1174]: + sleep 0.1969(finished: must succeed: vault kv put secret/blocking-secret foo=reload, in 0.25 seconds)970server: must succeed: systemctl restart vault-agent-default971server # [ 12.638648] vault[944]: ==> Vault Agent shutdown triggered972server # [ 12.639947] vault[944]: 2026-07-29T05:20:34.727Z [INFO] agent.sink.server: sink server stopped973server # [ 12.641714] vault[944]: 2026-07-29T05:20:34.727Z [INFO] agent: sinks finished, exiting974server # [ 12.643240] vault[944]: 2026-07-29T05:20:34.727Z [INFO] agent.auth.handler: shutdown triggered, stopping lifetime watcher975server # [ 12.645469] vault[944]: 2026-07-29T05:20:34.727Z [INFO] (runner) stopping976server # [ 12.646768] vault[944]: 2026-07-29T05:20:34.727Z [INFO] agent.auth.handler: auth handler stopped977server # [ 12.648476] vault[944]: 2026-07-29T05:20:34.727Z [INFO] agent.exec.server: exec server stopped978server # [ 12.650078] vault[944]: 2026-07-29T05:20:34.727Z [INFO] agent.template.server: template server stopped979server # [ 12.651977] systemd[1]: Stopping vault-agent-default.service...980server # [ 12.656384] systemd[1]: vault-agent-default.service: Deactivated successfully.981server # [ 12.659435] systemd[1]: Stopped vault-agent-default.service.982server # [ 12.662601] systemd[1]: vault-agent-default.service: Consumed 219ms CPU time, no IO, received 5.8K IP traffic, sent 4.7K IP traffic.983server # [ 12.673329] systemd[1]: Started vault-agent-default.service.984(finished: must succeed: systemctl restart vault-agent-default, in 0.10 seconds)985server: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2986server # [ 12.695436] service2-start[1174]: + true987server # [ 12.698602] service2-start[1174]: + cat /run/service2/secrets/secret988server # [ 12.702917] service2-start[1253]: bar989server # [ 12.705655] service2-start[1174]: + cat /run/service2/secrets/secret990server # [ 12.713160] service2-start[1174]: + sleep 0.1991server # cat: /run/systemd-vaultd/secrets/service2.service.json: No such file or directory992server # [ 12.784801] vault[1249]: ==> Vault Agent started! Log data will stream in below:993server # [ 12.786360] vault[1249]: ==> Vault Agent configuration:994server # [ 12.787379] vault[1249]: Api Address 1: http://bufconn995server # [ 12.788498] vault[1249]: Cgo: enabled996server # [ 12.789451] vault[1249]: Log Level:997server # [ 12.790272] vault[1249]: Version: Vault v1.14.4998server # [ 12.791342] vault[1249]: 2026-07-29T05:20:34.877Z [INFO] agent.exec.server: starting exec server999server # [ 12.793242] vault[1249]: 2026-07-29T05:20:34.877Z [INFO] agent.exec.server: no env templates or exec config, exiting1000server # [ 12.795097] vault[1249]: 2026-07-29T05:20:34.877Z [INFO] agent.auth.handler: starting auth handler1001server # [ 12.796865] vault[1249]: 2026-07-29T05:20:34.877Z [INFO] agent.auth.handler: authenticating1002server # [ 12.798302] vault[1249]: 2026-07-29T05:20:34.877Z [INFO] agent.sink.server: starting sink server1003server # [ 12.800051] vault[1249]: 2026-07-29T05:20:34.877Z [INFO] agent.template.server: starting template server1004server # [ 12.801817] vault[1249]: 2026-07-29T05:20:34.877Z [INFO] (runner) creating new runner (dry: false, once: false)1005server # [ 12.803742] vault[1249]: 2026-07-29T05:20:34.878Z [INFO] (runner) creating watcher1006server # [ 12.806141] vault[1249]: 2026-07-29T05:20:34.883Z [INFO] agent.auth.handler: authentication successful, sending token to sinks1007server # [ 12.810735] vault[1249]: 2026-07-29T05:20:34.883Z [INFO] agent.auth.handler: starting renewal process1008server # [ 12.814332] vault[1249]: 2026-07-29T05:20:34.883Z [INFO] agent.template.server: template server received new token1009server # [ 12.818883] vault[1249]: 2026-07-29T05:20:34.883Z [INFO] (runner) stopping1010server # [ 12.822970] vault[1249]: 2026-07-29T05:20:34.883Z [INFO] (runner) creating new runner (dry: false, once: false)1011server # [ 12.827878] vault[1249]: 2026-07-29T05:20:34.883Z [INFO] (runner) creating watcher1012server # [ 12.831534] vault[1249]: 2026-07-29T05:20:34.884Z [INFO] (runner) starting1013server # [ 12.836223] systemd-vaultd[789]: 2026/07/29 05:20:34 Detected added file: 26153009301014server # [ 12.840824] systemd-vaultd[789]: 2026/07/29 05:20:34 Detected added file: service2.service.json1015server # [ 12.846106] systemd-vaultd[789]: 2026/07/29 05:20:34 Ignore unknown file: 26153009301016server # [ 12.850796] systemd-vaultd[789]: 2026/07/29 05:20:34 Ignore unknown file: service2.service.json1017server # [ 12.854574] service2-start[1174]: + true1018server # [ 12.856351] service2-start[1174]: + cat /run/service2/secrets/secret1019server # [ 12.858823] vault[1249]: 2026-07-29T05:20:34.888Z [INFO] agent.auth.handler: renewed auth token1020server # [ 12.862316] vault[1249]: 2026-07-29T05:20:34.903Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"1021server # [ 12.867105] vault[1249]: 2026-07-29T05:20:34.904Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service2.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"1022server # [ 12.873275] vault[1249]: 2026-07-29T05:20:34.905Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'1023server # [ 12.877002] service2-start[1268]: bar1024server # [ 12.878098] service2-start[1174]: + cat /run/service2/secrets/secret1025server # [ 12.879849] service2-start[1174]: + sleep 0.11026server # [ 12.951889] service2-start[1174]: + true1027server # [ 12.953976] service2-start[1174]: + cat /run/service2/secrets/secret1028server # [ 12.960353] service2-start[1271]: bar1029server # [ 12.962218] service2-start[1174]: + cat /run/service2/secrets/secret1030server # [ 12.968403] service2-start[1174]: + sleep 0.11031server # [ 13.078033] service2-start[1174]: + true1032server # [ 13.080072] service2-start[1174]: + cat /run/service2/secrets/secret1033server # [ 13.086633] service2-start[1274]: bar1034server # [ 13.088475] service2-start[1174]: + cat /run/service2/secrets/secret1035server # [ 13.095673] service2-start[1174]: + sleep 0.11036server # [ 13.204684] service2-start[1174]: + true1037server # [ 13.206676] service2-start[1174]: + cat /run/service2/secrets/secret1038server # [ 13.214155] service2-start[1277]: bar1039server # [ 13.215980] service2-start[1174]: + cat /run/service2/secrets/secret1040server # [ 13.223012] service2-start[1174]: + sleep 0.11041server # [ 13.332704] service2-start[1174]: + true1042server # [ 13.334817] service2-start[1174]: + cat /run/service2/secrets/secret1043server # [ 13.341462] service2-start[1280]: bar1044server # [ 13.343176] service2-start[1174]: + cat /run/service2/secrets/secret1045server # [ 13.350896] service2-start[1174]: + sleep 0.11046server # [ 13.459530] service2-start[1174]: + true1047server # [ 13.461915] service2-start[1174]: + cat /run/service2/secrets/secret1048server # [ 13.468684] service2-start[1283]: bar1049server # [ 13.470533] service2-start[1174]: + cat /run/service2/secrets/secret1050server # [ 13.477830] service2-start[1174]: + sleep 0.11051server # [ 13.585226] systemd[1]: Reloading service2.service...1052server # [ 13.588928] service2-start[1174]: + true1053server # [ 13.591052] service2-start[1174]: + cat /run/service2/secrets/secret1054server # [ 13.595128] systemd-vaultd-update-secrets[1287]: 2026/07/29 05:20:35 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated1055server # [ 13.601638] service2-start[1288]: reload1056server # [ 13.603734] service2-start[1174]: + cat /run/service2/secrets/secret1057server # [ 13.607360] service2-start[1174]: + sleep 0.11058server # [ 13.716515] service2-start[1174]: + true1059server # [ 13.718724] service2-start[1174]: + cat /run/service2/secrets/secret1060server # [ 13.725253] service2-start[1295]: reload1061server # [ 13.727021] service2-start[1174]: + cat /run/service2/secrets/secret1062server # [ 13.734772] service2-start[1174]: + sleep 0.11063server # 1064server # {"secret":"reload"}1065(finished: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2, in 1.12 seconds)1066server: must succeed: systemctl restart service21067server # [ 13.842950] service2-start[1174]: + true1068server # [ 13.845480] service2-start[1174]: + cat /run/service2/secrets/secret1069server # [ 13.851878] service2-start[1306]: reload1070server # [ 13.854131] service2-start[1174]: + cat /run/service2/secrets/secret1071server # [ 13.860728] service2-start[1174]: + sleep 0.11072server # [ 13.864291] systemd[1]: service2.service: Control process exited, code=killed, status=15/TERM1073server # [ 13.869143] systemd[1]: service2.service: Failed with result 'signal'.1074server # [ 13.873257] systemd[1]: Stopped service2.service.1075server # [ 13.889057] systemd[1]: Starting service2.service...1076server # [ 13.894844] systemd-vaultd[789]: 2026/07/29 05:20:35 Systemd requested secret for service2.service/secret1077server # [ 13.920197] systemd[1]: Started service2.service.1078server # [ 13.930430] systemd-vaultd[789]: 2026/07/29 05:20:36 Systemd requested secret for service2.service/secret1079(finished: must succeed: systemctl restart service2, in 0.13 seconds)1080server: must succeed: rm /tmp/service21081server # [ 13.948542] service2-start[1316]: + true1082server # [ 13.950793] service2-start[1316]: + cat /run/service2/secrets/secret1083server # [ 13.957970] service2-start[1321]: reload1084server # [ 13.960337] service2-start[1316]: + cat /run/service2/secrets/secret1085(finished: must succeed: rm /tmp/service2, in 0.03 seconds)1086server: waiting for success: grep -q reload /tmp/service2 >&21087server # [ 13.967742] service2-start[1316]: + sleep 0.11088server # grep: /tmp/service2: No such file or directory1089server # [ 14.078518] service2-start[1316]: + true1090server # [ 14.080784] service2-start[1316]: + cat /run/service2/secrets/secret1091server # [ 14.087395] service2-start[1329]: reload1092server # [ 14.089455] service2-start[1316]: + cat /run/service2/secrets/secret1093server # [ 14.096523] service2-start[1316]: + sleep 0.11094server # [ 14.205217] service2-start[1316]: + true1095server # [ 14.207170] service2-start[1316]: + cat /run/service2/secrets/secret1096server # [ 14.214827] service2-start[1332]: reload1097server # [ 14.216755] service2-start[1316]: + cat /run/service2/secrets/secret1098server # [ 14.223411] service2-start[1316]: + sleep 0.11099server # [ 14.332460] service2-start[1316]: + true1100server # [ 14.334496] service2-start[1316]: + cat /run/service2/secrets/secret1101server # [ 14.341406] service2-start[1335]: reload1102server # [ 14.343308] service2-start[1316]: + cat /run/service2/secrets/secret1103server # [ 14.350497] service2-start[1316]: + sleep 0.11104server # [ 14.459057] service2-start[1316]: + true1105server # [ 14.461126] service2-start[1316]: + cat /run/service2/secrets/secret1106server # [ 14.468493] service2-start[1338]: reload1107server # [ 14.470541] service2-start[1316]: + cat /run/service2/secrets/secret1108server # [ 14.477494] service2-start[1316]: + sleep 0.11109server # [ 14.586754] service2-start[1316]: + true1110server # [ 14.588873] service2-start[1316]: + cat /run/service2/secrets/secret1111server # [ 14.595548] service2-start[1341]: reload1112server # [ 14.597550] service2-start[1316]: + cat /run/service2/secrets/secret1113server # [ 14.604470] service2-start[1316]: + sleep 0.11114server # [ 14.712796] service2-start[1316]: + true1115server # [ 14.714723] service2-start[1316]: + cat /run/service2/secrets/secret1116server # [ 14.722571] service2-start[1344]: reload1117server # [ 14.724580] service2-start[1316]: + cat /run/service2/secrets/secret1118server # [ 14.729972] service2-start[1316]: + sleep 0.11119server # [ 14.838893] service2-start[1316]: + true1120server # [ 14.841376] service2-start[1316]: + cat /run/service2/secrets/secret1121server # [ 14.847881] service2-start[1347]: reload1122server # [ 14.849863] service2-start[1316]: + cat /run/service2/secrets/secret1123server # [ 14.857460] service2-start[1316]: + sleep 0.11124server # [ 14.966384] service2-start[1316]: + true1125server # [ 14.968395] service2-start[1316]: + cat /run/service2/secrets/secret1126server # [ 14.975357] service2-start[1350]: reload1127server # [ 14.977344] service2-start[1316]: + cat /run/service2/secrets/secret1128server # [ 14.984269] service2-start[1316]: + sleep 0.11129(finished: waiting for success: grep -q reload /tmp/service2 >&2, in 1.10 seconds)1130server: must succeed: stat -c %u /run/service2/secrets/secret1131server # [ 15.093244] service2-start[1316]: + true1132server # [ 15.095936] service2-start[1316]: + cat /run/service2/secrets/secret1133server # [ 15.101621] service2-start[1361]: reload1134server # [ 15.103545] service2-start[1316]: + cat /run/service2/secrets/secret1135(finished: must succeed: stat -c %u /run/service2/secrets/secret, in 0.04 seconds)1136server: must succeed: stat -c %g /run/service2/secrets/secret1137server # [ 15.111016] service2-start[1316]: + sleep 0.11138(finished: must succeed: stat -c %g /run/service2/secrets/secret, in 0.04 seconds)1139server: must succeed: stat -c %a /run/service2/secrets/secret1140(finished: must succeed: stat -c %a /run/service2/secrets/secret, in 0.03 seconds)1141(finished: run the VM test script, in 15.75 seconds)1142server # [ 15.219975] service2-start[1316]: + true1143server # [ 15.221324] service2-start[1316]: + cat /run/service2/secrets/secret1144server # [ 15.230440] service2-start[1374]: reload1145test script finished in 15.80s1146cleanup1147kill machine (pid 6)1148server # [ 15.231445] service2-start[1316]: + qemu-system-aarch64: terminating on signal 15 from pid 4 (/nix/store/nxxghhhia9x1skpqfak4z407yy0mbnnd-python3-3.11.5/bin/python3.11)1149(finished: cleanup, in 0.01 seconds)1150additionally exposed symbols:1151 server,1152 vlan1,1153 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, Machine1154● service2.service1155 Loaded: loaded (/etc/systemd/system/service2.service; enabled; preset: enabled)1156 Active: activating (start-pre) since Wed 2026-07-29 05:20:27 UTC; 5s ago1157Cntrl PID: 778 ((re-start))1158 IP: 0B in, 0B out1159 IO: 0B read, 0B written1160 Tasks: 2 (limit: 1086)1161 Memory: 380.0K1162 CPU: 5ms1163 CGroup: /system.slice/service2.service1164 ├─778 "(re-start)"1165 └─780 "(sd-mkdcreds)"11661167Jul 29 05:20:27 server systemd[1]: Starting service2.service...11681169kill vlan (pid 5)