vm-test-run-systemd-vaultd
default.checks.aarch64-linux.systemd-vaultd
· build #3
· 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.j43nITqMbK', fmt=raw size=107374182413server # mke2fs 1.47.0 (5-Feb-2023)14(finished: start all VMs, in 0.36 seconds)15server # Discarding device blocks: 0/262144 done16server: waiting for unit vault.service17server # Creating filesystem with 262144 4k blocks and 65536 inodes18server: waiting for the VM to finish booting19server # Filesystem UUID: 3eb91a0e-91d1-47ac-8e42-24f805ec661e20server # 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/326z3v11ff15562s9x495g4cpmylwnsl-nixos-system-server-23.11pre-git/init regInfo=/nix/store/19nzyb6h07nrfqf8m149dm7wg9ahr806-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/19nzyb6h07nrfqf8m149dm7wg9ahr806-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.000096] Console: colour dummy device 80x2597server # [ 0.000117] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)98server # [ 0.000119] pid_max: default: 32768 minimum: 30199server # [ 0.000140] LSM: Security Framework initializing100server # [ 0.000146] landlock: Up and running.101server # [ 0.000146] Yama: becoming mindful.102server # [ 0.000165] LSM support for eBPF active103server # [ 0.000184] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)104server # [ 0.000186] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)105server # [ 0.000536] cblist_init_generic: Setting adjustable number of callback queues.106server # [ 0.000538] cblist_init_generic: Setting shift to 2 and lim to 1.107server # [ 0.000549] cblist_init_generic: Setting adjustable number of callback queues.108server # [ 0.000550] 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.000883] Platform MSI: its@8080000 domain created112server # [ 0.000891] PCI/MSI: /intc@8000000/its@8080000 domain created113server # [ 0.000901] fsl-mc MSI: its@8080000 domain created114server # [ 0.000996] EFI services will not be available.115server # [ 0.001036] smp: Bringing up secondary CPUs ...116server # [ 0.001484] Detected PIPT I-cache on CPU1117server # [ 0.001605] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000118server # [ 0.001735] GICv3: CPU1: using allocated LPI pending table @0x0000000043e60000119server # [ 0.001849] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40]120server # [ 0.002259] Detected PIPT I-cache on CPU2121server # [ 0.002384] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000122server # [ 0.002510] GICv3: CPU2: using allocated LPI pending table @0x0000000043e70000123server # [ 0.002650] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40]124server # [ 0.003035] Detected PIPT I-cache on CPU3125server # [ 0.003169] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000126server # [ 0.003297] GICv3: CPU3: using allocated LPI pending table @0x0000000043e80000127server # [ 0.003411] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40]128server # [ 0.003527] smp: Brought up 1 node, 4 CPUs129server # [ 0.003529] SMP: Total of 4 processors activated.130server # [ 0.003531] CPU features: detected: Branch Target Identification131server # [ 0.003533] CPU features: detected: ARMv8.4 Translation Table Level132server # [ 0.003534] CPU features: detected: Instruction cache invalidation not required for I/D coherence133server # [ 0.003535] CPU features: detected: Data cache clean to the PoU not required for I/D coherence134server # [ 0.003537] CPU features: detected: Common not Private translations135server # [ 0.003538] CPU features: detected: CRC32 instructions136server # [ 0.003539] CPU features: detected: E0PD137server # [ 0.003540] CPU features: detected: Enhanced Counter Virtualization138server # [ 0.003541] CPU features: detected: Generic authentication (architected QARMA5 algorithm)139server # [ 0.003542] CPU features: detected: RCpc load-acquire (LDAPR)140server # [ 0.003543] CPU features: detected: LSE atomic instructions141server # [ 0.003544] CPU features: detected: Privileged Access Never142server # [ 0.003545] CPU features: detected: RAS Extension Support143server # [ 0.003546] CPU features: detected: Random Number Generator144server # [ 0.003547] CPU features: detected: Speculation barrier (SB)145server # [ 0.003548] CPU features: detected: Stage-2 Force Write-Back146server # [ 0.003549] CPU features: detected: TLB range maintenance instructions147server # [ 0.003551] CPU features: detected: Speculative Store Bypassing Safe (SSBS)148server # [ 0.003658] CPU: All CPU(s) started at EL1149server # [ 0.003663] alternatives: applying system-wide alternatives150server # [ 0.007409] devtmpfs: initialized151server # [ 0.007967] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns152server # [ 0.007971] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)153server # [ 0.008095] pinctrl core: initialized pinctrl subsystem154server # [ 0.008318] DMI not present or invalid.155server # [ 0.008437] NET: Registered PF_NETLINK/PF_ROUTE protocol family156server # [ 0.009078] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations157server # [ 0.009111] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations158server # [ 0.009144] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations159server # [ 0.009157] audit: initializing netlink subsys (disabled)160server # [ 0.009227] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1161server # [ 0.009355] thermal_sys: Registered thermal governor 'step_wise'162server # [ 0.009357] thermal_sys: Registered thermal governor 'power_allocator'163server # [ 0.009366] cpuidle: using governor ladder164server # [ 0.009369] cpuidle: using governor menu165server # [ 0.009416] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.166server # [ 0.009582] ASID allocator initialised with 65536 entries167server # [ 0.009805] Serial: AMBA PL011 UART driver168server # [ 0.011576] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1169server # [ 0.112412] printk: console [ttyAMA0] enabled170server # [ 0.113317] KASLR enabled171server # [ 0.135336] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages172server # [ 0.136145] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page173server # [ 0.136887] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages174server # [ 0.137682] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page175server # [ 0.138420] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages176server # [ 0.139290] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page177server # [ 0.140028] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages178server # [ 0.140825] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page179server # [ 0.143646] ACPI: Interpreter disabled.180server # [ 0.144463] iommu: Default domain type: Translated181server # [ 0.145044] iommu: DMA domain TLB invalidation policy: strict mode182server # [ 0.145851] SCSI subsystem initialized183server # [ 0.146413] usbcore: registered new interface driver usbfs184server # [ 0.147074] usbcore: registered new interface driver hub185server # [ 0.147740] usbcore: registered new device driver usb186server # [ 0.148452] pps_core: LinuxPPS API ver. 1 registered187server # [ 0.149024] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>188server # [ 0.150057] PTP clock support registered189server # [ 0.150538] EDAC MC: Ver: 3.0.0190server # [ 0.151223] FPGA manager framework191server # [ 0.151644] Advanced Linux Sound Architecture Driver Initialized.192server # [ 0.152601] vgaarb: loaded193server # [ 0.154133] clocksource: Switched to clocksource arch_sys_counter194server # [ 0.154920] VFS: Disk quotas dquot_6.6.0195server # [ 0.155387] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)196server # [ 0.156212] pnp: PnP ACPI: disabled197server # [ 0.158632] NET: Registered PF_INET protocol family198server # [ 0.159249] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)199server # [ 0.160380] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)200server # [ 0.161382] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)201server # [ 0.162318] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)202server # [ 0.163430] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)203server # [ 0.164352] TCP: Hash tables configured (established 8192 bind 8192)204server # [ 0.165133] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)205server # [ 0.166005] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)206server # [ 0.166851] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)207server # [ 0.167699] NET: Registered PF_UNIX/PF_LOCAL protocol family208server # [ 0.168548] RPC: Registered named UNIX socket transport module.209server # [ 0.169250] RPC: Registered udp transport module.210server # [ 0.169809] RPC: Registered tcp transport module.211server # [ 0.170393] RPC: Registered tcp NFSv4.1 backchannel transport module.212server # [ 0.171157] NET: Registered PF_XDP protocol family213server # [ 0.171719] PCI: CLS 0 bytes, default 64214server # [ 0.172331] Trying to unpack rootfs image as initramfs...215server # [ 0.216993] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 counters available216server # [ 0.217960] kvm [1]: HYP mode not available217server # [ 0.218805] Initialise system trusted keyrings218server # [ 0.219411] workingset: timestamp_bits=42 max_order=18 bucket_order=0219server # [ 0.221093] zbud: loaded220server # [ 0.221783] squashfs: version 4.0 (2009/01/31) Phillip Lougher221server # [ 0.223207] NFS: Registering the id_resolver key type222server # [ 0.223978] Key type id_resolver registered223server # [ 0.224614] Key type id_legacy registered224server # [ 0.225245] nfs4filelayout_init: NFSv4 File Layout Driver Registering...225server # [ 0.226270] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...226server # [ 0.227503] 9p: Installing v9fs 9p2000 file system support227server # [ 0.240963] Key type asymmetric registered228server # [ 0.241611] Asymmetric key parser 'x509' registered229server # [ 0.283852] Freeing initrd memory: 9012K230server # [ 0.285276] alg: self-tests for CTR-KDF (hmac(sha256)) passed231server # [ 0.286976] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)232server # [ 0.289142] io scheduler mq-deadline registered233server # [ 0.290453] io scheduler kyber registered234server # [ 0.295077] pl061_gpio 9030000.pl061: PL061 GPIO chip registered235server # [ 0.297372] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:236server # [ 0.299521] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000237server # [ 0.301978] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000238server # [ 0.304465] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000239server # [ 0.306813] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits240server # [ 0.308824] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]241server # [ 0.311079] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00242server # [ 0.312657] pci_bus 0000:00: root bus resource [bus 00-ff]243server # [ 0.313886] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]244server # [ 0.315322] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]245server # [ 0.316765] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]246server # [ 0.318359] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000247server # [ 0.320151] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000248server # [ 0.321471] pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f]249server # [ 0.322702] pci 0000:00:01.0: reg 0x14: [mem 0x00000000-0x00000fff]250server # [ 0.324051] pci 0000:00:01.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]251server # [ 0.325471] pci 0000:00:01.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]252server # [ 0.327400] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00253server # [ 0.328584] pci 0000:00:02.0: reg 0x10: [io 0x0000-0x001f]254server # [ 0.329643] pci 0000:00:02.0: reg 0x14: [mem 0x00000000-0x00000fff]255server # [ 0.330978] pci 0000:00:02.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]256server # [ 0.332835] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200257server # [ 0.333967] pci 0000:00:03.0: reg 0x10: [io 0x0000-0x003f]258server # [ 0.335039] pci 0000:00:03.0: reg 0x14: [mem 0x00000000-0x00000fff]259server # [ 0.336208] pci 0000:00:03.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]260server # [ 0.337981] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200261server # [ 0.339129] pci 0000:00:04.0: reg 0x10: [io 0x0000-0x001f]262server # [ 0.340063] pci 0000:00:04.0: reg 0x14: [mem 0x00000000-0x00000fff]263server # [ 0.341191] pci 0000:00:04.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]264server # [ 0.342924] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200265server # [ 0.343931] pci 0000:00:05.0: reg 0x10: [io 0x0000-0x001f]266server # [ 0.344828] pci 0000:00:05.0: reg 0x14: [mem 0x00000000-0x00000fff]267server # [ 0.345912] pci 0000:00:05.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]268server # [ 0.347599] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000269server # [ 0.348565] pci 0000:00:06.0: reg 0x10: [io 0x0000-0x007f]270server # [ 0.349425] pci 0000:00:06.0: reg 0x14: [mem 0x00000000-0x00000fff]271server # [ 0.350461] pci 0000:00:06.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]272server # [ 0.351958] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000273server # [ 0.352859] pci 0000:00:07.0: reg 0x10: [io 0x0000-0x001f]274server # [ 0.353664] pci 0000:00:07.0: reg 0x14: [mem 0x00000000-0x00000fff]275server # [ 0.354691] pci 0000:00:07.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]276server # [ 0.355690] pci 0000:00:07.0: reg 0x30: [mem 0x00000000-0x0003ffff pref]277server # [ 0.357032] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000278server # [ 0.357926] pci 0000:00:08.0: reg 0x14: [mem 0x00000000-0x00000fff]279server # [ 0.358919] pci 0000:00:08.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]280server # [ 0.360320] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000281server # [ 0.361212] pci 0000:00:09.0: reg 0x14: [mem 0x00000000-0x00000fff]282server # [ 0.362160] pci 0000:00:09.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]283server # [ 0.363491] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320284server # [ 0.364330] pci 0000:00:0a.0: reg 0x10: [mem 0x00000000-0x00000fff]285server # [ 0.365457] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000286server # [ 0.366358] pci 0000:00:0b.0: reg 0x10: [io 0x0000-0x003f]287server # [ 0.367108] pci 0000:00:0b.0: reg 0x14: [mem 0x00000000-0x00000fff]288server # [ 0.368007] pci 0000:00:0b.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]289server # [ 0.369369] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00290server # [ 0.370212] pci 0000:00:0c.0: reg 0x10: [io 0x0000-0x001f]291server # [ 0.370963] pci 0000:00:0c.0: reg 0x14: [mem 0x00000000-0x00000fff]292server # [ 0.371864] pci 0000:00:0c.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]293server # [ 0.373330] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref]294server # [ 0.374291] pci 0000:00:07.0: BAR 6: assigned [mem 0x10040000-0x1007ffff pref]295server # [ 0.375234] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref]296server # [ 0.376321] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref]297server # [ 0.377401] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref]298server # [ 0.378509] pci 0000:00:04.0: BAR 4: assigned [mem 0x800000c000-0x800000ffff 64bit pref]299server # [ 0.379589] pci 0000:00:05.0: BAR 4: assigned [mem 0x8000010000-0x8000013fff 64bit pref]300server # [ 0.380663] pci 0000:00:06.0: BAR 4: assigned [mem 0x8000014000-0x8000017fff 64bit pref]301server # [ 0.381739] pci 0000:00:07.0: BAR 4: assigned [mem 0x8000018000-0x800001bfff 64bit pref]302server # [ 0.382885] pci 0000:00:08.0: BAR 4: assigned [mem 0x800001c000-0x800001ffff 64bit pref]303server # [ 0.383970] pci 0000:00:09.0: BAR 4: assigned [mem 0x8000020000-0x8000023fff 64bit pref]304server # [ 0.385055] pci 0000:00:0b.0: BAR 4: assigned [mem 0x8000024000-0x8000027fff 64bit pref]305server # [ 0.386167] pci 0000:00:0c.0: BAR 4: assigned [mem 0x8000028000-0x800002bfff 64bit pref]306server # [ 0.387266] pci 0000:00:01.0: BAR 1: assigned [mem 0x10080000-0x10080fff]307server # [ 0.388159] pci 0000:00:02.0: BAR 1: assigned [mem 0x10081000-0x10081fff]308server # [ 0.389052] pci 0000:00:03.0: BAR 1: assigned [mem 0x10082000-0x10082fff]309server # [ 0.389949] pci 0000:00:04.0: BAR 1: assigned [mem 0x10083000-0x10083fff]310server # [ 0.390910] pci 0000:00:05.0: BAR 1: assigned [mem 0x10084000-0x10084fff]311server # [ 0.391803] pci 0000:00:06.0: BAR 1: assigned [mem 0x10085000-0x10085fff]312server # [ 0.392697] pci 0000:00:07.0: BAR 1: assigned [mem 0x10086000-0x10086fff]313server # [ 0.393590] pci 0000:00:08.0: BAR 1: assigned [mem 0x10087000-0x10087fff]314server # [ 0.394519] pci 0000:00:09.0: BAR 1: assigned [mem 0x10088000-0x10088fff]315server # [ 0.395418] pci 0000:00:0a.0: BAR 0: assigned [mem 0x10089000-0x10089fff]316server # [ 0.396304] pci 0000:00:0b.0: BAR 1: assigned [mem 0x1008a000-0x1008afff]317server # [ 0.397192] pci 0000:00:0c.0: BAR 1: assigned [mem 0x1008b000-0x1008bfff]318server # [ 0.398077] pci 0000:00:06.0: BAR 0: assigned [io 0x1000-0x107f]319server # [ 0.398945] pci 0000:00:03.0: BAR 0: assigned [io 0x1080-0x10bf]320server # [ 0.399743] pci 0000:00:0b.0: BAR 0: assigned [io 0x10c0-0x10ff]321server # [ 0.400520] pci 0000:00:01.0: BAR 0: assigned [io 0x1100-0x111f]322server # [ 0.401294] pci 0000:00:02.0: BAR 0: assigned [io 0x1120-0x113f]323server # [ 0.402069] pci 0000:00:04.0: BAR 0: assigned [io 0x1140-0x115f]324server # [ 0.402913] pci 0000:00:05.0: BAR 0: assigned [io 0x1160-0x117f]325server # [ 0.403688] pci 0000:00:07.0: BAR 0: assigned [io 0x1180-0x119f]326server # [ 0.404462] pci 0000:00:0c.0: BAR 0: assigned [io 0x11a0-0x11bf]327server # [ 0.405264] pci 0000:00:0a.0: enabling device (0000 -> 0002)328server # [ 0.406844] EINJ: ACPI disabled.329server # [ 0.410516] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)330server # [ 0.412873] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)331server # [ 0.415253] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)332server # [ 0.417624] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)333server # [ 0.420131] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)334server # [ 0.422518] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)335server # [ 0.424892] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)336server # [ 0.428789] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)337server # [ 0.430936] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)338server # [ 0.433361] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)339server # [ 0.435723] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)340server # [ 0.440521] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled341server # [ 0.441950] SuperH (H)SCI(F) driver initialized342server # [ 0.442665] msm_serial: driver initialized343server # [ 0.454828] loop: module loaded344server # [ 0.455383] virtio_blk virtio5: 4/0/0 default/read/poll queues345server # [ 0.457284] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)346server # [ 0.460112] megasas: 07.719.03.00-rc1347server # [ 0.460846] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]348server # [ 0.468809] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000349server # [ 0.470066] Intel/Sharp Extended Query Table at 0x0031350server # [ 0.473289] Using buffer write method351server # [ 0.473792] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]352server # [ 0.476965] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000353server # [ 0.478107] Intel/Sharp Extended Query Table at 0x0031354server # [ 0.480680] Using buffer write method355server # [ 0.481148] Concatenating MTD devices:356server # [ 0.481611] (0): "0.flash"357server # [ 0.481950] (1): "0.flash"358server # [ 0.482318] into device "0.flash"359server # [ 0.484565] tun: Universal TUN/TAP device driver, 1.6360server # [ 0.487333] thunder_xcv, ver 1.0361server # [ 0.487740] thunder_bgx, ver 1.0362server # [ 0.488141] nicpf, ver 1.0363server # [ 0.488683] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version364server # [ 0.489539] hns3: Copyright (c) 2017 Huawei Corporation.365server # [ 0.490206] hclge is initializing366server # [ 0.490644] e1000: Intel(R) PRO/1000 Network Driver367server # [ 0.491225] e1000: Copyright (c) 1999-2006 Intel Corporation.368server # [ 0.491916] e1000e: Intel(R) PRO/1000 Network Driver369server # [ 0.492506] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370server # [ 0.493228] igb: Intel(R) Gigabit Ethernet Network Driver371server # [ 0.493871] igb: Copyright (c) 2007-2014 Intel Corporation.372server # [ 0.494589] igbvf: Intel(R) Gigabit Virtual Function Network Driver373server # [ 0.495339] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374server # [ 0.496107] sky2: driver version 1.30375server # [ 0.496781] VFIO - User Level meta-driver version: 0.3376server # [ 0.498193] ehci-pci 0000:00:0a.0: EHCI Host Controller377server # [ 0.498849] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1378server # [ 0.499853] ehci-pci 0000:00:0a.0: irq 16, io mem 0x10089000379server # [ 0.514180] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00380server # [ 0.515072] hub 1-0:1.0: USB hub found381server # [ 0.515535] hub 1-0:1.0: 6 ports detected382server # [ 0.516379] usbcore: registered new interface driver usb-storage383server # [ 0.517643] rtc-pl031 9010000.pl031: registered as rtc0384server # [ 0.518306] rtc-pl031 9010000.pl031: setting system clock to 2026-07-29T05:12:35 UTC (1785301955)385server # [ 0.519478] i2c_dev: i2c /dev entries driver386server # [ 0.521039] sdhci: Secure Digital Host Controller Interface driver387server # [ 0.521753] sdhci: Copyright(c) Pierre Ossman388server # [ 0.522411] Synopsys Designware Multimedia Card Interface Driver389server # [ 0.523267] sdhci-pltfm: SDHCI platform and OF driver helper390server # [ 0.524384] ledtrig-cpu: registered to indicate activity on CPUs391server # [ 0.525397] hid: raw HID events driver (C) Jiri Kosina392server # [ 0.526064] usbcore: registered new interface driver usbhid393server # [ 0.526774] usbhid: USB HID core driver394server # [ 0.528097] drop_monitor: Initializing network drop monitor service395server # [ 0.543243] NET: Registered PF_INET6 protocol family396server # [ 0.544964] Segment Routing with IPv6397server # [ 0.545414] In-situ OAM (IOAM) with IPv6398server # [ 0.545898] NET: Registered PF_PACKET protocol family399server # [ 0.546679] 9pnet: Installing 9P2000 support400server # [ 0.548908] Key type dns_resolver registered401server # [ 0.549855] registered taskstats version 1402server # [ 0.550399] Loading compiled-in X.509 certificates403server # [ 0.551143] zswap: loaded using pool lzo/zbud404server # [ 0.551849] Key type .fscrypt registered405server # [ 0.552321] Key type fscrypt-provisioning registered406server # [ 0.556188] input: gpio-keys as /devices/platform/gpio-keys/input/input0407server # [ 0.557374] ALSA device list:408server # [ 0.557746] No soundcards found.409server # [ 0.558265] uart-pl011 9000000.pl011: no DMA platform data410server # [ 0.562726] Freeing unused kernel memory: 12032K411server # [ 0.578355] 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.678256] 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.778188] 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.906587] EXT4-fs (vda): mounted filesystem with ordered data mode. Quota mode: none.431server # [ 0.911696] EXT4-fs (vda): re-mounted. Quota mode: none.432server # mounting nix-store on /nix/.ro-store...433server # [ 0.944338] 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/input1434server # mounting tmpfs on /nix/.rw-store...435server # mounting shared on /tmp/shared...436server # [ 1.007119] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0437server # mounting xchg on /tmp/xchg...438server # mounting overlay filesystem on /nix/store...439server # [ 1.138179] usb 1-2: new high-speed USB device number 3 using ehci-pci440server # 441server # [ 1.299945] 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/input2442server # <<< NixOS Stage 2 >>>[ 1.305292] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0443server # 444server # 445server # [ 1.330552] EXT4-fs (vda): re-mounted. Quota mode: none.446server # [ 1.334387] booting system configuration /nix/store/326z3v11ff15562s9x495g4cpmylwnsl-nixos-system-server-23.11pre-git447server # running activation script...448server # setting up /etc...449server # starting systemd...450server # [ 3.137398] 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.143056] systemd[1]: Detected virtualization qemu.452server # [ 3.144044] systemd[1]: Detected architecture arm64.453server # [ 3.148293] systemd[1]: Initializing machine ID from random generator.454server # [ 3.240553] systemd[1]: bpf-lsm: Failed to link program; assuming BPF LSM is not available455server # [ 3.755450] systemd[1]: Queued start job for default target Multi-User System.456server # [ 3.776026] systemd[1]: Created slice Slice /system/getty.457server # [ 3.777163] systemd[1]: Created slice Slice /system/modprobe.458server # [ 3.778270] systemd[1]: Created slice User and Session Slice.459server # [ 3.779045] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.460server # [ 3.780047] systemd[1]: Started Forward Password Requests to Wall Directory Watch.461server # [ 3.781153] systemd[1]: Reached target Local Encrypted Volumes.462server # [ 3.781898] systemd[1]: Reached target Containers.463server # [ 3.782559] systemd[1]: Reached target Path Units.464server # [ 3.783179] systemd[1]: Reached target Remote File Systems.465server # [ 3.783875] systemd[1]: Reached target Slice Units.466server # [ 3.784488] systemd[1]: Reached target Swaps.467server # [ 3.788161] systemd[1]: Listening on Process Core Dump Socket.468server # [ 3.789282] systemd[1]: Listening on Journal Socket (/dev/log).469server # [ 3.790103] systemd[1]: Listening on Journal Socket.470server # [ 3.791259] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.471server # [ 3.793277] systemd[1]: Listening on udev Control Socket.472server # [ 3.794125] systemd[1]: Listening on udev Kernel Socket.473server # [ 3.796179] systemd[1]: Mounting Huge Pages File System...474server # [ 3.798217] systemd[1]: Mounting POSIX Message Queue File System...475server # [ 3.800400] systemd[1]: Mounting Kernel Debug File System...476server # [ 3.803347] systemd[1]: Starting Create List of Static Device Nodes...477server # [ 3.805501] systemd[1]: Starting Load Kernel Module configfs...478server # [ 3.807561] systemd[1]: Starting Load Kernel Module drm...479server # [ 3.809571] systemd[1]: Starting Load Kernel Module efi_pstore...480server # [ 3.811733] systemd[1]: Starting Load Kernel Module fuse...481server # [ 3.813782] systemd[1]: Starting mount-pstore.service...482server # [ 3.814863] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).483server # [ 3.823449] systemd[1]: Starting Journal Service...484server # [ 3.825555] systemd[1]: Starting Load Kernel Modules...485server # [ 3.827682] systemd[1]: Starting Remount Root and Kernel File Systems...486server # [ 3.830890] systemd[1]: Starting Coldplug All udev Devices...487server # [ 3.835779] systemd[1]: Mounted Huge Pages File System.488server # [ 3.836918] systemd[1]: Mounted POSIX Message Queue File System.489server # [ 3.837951] systemd[1]: Mounted Kernel Debug File System.490server # [ 3.839362] systemd[1]: Finished Create List of Static Device Nodes.491server # [ 3.840788] systemd[1]: modprobe@configfs.service: Deactivated successfully.492server # [ 3.842070] systemd[1]: Finished Load Kernel Module configfs.493server # [ 3.845198] systemd[1]: Mounting Kernel Configuration File System...494server # [ 3.848031] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...495server # [ 3.859660] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.496server # [ 3.861151] systemd[1]: Finished Load Kernel Module efi_pstore.497server # [ 3.865863] systemd[1]: Mounted Kernel Configuration File System.498server # [ 3.869942] fuse: init (API version 7.37)499server # [ 3.874806] systemd[1]: modprobe@fuse.service: Deactivated successfully.500server # [ 3.876031] systemd[1]: Finished Load Kernel Module fuse.501server # [ 3.880630] systemd-journald[538]: Collecting audit messages is disabled.502server # [ 3.886775] EXT4-fs (vda): re-mounted. Quota mode: none.503server # [ 3.915150] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.504server # [ 3.918403] systemd[1]: Mounting FUSE Control File System...505server # [ 3.920872] systemd[1]: modprobe@drm.service: Deactivated successfully.506server # [ 3.921992] systemd[1]: Finished Load Kernel Module drm.507server # [ 3.923355] systemd[1]: Finished Remount Root and Kernel File Systems.508server # [ 3.926704] systemd[1]: Starting Load/Save OS Random Seed...509server # [ 3.931923] systemd[1]: Mounted FUSE Control File System.510server # [ 3.938088] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.511server # [ 3.942414] systemd[1]: Starting Create Static Device Nodes in /dev...512server # [ 3.945176] systemd[1]: Finished Load Kernel Modules.513server # [ 3.950614] systemd[1]: Starting Firewall...514server # [ 3.953270] systemd[1]: Starting Apply Kernel Variables...515server # [ 3.942306] systemd-modules-load[539]: Inserted module 'bridge'[ 3.960883] systemd[1]: Started Journal Service.516server # 517server # [ 3.946531] systemd-modules-load[539]: Inserted module 'macvlan'518server # [ 3.949935] systemd-modules-load[539]: Inserted module 'tap'519server # [ 3.952384] systemd-modules-load[539]: Module 'tun' is built in520server # [ 3.954410] systemd-modules-load[539]: Module 'loop' is built in521server # [ 3.957271] systemd-modules-load[539]: Module 'atkbd' is built in522server # [ 3.960379] systemd[1]: Finished Load/Save OS Random Seed.523server # [ 3.963734] systemd[1]: Starting Flush Journal to Persistent Storage...524server # [ 3.996085] systemd-journald[538]: Received client request to flush runtime journal.525server # [ 4.000629] systemd[1]: Finished Create Static Device Nodes in /dev.526server # [ 4.003121] systemd[1]: Reached target Preparation for Local File Systems.527server # [ 4.006842] systemd[1]: Reached target Local File Systems.528server # [ 4.008202] systemd[1]: Starting Rule-based Manager for Device Events and Files...529server # [ 4.009530] systemd[1]: Finished Coldplug All udev Devices.530server # [ 4.010747] systemd[1]: Finished Apply Kernel Variables.531server # [ 4.011747] systemd[1]: Finished Flush Journal to Persistent Storage.532server # [ 4.012985] systemd[1]: Starting Create Volatile Files and Directories...533server # [ 4.051154] systemd-udevd[580]: Using default interface naming scheme 'v253'.534server # [ 4.068745] systemd[1]: Finished Create Volatile Files and Directories.535server # [ 4.084622] systemd[1]: Starting Rebuild Journal Catalog...536server # [ 4.087665] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...537server # [ 4.093952] systemd[1]: Starting Record System Boot/Shutdown in UTMP...538server # [ 4.122470] systemd[1]: Finished Record System Boot/Shutdown in UTMP.539server # [ 4.127096] systemd[1]: Started Rule-based Manager for Device Events and Files.540server # [ 4.142968] systemd[1]: Finished Rebuild Journal Catalog.541server # [ 4.153264] systemd-oomd[602]: No swap; memory pressure usage will be degraded542server # [ 4.154771] systemd[1]: Starting Update is Completed...543server # [ 4.155968] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.544server # [ 4.157686] systemd[1]: Found device /dev/hvc0.545server # [ 4.169394] systemd[1]: Found device /dev/ttyAMA0.546server # [ 4.185954] systemd[1]: Finished Update is Completed.547server # [ 4.197065] (udev-worker)[620]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.548server # [ 4.199362] (udev-worker)[620]: Network interface NamePolicy= disabled on kernel command line.549server # [ 4.201725] (udev-worker)[615]: Network interface NamePolicy= disabled on kernel command line.550server # [ 4.231996] mousedev: PS/2 mouse device common for all mice551server # [ 4.233963] systemd[1]: Found device Virtio network device.552server # [ 4.296355] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3553server # [ 4.318234] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0554server # [ 4.319154] [drm] features: -virgl +edid -resource_blob -host_visible555server # [ 4.319156] [drm] features: -context_init556server # [ 4.321292] [drm] number of scanouts: 1557server # [ 4.321832] [drm] number of cap sets: 0558server # [ 4.324680] [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:09.0 on minor 0559server # [ 4.330282] fbcon: Deferring console take-over560server # [ 4.331027] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device561server # [ 4.364345] systemd[1]: Starting Virtual Console Setup...562server # [ 4.691639] systemd[1]: Finished Virtual Console Setup.563server # [ 4.741378] systemd[1]: Finished Firewall.564server # [ 5.596385] w8yz32d54jbh5dlnl3mr061y6flfa0wm-mount-pstore.sh[545]: Persistent Storage backend was not registered in time.565server # [ 5.601858] systemd[1]: Finished mount-pstore.service.566server # [ 5.604246] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).567server # [ 5.609498] systemd[1]: Reached target System Initialization.568server # [ 5.611765] systemd[1]: Started logrotate.timer.569server # [ 5.613752] systemd[1]: Started Daily Cleanup of Temporary Directories.570server # [ 5.616366] systemd[1]: Reached target Timer Units.571server # [ 5.618352] systemd[1]: Listening on D-Bus System Message Bus Socket.572server # [ 5.621013] systemd[1]: Listening on Nix Daemon Socket.573server # [ 5.623389] systemd[1]: Starting systemd-vaultd socket...574server # [ 5.625654] systemd[1]: Listening on systemd-vaultd socket.575server # [ 5.627849] systemd[1]: Reached target Socket Units.576server # [ 5.630304] systemd[1]: Reached target Basic System.577server # [ 5.632739] systemd[1]: Starting Kernel Auditing...578server # [ 5.635846] systemd[1]: Started backdoor.service.579server # [ 5.638413] systemd[1]: Starting D-Bus System Message Bus...580server # [ 5.641274] systemd[1]: Starting DHCP Client...581server # [ 5.643618] systemd[1]: Starting Logrotate configuration check...582server # [ 5.647202] systemd[1]: Starting Name Service Cache Daemon (nsncd)...583server # [ 5.651074] systemd[1]: Started Reset console on configuration changes.584server # [ 5.655314] systemd[1]: Starting resolvconf update...585server # [ 5.663163] systemd[1]: Starting service1-envfile.service...586server # [ 5.666996] systemd[1]: Starting service2.service...587server # connecting to host...588server # [ 5.670074] systemd[1]: Starting setup-vault-agent-approle.service...589server # [ 5.676179] rrll6i65cnvqwxannbj95lzl20a8y5dv-audit-disable[778]: No rules590server # [ 5.682316] dhcpcd[774]: dhcpcd-9.4.1 starting591server # [ 5.686876] logrotate[775]: warning: logrotate in debug mode does nothing except printing debug messages! Consider using verbose mode (-v) instead if this is not what you want.592server # [ 5.700180] logrotate[775]: reading config file /nix/store/qb85sn99mapdxc37vcclnv3fx3dlqv0q-logrotate.conf593server: Guest shell says: b'Spawning backdoor root shell...\n'594server: connected to guest root shell595server: (connecting took 5.90 seconds)596(finished: waiting for the VM to finish booting, in 5.90 seconds)597server # [ 5.709053] systemd[1]: Started systemd-vaultd daemon.598server # [ 5.713241] setup-vault-agent-approle-start[785]: + vault status599server # [ 5.740467] dhcpcd[793]: dev: loaded udev[ 5.759595] 8021q: 802.1Q VLAN Support v1.8600server # 601server # [ 5.743571] logrotate[775]: note: 'monthly' overrides previously specified 'weekly'602server # [ 5.749661] logrotate[775]: Reading state from file: /var/lib/logrotate.status603server # [ 5.751818] logrotate[775]: state file /var/lib/logrotate.status does not exist604server # [ 5.754195] logrotate[775]: Allocating hash table for state file, size 64 entries605server # [ 5.756138] logrotate[775]: Handling 2 logs606server # [ 5.757596] logrotate[775]: rotating pattern: "/var/log/btmp" monthly (1 rotations)607server # [ 5.759434] logrotate[775]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed608server # [ 5.764851] logrotate[775]: considering log /var/log/btmp609server # [ 5.768461] logrotate[775]: Creating new state610server # [ 5.771215] logrotate[775]: Now: 2026-07-29 05:12611server # [ 5.773855] logrotate[775]: Last rotated at 2026-07-29 05:00612server # [ 5.779117] logrotate[775]: log does not need rotating (log has already been rotated)613server # [ 5.784753] logrotate[775]: rotating pattern: "/var/log/wtmp" monthly (1 rotations)614server # [ 5.786022] logrotate[775]: empty log files are not rotated, only log files >= 1048576 bytes are rotated, old logs are removed615server # [ 5.787707] logrotate[775]: considering log /var/log/wtmp616server # [ 5.788692] logrotate[775]: Creating new state617server # [ 5.789434] logrotate[775]: Now: 2026-07-29 05:12618server # [ 5.790295] logrotate[775]: Last rotated at 2026-07-29 05:00619server # [ 5.791267] logrotate[775]: log does not need rotating (log has already been rotated)620server # [ 5.792795] systemd[1]: Finished Kernel Auditing.621server # [ 5.793892] nsncd[776]: Jul 29 05:12:39.855 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"622server # [ 5.796317] systemd-vaultd[792]: 2026/07/29 05:12:39 Use unix socket received from systemd623server # [ 5.797713] systemd-vaultd[792]: 2026/07/29 05:12:39 Listening on /run/systemd-vaultd/sock624server # [ 5.799036] systemd-vaultd[792]: 2026/07/29 05:12:39 Systemd requested secret for service2.service/secret625server # [ 5.800652] systemd-vaultd[792]: 2026/07/29 05:12:39 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service626server # [ 5.802757] systemd-vaultd[792]: 2026/07/29 05:12:39 Block start until service1.service.EnvironmentFile appears627server # [ 5.804386] systemd-vaultd[792]: 2026/07/29 05:12:39 Block start until service2.service.json appears628server # [ 5.806043] dbus-daemon[773]: dbus[773]: Unknown username "systemd-timesync" in message bus configuration file629server # [ 5.807753] systemd[1]: Finished Logrotate configuration check.630server # [ 5.809000] systemd[1]: Started Name Service Cache Daemon (nsncd).631server # [ 5.810176] systemd[1]: Reached target Host and Network Name Lookups.632server # [ 5.811406] systemd[1]: Reached target User and Group Name Lookups.633server # [ 5.812671] systemd[1]: Starting User Login Management...634server # [ 5.813637] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.635server # [ 5.838912] systemd-logind[818]: New seat seat0.636server # [ 5.860350] cfg80211: Loading compiled-in X.509 certificates for regulatory database637server # [ 5.848225] systemd-logind[818]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)638server # [ 5.849778] systemd-logind[818]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)639server # [ 5.851164] systemd-logind[818]: Watching system buttons on /dev/input/event0 (gpio-keys)640server # [ 5.860884] systemd[1]: Started User Login Management.641server # [ 5.863193] systemd[1]: Started D-Bus System Message Bus.642server # [ 5.866276] systemd[1]: Stopped target Host and Network Name Lookups.643server # [ 5.871868] systemd[1]: Stopping Host and Network Name Lookups...644server # [ 5.876094] systemd[1]: Stopped target User and Group Name Lookups.645server # [ 5.879474] systemd[1]: Stopping User and Group Name Lookups...646server # [ 5.883174] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...647server # [ 5.888035] systemd[1]: nscd.service: Deactivated successfully.648server # [ 5.891680] systemd[1]: Stopped Name Service Cache Daemon (nsncd).[ 5.911019] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'649server # 650server # [ 5.897441] systemd[1]: Starting Name Service Cache Daemon (nsncd)...651server # [ 5.921005] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2652server # [ 5.922305] cfg80211: failed to load regulatory.db653server # [ 5.916779] nsncd[860]: Jul 29 05:12:40.065 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"654server # [ 5.923493] systemd[1]: Started Name Service Cache Daemon (nsncd).655server # [ 5.926431] systemd[1]: Reached target Host and Network Name Lookups.656server # [ 5.929967] systemd[1]: Reached target User and Group Name Lookups.657server # [ 5.933452] systemd[1]: Finished resolvconf update.658server # [ 5.935760] systemd[1]: Reached target Preparation for Network.659server # [ 5.938434] systemd[1]: Reached target All Network Interfaces (deprecated).660server # [ 5.964687] systemd[1]: Starting Address configuration of eth1...661server # [ 5.993809] 8021q: adding VLAN 0 to HW filter on device eth0662server # [ 5.977014] dhcpcd[793]: eth0: waiting for carrier663server # [ 5.979350] dhcpcd[793]: eth0: carrier acquired664server # [ 5.986299] dhcpcd[793]: DUID 00:01:00:01:31:fc:48:48:52:54:00:12:34:56665server # [ 5.989749] dhcpcd[793]: eth0: IAID 00:12:34:56666server # [ 5.992171] dhcpcd[793]: eth0: adding address fe80::5054:ff:fe12:3456667server # [ 6.018858] 8021q: adding VLAN 0 to HW filter on device eth1668server # [ 6.009930] network-addresses-eth1-start[886]: adding address 192.168.1.1/24... done669server # [ 6.023033] systemd[1]: Finished Address configuration of eth1.670server # [ 6.044719] systemd[1]: Starting Networking Setup...671server # [ 6.164723] systemd[1]: Finished Networking Setup.672server # [ 6.180602] systemd[1]: Starting Extra networking commands....673server # [ 6.193606] systemd[1]: Finished Extra networking commands..674server # [ 6.195729] systemd[1]: Reached target Network.675server # [ 6.197623] systemd[1]: Starting Permit User Sessions...676server # [ 6.199548] systemd[1]: Started vault-agent-default.service.677server # [ 6.201884] systemd[1]: Started Vault server daemon.678server # [ 6.225480] systemd[1]: Finished Permit User Sessions.679server # [ 6.228475] systemd[1]: Started Getty on tty1.680server # [ 6.230159] systemd[1]: Reached target Login Prompts.681server # [ 6.507179] dhcpcd[793]: eth0: soliciting an IPv6 router682server # [ 6.558212] setup-vault-agent-approle-start[801]: 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.563182] vault[948]: ==> Vault server configuration:684server # [ 6.564102] vault[948]: Administrative Namespace:685server # [ 6.565131] vault[948]: Api Address: http://127.0.0.1:8200686server # [ 6.566257] vault[948]: Cgo: enabled687server # [ 6.567278] vault[948]: Cluster Address: https://127.0.0.1:8201688server # [ 6.568555] vault[948]: Environment Variables: GODEBUG, HOME, INVOCATION_ID, JOURNAL_STREAM, LANG, LOCALE_ARCHIVE, LOGNAME, MEMORY_PRESSURE_WATCH, MEMORY_PRESSURE_WRITE, PATH, PWD, SHELL, SHLVL, STATE_DIRECTORY, SYSTEMD_EXEC_PID, TZDIR, USER689server # [ 6.572045] vault[948]: Go Version: go1.21.3690server # [ 6.572757] vault[948]: Listener 1: tcp (addr: "127.0.0.1:8200", cluster address: "127.0.0.1:8201", max_request_duration: "1m30s", max_request_size: "33554432", tls: "disabled")691server # [ 6.574917] vault[948]: Log Level:692server # [ 6.575525] vault[948]: Mlock: supported: true, enabled: false693server # [ 6.576709] vault[948]: Recovery Mode: false694server # [ 6.577624] vault[948]: Storage: inmem695server # [ 6.578542] vault[948]: Version: Vault v1.14.4696server # [ 6.579505] setup-vault-agent-approle-start[785]: + sleep 1697server # [ 6.924076] dhcpcd[793]: eth0: soliciting a DHCP lease698server # [ 6.949106] dhcpcd[793]: eth0: offered 10.0.2.15 from 10.0.2.2699server # [ 6.951821] dhcpcd[793]: eth0: leased 10.0.2.15 for 86400 seconds700server # [ 6.954659] dhcpcd[793]: eth0: adding route to 10.0.2.0/24701server # [ 6.957030] dhcpcd[793]: eth0: adding default route via 10.0.2.2702(finished: waiting for unit vault.service, in 7.24 seconds)703server: waiting for TCP port 8200 on localhost704server # [ 7.101966] systemd[1]: Stopped target Host and Network Name Lookups.705server # [ 7.104220] systemd[1]: Stopping Host and Network Name Lookups...706server # [ 7.106704] systemd[1]: Stopped target User and Group Name Lookups.707server # [ 7.109686] systemd[1]: Stopping User and Group Name Lookups...708server # [ 7.111651] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...709server # [ 7.114788] systemd[1]: nscd.service: Deactivated successfully.710server # [ 7.117611] systemd[1]: Stopped Name Service Cache Daemon (nsncd).711server # [ 7.132936] systemd[1]: Starting Name Service Cache Daemon (nsncd)...712server # [ 7.151047] nsncd[1021]: Jul 29 05:12:41.299 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 3s }, path: "/var/run/nscd/socket"713server # Connection to localhost[ 7.156907] systemd[1]: Started Name Service Cache Daemon (nsncd).714server # (127.0.0.1)[ 7.160028] systemd[1]: Reached target Host and Network Name Lookups.715server # 8200 port [tcp/trivnet1] succeeded!716server # [ 7.163017] systemd[1]: Reached target User and Group Name Lookups.717(finished: waiting for TCP port 8200 on localhost, in 0.11 seconds)718server: waiting for unit setup-vault-agent-approle.service719server # [ 7.170129] dhcpcd[774]: Failed to reload-or-try-restart ntpd.service: Unit ntpd.service not found.720server # [ 7.172344] dhcpcd[774]: Failed to reload-or-try-restart openntpd.service: Unit openntpd.service not found.721server # [ 7.174295] dhcpcd[774]: Failed to reload-or-try-restart chronyd.service: Unit chronyd.service not found.722server # [ 7.180739] systemd[1]: Started DHCP Client.723server # [ 7.182159] systemd[1]: Reached target Network is Online.724server # [ 7.351755] vault[948]: ==> Vault server started! Log data will stream in below:725server # [ 7.353218] vault[948]: 2026-07-29T05:12:40.706Z [INFO] proxy environment: http_proxy="" https_proxy="" no_proxy=""726server # [ 7.355002] vault[948]: 2026-07-29T05:12:40.708Z [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 set727server # [ 7.358012] vault[948]: 2026-07-29T05:12:40.711Z [INFO] core: Initializing version history cache for core728server # [ 7.359656] vault[948]: 2026-07-29T05:12:40.712Z [INFO] core: security barrier not initialized729server # [ 7.361247] vault[948]: 2026-07-29T05:12:40.713Z [INFO] core: security barrier initialized: stored=1 shares=1 threshold=1730server # [ 7.363212] vault[948]: 2026-07-29T05:12:40.716Z [INFO] core: post-unseal setup starting731server # [ 7.364683] vault[948]: 2026-07-29T05:12:40.725Z [INFO] core: loaded wrapping token key732server # [ 7.366082] vault[948]: 2026-07-29T05:12:40.725Z [INFO] core: successfully setup plugin catalog: plugin-directory=""733server # [ 7.367870] vault[948]: 2026-07-29T05:12:40.727Z [INFO] core: no mounts; adding default mount table734server # [ 7.369514] vault[948]: 2026-07-29T05:12:40.731Z [INFO] core: successfully mounted: type=cubbyhole version="v1.14.4+builtin.vault" path=cubbyhole/ namespace="ID: root. Path: "735server # [ 7.372047] vault[948]: 2026-07-29T05:12:40.736Z [INFO] core: successfully mounted: type=system version="v1.14.4+builtin.vault" path=sys/ namespace="ID: root. Path: "736server # [ 7.374466] vault[948]: 2026-07-29T05:12:40.738Z [INFO] core: successfully mounted: type=identity version="v1.14.4+builtin.vault" path=identity/ namespace="ID: root. Path: "737server # [ 7.377060] vault[948]: 2026-07-29T05:12:40.741Z [INFO] core: successfully mounted: type=token version="v1.14.4+builtin.vault" path=token/ namespace="ID: root. Path: "738server # [ 7.379557] vault[948]: 2026-07-29T05:12:40.743Z [INFO] rollback: starting rollback manager739server # [ 7.381122] vault[948]: 2026-07-29T05:12:40.744Z [INFO] core: restoring leases740server # [ 7.382441] vault[948]: 2026-07-29T05:12:40.745Z [INFO] identity: entities restored741server # [ 7.383832] vault[948]: 2026-07-29T05:12:40.745Z [INFO] identity: groups restored742server # [ 7.385294] vault[948]: 2026-07-29T05:12:40.746Z [INFO] core: Recorded vault version: vault version=1.14.4 upgrade time="2026-07-29 05:12:40.74681992 +0000 UTC" build date=""743server # [ 7.387945] vault[948]: 2026-07-29T05:12:40.747Z [INFO] expiration: lease restore complete744server # [ 7.389486] vault[948]: 2026-07-29T05:12:41.475Z [INFO] core: post-unseal setup complete745server # [ 7.390891] vault[948]: 2026-07-29T05:12:41.476Z [INFO] core: root token generated746server # [ 7.392314] vault[948]: 2026-07-29T05:12:41.476Z [INFO] core: pre-seal teardown starting747server # [ 7.393842] vault[948]: 2026-07-29T05:12:41.477Z [INFO] rollback: stopping rollback manager748server # [ 7.395237] vault[948]: 2026-07-29T05:12:41.478Z [INFO] core: pre-seal teardown complete749server # [ 7.396660] vault[948]: 2026-07-29T05:12:41.479Z [INFO] core.cluster-listener.tcp: starting listener: listener_address=127.0.0.1:8201750server # [ 7.398572] vault[948]: 2026-07-29T05:12:41.479Z [INFO] core.cluster-listener: serving cluster requests: cluster_listen_address=127.0.0.1:8201751server # [ 7.400734] vault[948]: 2026-07-29T05:12:41.479Z [INFO] core: post-unseal setup starting752server # [ 7.402063] vault[948]: 2026-07-29T05:12:41.479Z [INFO] core: loaded wrapping token key753server # [ 7.403385] vault[948]: 2026-07-29T05:12:41.479Z [INFO] core: successfully setup plugin catalog: plugin-directory=""754server # [ 7.405200] vault[948]: 2026-07-29T05:12:41.480Z [INFO] core: successfully mounted: type=system version="v1.14.4+builtin.vault" path=sys/ namespace="ID: root. Path: "755server # [ 7.407543] vault[948]: 2026-07-29T05:12:41.481Z [INFO] core: successfully mounted: type=identity version="v1.14.4+builtin.vault" path=identity/ namespace="ID: root. Path: "756server # [ 7.410104] vault[948]: 2026-07-29T05:12:41.481Z [INFO] core: successfully mounted: type=cubbyhole version="v1.14.4+builtin.vault" path=cubbyhole/ namespace="ID: root. Path: "757server # [ 7.412488] vault[948]: 2026-07-29T05:12:41.482Z [INFO] core: successfully mounted: type=token version="v1.14.4+builtin.vault" path=token/ namespace="ID: root. Path: "758server # [ 7.414693] vault[948]: 2026-07-29T05:12:41.483Z [INFO] rollback: starting rollback manager759server # [ 7.415988] vault[948]: 2026-07-29T05:12:41.483Z [INFO] core: restoring leases760server # [ 7.417221] vault[948]: 2026-07-29T05:12:41.483Z [INFO] identity: entities restored761server # [ 7.418405] vault[948]: 2026-07-29T05:12:41.483Z [INFO] expiration: lease restore complete762server # [ 7.419646] vault[948]: 2026-07-29T05:12:41.483Z [INFO] identity: groups restored763server # [ 7.420906] vault[948]: 2026-07-29T05:12:41.484Z [INFO] core: post-unseal setup complete764server # [ 7.422158] vault[948]: 2026-07-29T05:12:41.484Z [INFO] core: vault is unsealed765server # [ 7.423310] vault[948]: 2026-07-29T05:12:41.493Z [INFO] expiration: revoked lease: lease_id=auth/token/root/hac25909f4f2e7c4eb42f264c23f377227311c9b7d74b92bda3054a2a5159c120766server # [ 7.425728] vault[948]: 2026-07-29T05:12:41.500Z [INFO] core: successful mount: namespace="" path=secret/ type=kv version=""767server # [ 7.428677] vault[947]: ==> Vault Agent started! Log data will stream in below:768server # [ 7.430587] vault[947]: ==> Vault Agent configuration:769server # [ 7.433530] vault[947]: Api Address 1: http://bufconn770server # [ 7.436319] vault[947]: Cgo: enabled771server # [ 7.438463] vault[947]: Log Level:772server # [ 7.440289] vault[947]: Version: Vault v1.14.4773server # [ 7.442508] vault[947]: 2026-07-29T05:12:41.580Z [INFO] agent.exec.server: starting exec server774server # [ 7.445715] vault[947]: 2026-07-29T05:12:41.580Z [INFO] agent.exec.server: no env templates or exec config, exiting775server # [ 7.449427] vault[947]: 2026-07-29T05:12:41.580Z [INFO] agent.auth.handler: starting auth handler776server # [ 7.452740] vault[947]: 2026-07-29T05:12:41.580Z [INFO] agent.auth.handler: authenticating777server # [ 7.456300] vault[947]: 2026-07-29T05:12:41.580Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1s778server # [ 7.461622] vault[947]: 2026-07-29T05:12:41.580Z [INFO] agent.template.server: starting template server779server # [ 7.465561] vault[947]: 2026-07-29T05:12:41.580Z [INFO] agent.sink.server: starting sink server780server # [ 7.469127] vault[947]: 2026-07-29T05:12:41.582Z [INFO] (runner) creating new runner (dry: false, once: false)781server # [ 7.471306] vault[947]: 2026-07-29T05:12:41.584Z [INFO] (runner) creating watcher782server # [ 7.473037] vault[948]: WARNING! dev mode is enabled! In this mode, Vault runs entirely in-memory783server # [ 7.474642] vault[948]: and starts unsealed with a single unseal key. The root token is already784server # [ 7.476229] vault[948]: authenticated to the CLI, so you can immediately begin using Vault.785server # [ 7.477736] vault[948]: You may need to set the following environment variables:786server # [ 7.479100] vault[948]: $ export VAULT_ADDR='http://127.0.0.1:8200'787server # [ 7.480335] vault[948]: The unseal key and root token are displayed below in case you want to788server # [ 7.481834] vault[948]: seal/unseal the Vault or re-authenticate.789server # [ 7.482922] vault[948]: Unseal Key: mRN/zi/uGmdg0Vbs8UEvx9lx2PhWC+GKVau8X0faUss=790server # [ 7.484247] vault[948]: Root Token: phony-secret791server # [ 7.485097] vault[948]: Development mode should NOT be used in production installations!792server # [ 7.576121] setup-vault-agent-approle-start[785]: + vault status793server # [ 7.719713] setup-vault-agent-approle-start[1047]: Key Value794server # [ 7.722536] setup-vault-agent-approle-start[1047]: --- -----795server # [ 7.725173] setup-vault-agent-approle-start[1047]: Seal Type shamir796server # [ 7.727756] setup-vault-agent-approle-start[1047]: Initialized true797server # [ 7.730586] setup-vault-agent-approle-start[1047]: Sealed false798server # [ 7.733374] setup-vault-agent-approle-start[1047]: Total Shares 1799server # [ 7.735893] setup-vault-agent-approle-start[1047]: Threshold 1800server # [ 7.738572] setup-vault-agent-approle-start[1047]: Version 1.14.4801server # [ 7.741357] setup-vault-agent-approle-start[1047]: Build Date n/a802server # [ 7.743932] setup-vault-agent-approle-start[1047]: Storage Type inmem803server # [ 7.747237] setup-vault-agent-approle-start[1047]: Cluster Name vault-cluster-f8e03e8d804server # [ 7.751050] setup-vault-agent-approle-start[1047]: Cluster ID 4fef1b98-b00b-62b1-4182-98207f4b2eee805server # [ 7.755464] setup-vault-agent-approle-start[1047]: HA Enabled false806server # [ 7.758797] setup-vault-agent-approle-start[785]: + cat807server # [ 7.761396] dhcpcd[793]: eth0: Router Advertisement from fe80::2808server # [ 7.763859] dhcpcd[793]: eth0: adding address fec0::5054:ff:fe12:3456/64809server # [ 7.766793] dhcpcd[793]: eth0: adding route to fec0::/64810server # [ 7.769101] dhcpcd[793]: eth0: adding default route via fe80::2811server # [ 7.775389] setup-vault-agent-approle-start[785]: + vault policy write demo /tmp/policy-file.hcl812server # [ 7.920426] setup-vault-agent-approle-start[1056]: Success! Uploaded policy: demo813server # [ 7.933469] setup-vault-agent-approle-start[785]: + vault kv put secret/my-secret foo=bar814server # [ 8.071674] setup-vault-agent-approle-start[1064]: ==== Secret Path ====815server # [ 8.074526] setup-vault-agent-approle-start[1064]: secret/data/my-secret816server # [ 8.077158] setup-vault-agent-approle-start[1064]: ======= Metadata =======817server # [ 8.079928] setup-vault-agent-approle-start[1064]: Key Value818server # [ 8.082866] setup-vault-agent-approle-start[1064]: --- -----819server # [ 8.085749] setup-vault-agent-approle-start[1064]: created_time 2026-07-29T05:12:42.2163737Z820server # [ 8.089314] setup-vault-agent-approle-start[1064]: custom_metadata <nil>821server # [ 8.092114] setup-vault-agent-approle-start[1064]: deletion_time n/a822server # [ 8.094886] setup-vault-agent-approle-start[1064]: destroyed false823server # [ 8.097766] setup-vault-agent-approle-start[1064]: version 1824server # [ 8.100484] setup-vault-agent-approle-start[785]: + vault auth enable approle825server # [ 8.270941] vault[948]: 2026-07-29T05:12:42.419Z [INFO] core: enabled credential backend: path=approle/ type=approle version=""826server # [ 8.276268] setup-vault-agent-approle-start[1073]: Success! Enabled approle auth method at: approle/827server # [ 8.284737] setup-vault-agent-approle-start[785]: + vault write auth/approle/role/role1 bind_secret_id=true token_policies=demo828server # [ 8.433364] vault[947]: 2026-07-29T05:12:42.581Z [INFO] agent.auth.handler: authenticating829server # [ 8.436986] vault[947]: 2026-07-29T05:12:42.581Z [ERROR] agent.auth.handler: error getting path or data from method: error="no known role ID" backoff=1.6s830server # [ 8.452845] setup-vault-agent-approle-start[1084]: Success! Data written to: auth/approle/role/role1831server # [ 8.464498] setup-vault-agent-approle-start[1094]: ++ vault read -format json auth/approle/role/role1/role-id832server # [ 8.468886] setup-vault-agent-approle-start[1095]: ++ jq -r .data.role_id833server # [ 8.607590] setup-vault-agent-approle-start[785]: + echo -n cc18a7b9-1365-c1c4-a27b-3b4059f90b96834server # [ 8.611962] setup-vault-agent-approle-start[1103]: ++ vault write -force -format json auth/approle/role/role1/secret-id835server # [ 8.616905] setup-vault-agent-approle-start[1104]: ++ jq -r .data.secret_id836server # [ 8.759447] setup-vault-agent-approle-start[785]: + echo -n e6dab8c4-6132-6851-a1fe-870e54d1635b837server # [ 8.766723] systemd[1]: Finished setup-vault-agent-approle.service.838(finished: waiting for unit setup-vault-agent-approle.service, in 2.28 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 # grep: /tmp/service1: No such file or directory843server # [ 10.035363] vault[947]: 2026-07-29T05:12:44.183Z [INFO] agent.auth.handler: authenticating844server # [ 10.050396] vault[947]: 2026-07-29T05:12:44.199Z [INFO] agent.auth.handler: authentication successful, sending token to sinks845server # [ 10.056322] vault[947]: 2026-07-29T05:12:44.199Z [INFO] agent.template.server: template server received new token846server # [ 10.061259] vault[947]: 2026-07-29T05:12:44.199Z [INFO] agent.auth.handler: starting renewal process847server # [ 10.064762] vault[947]: 2026-07-29T05:12:44.199Z [INFO] (runner) stopping848server # [ 10.067560] vault[947]: 2026-07-29T05:12:44.199Z [INFO] (runner) creating new runner (dry: false, once: false)849server # [ 10.071492] vault[947]: 2026-07-29T05:12:44.199Z [INFO] (runner) creating watcher850server # [ 10.074640] vault[947]: 2026-07-29T05:12:44.200Z [INFO] (runner) starting851server # [ 10.078134] vault[947]: 2026-07-29T05:12:44.209Z [INFO] agent.auth.handler: renewed auth token852server # [ 10.082556] vault[947]: 2026-07-29T05:12:44.221Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json"853server # [ 10.088811] vault[947]: 2026-07-29T05:12:44.223Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.EnvironmentFile"854server # [ 10.094627] vault[947]: 2026-07-29T05:12:44.223Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service1.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service1.service.json"855server # [ 10.102361] vault[947]: 2026-07-29T05:12:44.224Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 1 after "250ms")856server # [ 10.110125] vault[947]: 2026-07-29T05:12:44.225Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service1.service'857server # [ 10.128592] systemd-vaultd[792]: 2026/07/29 05:12:44 Detected added file: 2448006045858server # [ 10.131562] systemd-vaultd[792]: 2026/07/29 05:12:44 Detected added file: service1.service.json859server # [ 10.134984] systemd-vaultd[792]: 2026/07/29 05:12:44 Ignore unknown file: 2448006045860server # [ 10.138043] systemd-vaultd[792]: 2026/07/29 05:12:44 Ignore unknown file: service1.service.json861server # [ 10.141475] systemd-vaultd[792]: 2026/07/29 05:12:44 Detected added file: 1815457440862server # [ 10.144362] systemd-vaultd[792]: 2026/07/29 05:12:44 Detected added file: service1.service.EnvironmentFile863server # [ 10.147926] systemd-vaultd[792]: 2026/07/29 05:12:44 Ignore unknown file: 1815457440864server # [ 10.150994] systemd-vaultd[792]: 2026/07/29 05:12:44 Served service1.service.EnvironmentFile to @c19694896a7a109b/unit/service1-envfile.service/service1.service.EnvironmentFile865server # [ 10.156781] systemd[1]: Finished service1-envfile.service.866server # [ 10.158694] systemd[1]: Started service1.service.867server # [ 10.160473] systemd[1]: Stopping service1.service...868server # [ 10.173593] systemd[1]: service1.service: Deactivated successfully.869server # [ 10.176126] systemd[1]: Stopped service1.service.870server # [ 10.177987] systemd[1]: service1-envfile.service: Deactivated successfully.871server # [ 10.180825] systemd[1]: Stopped service1-envfile.service.872server # [ 10.182970] systemd[1]: Stopping service1-envfile.service...873server # [ 10.185299] systemd[1]: Starting service1-envfile.service...874server # [ 10.187531] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.875server # [ 10.191060] systemd-vaultd[792]: 2026/07/29 05:12:44 Systemd requested environment file for service1.service.EnvironmentFile from service1-envfile.service876server # [ 10.201267] systemd[1]: Finished service1-envfile.service.877server # [ 10.222602] systemd[1]: Started service1.service.878server # [ 10.226566] systemd-vaultd[792]: 2026/07/29 05:12:44 Systemd requested secret for service1.service/foo879server # [ 10.253472] systemd[1]: service1.service: Deactivated successfully.880server # [ 10.257988] systemd[1]: run-credentials-service1.service.mount: Deactivated successfully.881server # [ 10.261251] systemd[1]: service1-envfile.service: Deactivated successfully.882server # [ 10.263914] systemd[1]: Stopped service1-envfile.service.883server # [ 10.330528] vault[947]: 2026-07-29T05:12:44.479Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 2 after "500ms")884(finished: waiting for success: grep -q bar /tmp/service1, in 1.10 seconds)885server: must succeed: grep -q bar /tmp/service1-env886(finished: must succeed: grep -q bar /tmp/service1-env, in 0.04 seconds)887server: must succeed: systemctl status service2 || :888(finished: must succeed: systemctl status service2 || :, in 0.09 seconds)889server: must succeed: vault kv put secret/blocking-secret foo=bar890server # [ 10.833828] vault[947]: 2026-07-29T05:12:44.982Z [WARN] (view) vault.read(secret/blocking-secret): no secret exists at secret/data/blocking-secret (retry attempt 3 after "1s")891(finished: must succeed: vault kv put secret/blocking-secret foo=bar, in 0.25 seconds)892server: waiting for success: grep -q bar /tmp/service2 >&2893server # grep: /tmp/service2: No such file or directory894server # [ 11.078175] systemd[1]: run-credentials-service1\x2denvfile.service.mount: Deactivated successfully.895server # [ 11.842767] systemd-vaultd[792]: 2026/07/29 05:12:45 Detected added file: 2515705492896server # [ 11.847185] systemd-vaultd[792]: 2026/07/29 05:12:45 Detected added file: service2.service.json897server # [ 11.852250] systemd-vaultd[792]: 2026/07/29 05:12:45 Ignore unknown file: 2515705492898server # [ 11.855925] vault[947]: 2026-07-29T05:12:45.990Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"899server # [ 11.861358] vault[947]: 2026-07-29T05:12:45.991Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service2.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"900server # [ 11.868996] vault[947]: 2026-07-29T05:12:45.992Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'901server # [ 11.873954] systemd-vaultd[792]: 2026/07/29 05:12:45 Served service2.service.json to @9077207a02920097/unit/service2.service/secret902server # [ 11.925234] systemd[1]: Started service2.service.903server # [ 11.935666] systemd[1]: Reloading service2.service...904server # [ 11.940719] systemd-vaultd[792]: 2026/07/29 05:12:46 Systemd requested secret for service2.service/secret905server # [ 11.960151] service2-start[1177]: + true906server # [ 11.967409] service2-start[1177]: + cat /run/service2/secrets/secret907server # [ 11.971431] systemd-vaultd-update-secrets[1178]: 2026/07/29 05:12:46 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated908server # [ 11.977859] service2-start[1184]: bar909server # [ 11.979514] service2-start[1177]: + cat /run/service2/secrets/secret910server # [ 11.982546] service2-start[1177]: + sleep 0.1911server # [ 12.086920] service2-start[1177]: + true912server # [ 12.089191] service2-start[1177]: + cat /run/service2/secrets/secret913server # [ 12.095635] service2-start[1190]: bar914server # [ 12.097732] service2-start[1177]: + cat /run/service2/secrets/secret915server # [ 12.103939] service2-start[1177]: + sleep 0.1916(finished: waiting for success: grep -q bar /tmp/service2 >&2, in 1.11 seconds)917server: must succeed: umount /run/credentials/service2.service918server # [ 12.158278] systemd[1]: run-credentials-service2.service.mount: Deactivated successfully.919(finished: must succeed: umount /run/credentials/service2.service, in 0.03 seconds)920server: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json921(finished: must succeed: rm /run/systemd-vaultd/secrets/service2.service.json, in 0.04 seconds)922server: must succeed: vault kv put secret/blocking-secret foo=reload923server # [ 12.213421] service2-start[1177]: + true924server # [ 12.215656] service2-start[1177]: + cat /run/service2/secrets/secret925server # [ 12.221743] service2-start[1204]: bar926server # [ 12.224057] service2-start[1177]: + cat /run/service2/secrets/secret927server # [ 12.229956] service2-start[1177]: + sleep 0.1928server # [ 12.297295] fbcon: Taking over console929server # [ 12.298260] virtio-pci 0000:00:09.0: [drm] drm_plane_enable_fb_damage_clips() not called930server # [ 12.298331] Console: switching to colour frame buffer device 160x50931server # [ 12.338059] service2-start[1177]: + true932server # [ 12.340778] service2-start[1177]: + cat /run/service2/secrets/secret933server # [ 12.345350] service2-start[1218]: bar934server # [ 12.348121] service2-start[1177]: + cat /run/service2/secrets/secret935server # [ 12.352760] service2-start[1177]: + sleep 0.1936server # [ 12.394920] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.937server # [ 12.397319] systemd[1]: Stopped Virtual Console Setup.938server # [ 12.398800] systemd[1]: Stopping Virtual Console Setup...939server # [ 12.417985] systemd[1]: Starting Virtual Console Setup...940(finished: must succeed: vault kv put secret/blocking-secret foo=reload, in 0.23 seconds)941server: must succeed: systemctl restart vault-agent-default942server # [ 12.462039] service2-start[1177]: + true943server # [ 12.465100] service2-start[1177]: + cat /run/service2/secrets/secret944server # [ 12.470242] service2-start[1231]: bar945server # [ 12.473109] service2-start[1177]: + cat /run/service2/secrets/secret946server # [ 12.477782] service2-start[1177]: + sleep 0.1947server # [ 12.490251] vault[947]: ==> Vault Agent shutdown triggered948server # [ 12.494365] vault[947]: 2026-07-29T05:12:46.638Z [INFO] agent.exec.server: exec server stopped949server # [ 12.499630] vault[947]: 2026-07-29T05:12:46.638Z [INFO] agent.auth.handler: shutdown triggered, stopping lifetime watcher950server # [ 12.505187] vault[947]: 2026-07-29T05:12:46.638Z [INFO] agent.auth.handler: auth handler stopped951server # [ 12.509710] vault[947]: 2026-07-29T05:12:46.638Z [INFO] (runner) stopping952server # [ 12.513293] vault[947]: 2026-07-29T05:12:46.638Z [INFO] agent.template.server: template server stopped953server # [ 12.518517] vault[947]: 2026-07-29T05:12:46.638Z [INFO] agent.sink.server: sink server stopped954server # [ 12.523845] vault[947]: 2026-07-29T05:12:46.638Z [INFO] agent: sinks finished, exiting955server # [ 12.527209] systemd[1]: Stopping vault-agent-default.service...956server # [ 12.529693] systemd[1]: vault-agent-default.service: Deactivated successfully.957server # [ 12.532686] systemd[1]: Stopped vault-agent-default.service.958server # [ 12.535210] systemd[1]: vault-agent-default.service: Consumed 216ms CPU time, no IO, received 6.3K IP traffic, sent 4.9K IP traffic.959server # [ 12.540893] systemd[1]: Started vault-agent-default.service.960(finished: must succeed: systemctl restart vault-agent-default, in 0.11 seconds)961server: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2962server # cat: /run/systemd-vaultd/secrets/service2.service.json[ 12.591143] service2-start[1177]: + true963server # : No such file or directory[ 12.592984] service2-start[1177]: + cat /run/service2/secrets/secret964server # 965server # [ 12.599204] service2-start[1249]: bar966server # [ 12.599942] service2-start[1177]: + cat /run/service2/secrets/secret967server # [ 12.607752] service2-start[1177]: + sleep 0.1968server # [ 12.666935] vault[1236]: ==> Vault Agent started! Log data will stream in below:969server # [ 12.668146] vault[1236]: ==> Vault Agent configuration:970server # [ 12.668919] vault[1236]: Api Address 1: http://bufconn971server # [ 12.669743] vault[1236]: Cgo: enabled972server # [ 12.670493] vault[1236]: Log Level:973server # [ 12.671161] vault[1236]: Version: Vault v1.14.4974server # [ 12.672262] vault[1236]: 2026-07-29T05:12:46.815Z [INFO] agent.exec.server: starting exec server975server # [ 12.673564] vault[1236]: 2026-07-29T05:12:46.815Z [INFO] agent.exec.server: no env templates or exec config, exiting976server # [ 12.675289] vault[1236]: 2026-07-29T05:12:46.815Z [INFO] agent.template.server: starting template server977server # [ 12.676623] vault[1236]: 2026-07-29T05:12:46.815Z [INFO] agent.sink.server: starting sink server978server # [ 12.677814] vault[1236]: 2026-07-29T05:12:46.816Z [INFO] agent.auth.handler: starting auth handler979server # [ 12.679106] vault[1236]: 2026-07-29T05:12:46.816Z [INFO] agent.auth.handler: authenticating980server # [ 12.680391] vault[1236]: 2026-07-29T05:12:46.816Z [INFO] (runner) creating new runner (dry: false, once: false)981server # [ 12.681962] vault[1236]: 2026-07-29T05:12:46.817Z [INFO] (runner) creating watcher982server # [ 12.683003] vault[1236]: 2026-07-29T05:12:46.819Z [INFO] agent.auth.handler: authentication successful, sending token to sinks983server # [ 12.684594] vault[1236]: 2026-07-29T05:12:46.819Z [INFO] agent.auth.handler: starting renewal process984server # [ 12.685809] vault[1236]: 2026-07-29T05:12:46.819Z [INFO] agent.template.server: template server received new token985server # [ 12.687136] vault[1236]: 2026-07-29T05:12:46.819Z [INFO] (runner) stopping986server # [ 12.688224] vault[1236]: 2026-07-29T05:12:46.819Z [INFO] (runner) creating new runner (dry: false, once: false)987server # [ 12.689504] vault[1236]: 2026-07-29T05:12:46.819Z [INFO] (runner) creating watcher988server # [ 12.690521] vault[1236]: 2026-07-29T05:12:46.819Z [INFO] (runner) starting989server # [ 12.691446] vault[1236]: 2026-07-29T05:12:46.823Z [INFO] agent.auth.handler: renewed auth token990server # [ 12.695640] vault[1236]: 2026-07-29T05:12:46.844Z [INFO] (runner) rendered "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"991server # [ 12.697748] systemd-vaultd[792]: 2026/07/29 05:12:46 Detected added file: 461759218992server # [ 12.698808] systemd-vaultd[792]: 2026/07/29 05:12:46 Detected added file: service2.service.json993server # [ 12.699963] systemd-vaultd[792]: 2026/07/29 05:12:46 Ignore unknown file: 461759218994server # [ 12.701087] systemd-vaultd[792]: 2026/07/29 05:12:46 Ignore unknown file: service2.service.json995server # [ 12.702295] vault[1236]: 2026-07-29T05:12:46.849Z [INFO] (runner) executing command "[\"systemctl try-reload-or-restart 'service2.service'\"]" from "(dynamic)" => "/run/systemd-vaultd/secrets/service2.service.json"996server # [ 12.704955] vault[1236]: 2026-07-29T05:12:46.853Z [INFO] (child) spawning: /bin/sh -c systemctl try-reload-or-restart 'service2.service'997server # [ 12.714734] service2-start[1177]: + true998server # [ 12.715400] service2-start[1177]: + cat /run/service2/secrets/secret999server # [ 12.722312] service2-start[1256]: bar1000server # [ 12.722945] service2-start[1177]: + cat /run/service2/secrets/secret1001server # [ 12.729430] service2-start[1177]: + sleep 0.11002server # [ 12.808134] systemd[1]: Finished Virtual Console Setup.1003server # [ 12.836761] service2-start[1177]: + true1004server # [ 12.838631] service2-start[1177]: + cat /run/service2/secrets/secret1005server # [ 12.844280] service2-start[1261]: bar1006server # [ 12.846036] service2-start[1177]: + cat /run/service2/secrets/secret1007server # [ 12.852248] service2-start[1177]: + sleep 0.11008server # [ 12.960865] service2-start[1177]: + true1009server # [ 12.962831] service2-start[1177]: + cat /run/service2/secrets/secret1010server # [ 12.968703] service2-start[1264]: bar1011server # [ 12.970840] service2-start[1177]: + cat /run/service2/secrets/secret1012server # [ 12.977266] service2-start[1177]: + sleep 0.11013server # [ 12.997487] systemd[1]: Reloading service2.service...1014server # [ 13.007254] systemd-vaultd-update-secrets[1268]: 2026/07/29 05:12:47 waiting for /run/systemd-vaultd/secrets/service2.service.json to be updated1015server # [ 13.086358] service2-start[1177]: + true1016server # [ 13.088088] service2-start[1177]: + cat /run/service2/secrets/secret1017server # [ 13.097970] service2-start[1273]: reload1018server # [ 13.099626] service2-start[1177]: + cat /run/service2/secrets/secret1019server # [ 13.109973] service2-start[1177]: + sleep 0.11020server # [ 13.219649] service2-start[1177]: + true1021server # [ 13.221411] service2-start[1177]: + cat /run/service2/secrets/secret1022server # [ 13.231172] service2-start[1276]: reload1023server # [ 13.232928] service2-start[1177]: + cat /run/service2/secrets/secret1024server # [ 13.242601] service2-start[1177]: + sleep 0.11025server # [ 13.352456] service2-start[1177]: + true1026server # [ 13.354178] service2-start[1177]: + cat /run/service2/secrets/secret1027server # [ 13.363935] service2-start[1279]: reload1028server # [ 13.365699] service2-start[1177]: + cat /run/service2/secrets/secret1029server # [ 13.375675] service2-start[1177]: + sleep 0.11030server # [ 13.485424] service2-start[1177]: + true1031server # [ 13.487094] service2-start[1177]: + cat /run/service2/secrets/secret1032server # [ 13.496870] service2-start[1282]: reload1033server # [ 13.498510] service2-start[1177]: + cat /run/service2/secrets/secret1034server # [ 13.508485] service2-start[1177]: + sleep 0.11035server # [ 13.617951] service2-start[1177]: + true1036server # [ 13.619829] service2-start[1177]: + cat /run/service2/secrets/secret1037server # [ 13.629675] service2-start[1289]: reload1038server # [ 13.631406] service2-start[1177]: + cat /run/service2/secrets/secret1039server # [ 13.641714] service2-start[1177]: + sleep 0.11040server # 1041server # {"secret":"reload"}1042(finished: waiting for success: cat /run/systemd-vaultd/secrets/service2.service.json >&2, in 1.12 seconds)1043server: must succeed: systemctl restart service21044server # [ 13.724003] systemd[1]: service2.service: Control process exited, code=killed, status=15/TERM1045server # [ 13.728251] systemd[1]: service2.service: Failed with result 'signal'.1046server # [ 13.732209] systemd[1]: Stopped service2.service.1047server # [ 13.749490] systemd[1]: Starting service2.service...1048server # [ 13.756571] systemd-vaultd[792]: 2026/07/29 05:12:47 Systemd requested secret for service2.service/secret1049server # [ 13.785932] systemd[1]: Started service2.service.1050server # [ 13.795752] systemd[1]: Reached target Multi-User System.1051(finished: must succeed: systemctl restart service2, in 0.13 seconds)1052server: must succeed: rm /tmp/service21053server # [ 13.799334] systemd[1]: Startup finished in 3.022s (kernel) + 10.776s (userspace) = 13.799s.1054server # [ 13.804979] systemd-vaultd[792]: 2026/07/29 05:12:47 Systemd requested secret for service2.service/secret1055server # [ 13.825059] service2-start[1303]: + true1056server # [ 13.827303] service2-start[1303]: + cat /run/service2/secrets/secret1057server # [ 13.834374] service2-start[1308]: reload1058server # [ 13.836866] service2-start[1303]: + cat /run/service2/secrets/secret1059(finished: must succeed: rm /tmp/service2, in 0.04 seconds)1060server: waiting for success: grep -q reload /tmp/service2 >&21061server # [ 13.842587] service2-start[1303]: + sleep 0.11062server # grep: /tmp/service2: No such file or directory1063server # [ 13.951573] service2-start[1303]: + true1064server # [ 13.953718] service2-start[1303]: + cat /run/service2/secrets/secret1065server # [ 13.960306] service2-start[1316]: reload1066server # [ 13.962290] service2-start[1303]: + cat /run/service2/secrets/secret1067server # [ 13.968330] service2-start[1303]: + sleep 0.11068server # [ 14.076395] service2-start[1303]: + true1069server # [ 14.078240] service2-start[1303]: + cat /run/service2/secrets/secret1070server # [ 14.084902] service2-start[1319]: reload1071server # [ 14.086823] service2-start[1303]: + cat /run/service2/secrets/secret1072server # [ 14.092898] service2-start[1303]: + sleep 0.11073server # [ 14.201401] service2-start[1303]: + true1074server # [ 14.203278] service2-start[1303]: + cat /run/service2/secrets/secret1075server # [ 14.209432] service2-start[1322]: reload1076server # [ 14.211233] service2-start[1303]: + cat /run/service2/secrets/secret1077server # [ 14.218054] service2-start[1303]: + sleep 0.11078server # [ 14.326021] service2-start[1303]: + true1079server # [ 14.327862] service2-start[1303]: + cat /run/service2/secrets/secret1080server # [ 14.334640] service2-start[1325]: reload1081server # [ 14.336720] service2-start[1303]: + cat /run/service2/secrets/secret1082server # [ 14.342902] service2-start[1303]: + sleep 0.11083server # [ 14.451618] service2-start[1303]: + true1084server # [ 14.453643] service2-start[1303]: + cat /run/service2/secrets/secret1085server # [ 14.459734] service2-start[1328]: reload1086server # [ 14.461722] service2-start[1303]: + cat /run/service2/secrets/secret1087server # [ 14.468196] service2-start[1303]: + sleep 0.11088server # [ 14.576407] service2-start[1303]: + true1089server # [ 14.578385] service2-start[1303]: + cat /run/service2/secrets/secret1090server # [ 14.584393] service2-start[1331]: reload1091server # [ 14.586158] service2-start[1303]: + cat /run/service2/secrets/secret1092server # [ 14.592829] service2-start[1303]: + sleep 0.11093server # [ 14.700826] service2-start[1303]: + true1094server # [ 14.702674] service2-start[1303]: + cat /run/service2/secrets/secret1095server # [ 14.709276] service2-start[1334]: reload1096server # [ 14.711185] service2-start[1303]: + cat /run/service2/secrets/secret1097server # [ 14.717310] service2-start[1303]: + sleep 0.11098server # [ 14.826133] service2-start[1303]: + true1099server # [ 14.828114] service2-start[1303]: + cat /run/service2/secrets/secret1100server # [ 14.833934] service2-start[1337]: reload1101server # [ 14.835797] service2-start[1303]: + cat /run/service2/secrets/secret1102server # [ 14.842231] service2-start[1303]: + sleep 0.11103(finished: waiting for success: grep -q reload /tmp/service2 >&2, in 1.10 seconds)1104server: must succeed: stat -c %u /run/service2/secrets/secret1105server # [ 14.950066] service2-start[1303]: + true1106server # [ 14.952794] service2-start[1303]: + cat /run/service2/secrets/secret1107server # [ 14.958180] service2-start[1348]: reload1108server # [ 14.960595] service2-start[1303]: + cat /run/service2/secrets/secret1109server # [ 14.966827] service2-start[1303]: + sleep 0.11110(finished: must succeed: stat -c %u /run/service2/secrets/secret, in 0.04 seconds)1111server: must succeed: stat -c %g /run/service2/secrets/secret1112(finished: must succeed: stat -c %g /run/service2/secrets/secret, in 0.03 seconds)1113server: must succeed: stat -c %a /run/service2/secrets/secret1114(finished: must succeed: stat -c %a /run/service2/secrets/secret, in 0.03 seconds)1115(finished: run the VM test script, in 15.58 seconds)1116server # [ 15.074047] service2-start[1303]: + true1117server # [ 15.076738] service2-start[1303]: + cat /run/service2/secrets/secret1118server # [ 15.082143] service2-start[1361]: reload1119server # [ 15.089893] service2-start[1303]: + cat /run/service2/secrets/secret1120server # [ 15.096666] service2-start[1303]: + sleep 0.11121test script finished in 15.65s1122cleanup1123kill machine (pid 6)1124server # qemu-system-aarch64: terminating on signal 15 from pid 4 (/nix/store/nxxghhhia9x1skpqfak4z407yy0mbnnd-python3-3.11.5/bin/python3.11)1125(finished: cleanup, in 0.01 seconds)1126additionally exposed symbols:1127 server,1128 vlan1,1129 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, Machine1130● service2.service1131 Loaded: loaded (/etc/systemd/system/service2.service; enabled; preset: enabled)1132 Active: activating (start-pre) since Wed 2026-07-29 05:12:39 UTC; 5s ago1133Cntrl PID: 782 ((re-start))1134 IP: 0B in, 0B out1135 IO: 0B read, 0B written1136 Tasks: 2 (limit: 1086)1137 Memory: 380.0K1138 CPU: 4ms1139 CGroup: /system.slice/service2.service1140 ├─782 "(re-start)"1141 └─788 "(sd-mkdcreds)"11421143Jul 29 05:12:39 server systemd[1]: Starting service2.service...11441145kill vlan (pid 5)