Sep 12 05:17:11.773494 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 12 05:17:11.773516 kernel: Linux version 6.12.46-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT Fri Sep 12 04:03:04 -00 2025 Sep 12 05:17:11.773526 kernel: KASLR enabled Sep 12 05:17:11.773532 kernel: efi: EFI v2.7 by EDK II Sep 12 05:17:11.773538 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Sep 12 05:17:11.773544 kernel: random: crng init done Sep 12 05:17:11.773551 kernel: secureboot: Secure boot disabled Sep 12 05:17:11.773557 kernel: ACPI: Early table checksum verification disabled Sep 12 05:17:11.773563 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Sep 12 05:17:11.773570 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 12 05:17:11.773576 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773583 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773588 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773595 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773602 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773610 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773616 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773623 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773629 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 05:17:11.773635 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 12 05:17:11.773642 kernel: ACPI: Use ACPI SPCR as default console: No Sep 12 05:17:11.773648 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 12 05:17:11.773655 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Sep 12 05:17:11.773661 kernel: Zone ranges: Sep 12 05:17:11.773667 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 12 05:17:11.773675 kernel: DMA32 empty Sep 12 05:17:11.773682 kernel: Normal empty Sep 12 05:17:11.773688 kernel: Device empty Sep 12 05:17:11.773694 kernel: Movable zone start for each node Sep 12 05:17:11.773700 kernel: Early memory node ranges Sep 12 05:17:11.773707 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Sep 12 05:17:11.773713 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Sep 12 05:17:11.773719 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Sep 12 05:17:11.773726 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Sep 12 05:17:11.773732 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Sep 12 05:17:11.773738 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Sep 12 05:17:11.773745 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Sep 12 05:17:11.773752 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Sep 12 05:17:11.773759 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Sep 12 05:17:11.773765 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 12 05:17:11.773774 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 12 05:17:11.773781 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 12 05:17:11.773788 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 12 05:17:11.773796 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 12 05:17:11.773810 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 12 05:17:11.773818 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Sep 12 05:17:11.773824 kernel: psci: probing for conduit method from ACPI. Sep 12 05:17:11.773831 kernel: psci: PSCIv1.1 detected in firmware. Sep 12 05:17:11.773838 kernel: psci: Using standard PSCI v0.2 function IDs Sep 12 05:17:11.773845 kernel: psci: Trusted OS migration not required Sep 12 05:17:11.773851 kernel: psci: SMC Calling Convention v1.1 Sep 12 05:17:11.773858 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 12 05:17:11.773865 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Sep 12 05:17:11.773874 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Sep 12 05:17:11.773881 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 12 05:17:11.773888 kernel: Detected PIPT I-cache on CPU0 Sep 12 05:17:11.773895 kernel: CPU features: detected: GIC system register CPU interface Sep 12 05:17:11.773901 kernel: CPU features: detected: Spectre-v4 Sep 12 05:17:11.773908 kernel: CPU features: detected: Spectre-BHB Sep 12 05:17:11.773915 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 12 05:17:11.773921 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 12 05:17:11.773928 kernel: CPU features: detected: ARM erratum 1418040 Sep 12 05:17:11.773935 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 12 05:17:11.773942 kernel: alternatives: applying boot alternatives Sep 12 05:17:11.773950 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=45b953e473daf02253cb2b9c2cab5c623bf7f40867b508b0e703cff6ccf15019 Sep 12 05:17:11.773958 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 05:17:11.773965 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 05:17:11.773972 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 05:17:11.773979 kernel: Fallback order for Node 0: 0 Sep 12 05:17:11.773986 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Sep 12 05:17:11.773992 kernel: Policy zone: DMA Sep 12 05:17:11.773999 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 05:17:11.774006 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Sep 12 05:17:11.774013 kernel: software IO TLB: area num 4. Sep 12 05:17:11.774019 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Sep 12 05:17:11.774026 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Sep 12 05:17:11.774034 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 12 05:17:11.774041 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 05:17:11.774049 kernel: rcu: RCU event tracing is enabled. Sep 12 05:17:11.774055 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 12 05:17:11.774062 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 05:17:11.774069 kernel: Tracing variant of Tasks RCU enabled. Sep 12 05:17:11.774076 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 05:17:11.774083 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 12 05:17:11.774090 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 05:17:11.774097 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 05:17:11.774104 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 12 05:17:11.774115 kernel: GICv3: 256 SPIs implemented Sep 12 05:17:11.774126 kernel: GICv3: 0 Extended SPIs implemented Sep 12 05:17:11.774133 kernel: Root IRQ handler: gic_handle_irq Sep 12 05:17:11.774142 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 12 05:17:11.774148 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Sep 12 05:17:11.774155 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 12 05:17:11.774162 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 12 05:17:11.774169 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Sep 12 05:17:11.774176 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Sep 12 05:17:11.774183 kernel: GICv3: using LPI property table @0x0000000040130000 Sep 12 05:17:11.774190 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Sep 12 05:17:11.774197 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 05:17:11.774206 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 05:17:11.774213 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 12 05:17:11.774220 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 12 05:17:11.774227 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 12 05:17:11.774234 kernel: arm-pv: using stolen time PV Sep 12 05:17:11.774241 kernel: Console: colour dummy device 80x25 Sep 12 05:17:11.774248 kernel: ACPI: Core revision 20240827 Sep 12 05:17:11.774255 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 12 05:17:11.774262 kernel: pid_max: default: 32768 minimum: 301 Sep 12 05:17:11.774269 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 12 05:17:11.774287 kernel: landlock: Up and running. Sep 12 05:17:11.774294 kernel: SELinux: Initializing. Sep 12 05:17:11.774301 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 05:17:11.774308 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 05:17:11.774315 kernel: rcu: Hierarchical SRCU implementation. Sep 12 05:17:11.774322 kernel: rcu: Max phase no-delay instances is 400. Sep 12 05:17:11.774329 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 12 05:17:11.774336 kernel: Remapping and enabling EFI services. Sep 12 05:17:11.774343 kernel: smp: Bringing up secondary CPUs ... Sep 12 05:17:11.774356 kernel: Detected PIPT I-cache on CPU1 Sep 12 05:17:11.774364 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 12 05:17:11.774371 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Sep 12 05:17:11.774380 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 05:17:11.774387 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 12 05:17:11.774394 kernel: Detected PIPT I-cache on CPU2 Sep 12 05:17:11.774402 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 12 05:17:11.774409 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Sep 12 05:17:11.774418 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 05:17:11.774425 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 12 05:17:11.774432 kernel: Detected PIPT I-cache on CPU3 Sep 12 05:17:11.774440 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 12 05:17:11.774447 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Sep 12 05:17:11.774454 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 05:17:11.774462 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 12 05:17:11.774469 kernel: smp: Brought up 1 node, 4 CPUs Sep 12 05:17:11.774476 kernel: SMP: Total of 4 processors activated. Sep 12 05:17:11.774485 kernel: CPU: All CPU(s) started at EL1 Sep 12 05:17:11.774493 kernel: CPU features: detected: 32-bit EL0 Support Sep 12 05:17:11.774500 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 12 05:17:11.774508 kernel: CPU features: detected: Common not Private translations Sep 12 05:17:11.774515 kernel: CPU features: detected: CRC32 instructions Sep 12 05:17:11.774522 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 12 05:17:11.774530 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 12 05:17:11.774537 kernel: CPU features: detected: LSE atomic instructions Sep 12 05:17:11.774545 kernel: CPU features: detected: Privileged Access Never Sep 12 05:17:11.774552 kernel: CPU features: detected: RAS Extension Support Sep 12 05:17:11.774561 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 12 05:17:11.774568 kernel: alternatives: applying system-wide alternatives Sep 12 05:17:11.774576 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Sep 12 05:17:11.774583 kernel: Memory: 2424480K/2572288K available (11136K kernel code, 2440K rwdata, 9068K rodata, 38976K init, 1038K bss, 125472K reserved, 16384K cma-reserved) Sep 12 05:17:11.774591 kernel: devtmpfs: initialized Sep 12 05:17:11.774598 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 05:17:11.774606 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 12 05:17:11.774614 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 12 05:17:11.774623 kernel: 0 pages in range for non-PLT usage Sep 12 05:17:11.774631 kernel: 508560 pages in range for PLT usage Sep 12 05:17:11.774638 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 05:17:11.774646 kernel: SMBIOS 3.0.0 present. Sep 12 05:17:11.774653 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Sep 12 05:17:11.774661 kernel: DMI: Memory slots populated: 1/1 Sep 12 05:17:11.774668 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 05:17:11.774676 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 12 05:17:11.774683 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 12 05:17:11.774692 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 12 05:17:11.774700 kernel: audit: initializing netlink subsys (disabled) Sep 12 05:17:11.774707 kernel: audit: type=2000 audit(0.021:1): state=initialized audit_enabled=0 res=1 Sep 12 05:17:11.774715 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 05:17:11.774723 kernel: cpuidle: using governor menu Sep 12 05:17:11.774730 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 12 05:17:11.774738 kernel: ASID allocator initialised with 32768 entries Sep 12 05:17:11.774746 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 05:17:11.774753 kernel: Serial: AMBA PL011 UART driver Sep 12 05:17:11.774762 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 05:17:11.774770 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 05:17:11.774777 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 12 05:17:11.774785 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 12 05:17:11.774792 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 05:17:11.774805 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 05:17:11.774812 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 12 05:17:11.774820 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 12 05:17:11.774828 kernel: ACPI: Added _OSI(Module Device) Sep 12 05:17:11.774837 kernel: ACPI: Added _OSI(Processor Device) Sep 12 05:17:11.774845 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 05:17:11.774853 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 05:17:11.774860 kernel: ACPI: Interpreter enabled Sep 12 05:17:11.774868 kernel: ACPI: Using GIC for interrupt routing Sep 12 05:17:11.774875 kernel: ACPI: MCFG table detected, 1 entries Sep 12 05:17:11.774882 kernel: ACPI: CPU0 has been hot-added Sep 12 05:17:11.774890 kernel: ACPI: CPU1 has been hot-added Sep 12 05:17:11.774898 kernel: ACPI: CPU2 has been hot-added Sep 12 05:17:11.774905 kernel: ACPI: CPU3 has been hot-added Sep 12 05:17:11.774914 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 12 05:17:11.774921 kernel: printk: legacy console [ttyAMA0] enabled Sep 12 05:17:11.774929 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 12 05:17:11.775094 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 12 05:17:11.775171 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 12 05:17:11.775235 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 12 05:17:11.775334 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 12 05:17:11.775402 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 12 05:17:11.775412 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 12 05:17:11.775420 kernel: PCI host bridge to bus 0000:00 Sep 12 05:17:11.775493 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 12 05:17:11.775550 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 12 05:17:11.775605 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 12 05:17:11.775659 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 12 05:17:11.775739 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Sep 12 05:17:11.775825 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 12 05:17:11.775892 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Sep 12 05:17:11.775955 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Sep 12 05:17:11.776017 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Sep 12 05:17:11.776084 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Sep 12 05:17:11.776149 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Sep 12 05:17:11.776217 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Sep 12 05:17:11.776275 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 12 05:17:11.776350 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 12 05:17:11.776408 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 12 05:17:11.776418 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 12 05:17:11.776426 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 12 05:17:11.776433 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 12 05:17:11.776443 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 12 05:17:11.776451 kernel: iommu: Default domain type: Translated Sep 12 05:17:11.776459 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 12 05:17:11.776466 kernel: efivars: Registered efivars operations Sep 12 05:17:11.776474 kernel: vgaarb: loaded Sep 12 05:17:11.776481 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 12 05:17:11.776489 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 05:17:11.776496 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 05:17:11.776504 kernel: pnp: PnP ACPI init Sep 12 05:17:11.776573 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 12 05:17:11.776584 kernel: pnp: PnP ACPI: found 1 devices Sep 12 05:17:11.776591 kernel: NET: Registered PF_INET protocol family Sep 12 05:17:11.776599 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 12 05:17:11.776606 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 12 05:17:11.776614 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 05:17:11.776621 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 12 05:17:11.776629 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 12 05:17:11.776638 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 12 05:17:11.776645 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 05:17:11.776653 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 05:17:11.776660 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 05:17:11.776668 kernel: PCI: CLS 0 bytes, default 64 Sep 12 05:17:11.776675 kernel: kvm [1]: HYP mode not available Sep 12 05:17:11.776682 kernel: Initialise system trusted keyrings Sep 12 05:17:11.776690 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 12 05:17:11.776697 kernel: Key type asymmetric registered Sep 12 05:17:11.776706 kernel: Asymmetric key parser 'x509' registered Sep 12 05:17:11.776713 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 12 05:17:11.776721 kernel: io scheduler mq-deadline registered Sep 12 05:17:11.776728 kernel: io scheduler kyber registered Sep 12 05:17:11.776735 kernel: io scheduler bfq registered Sep 12 05:17:11.776743 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 12 05:17:11.776750 kernel: ACPI: button: Power Button [PWRB] Sep 12 05:17:11.776758 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 12 05:17:11.776828 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 12 05:17:11.776841 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 05:17:11.776848 kernel: thunder_xcv, ver 1.0 Sep 12 05:17:11.776856 kernel: thunder_bgx, ver 1.0 Sep 12 05:17:11.776863 kernel: nicpf, ver 1.0 Sep 12 05:17:11.776871 kernel: nicvf, ver 1.0 Sep 12 05:17:11.776940 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 12 05:17:11.776998 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-12T05:17:11 UTC (1757654231) Sep 12 05:17:11.777008 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 12 05:17:11.777016 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Sep 12 05:17:11.777025 kernel: watchdog: NMI not fully supported Sep 12 05:17:11.777032 kernel: watchdog: Hard watchdog permanently disabled Sep 12 05:17:11.777040 kernel: NET: Registered PF_INET6 protocol family Sep 12 05:17:11.777047 kernel: Segment Routing with IPv6 Sep 12 05:17:11.777054 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 05:17:11.777062 kernel: NET: Registered PF_PACKET protocol family Sep 12 05:17:11.777069 kernel: Key type dns_resolver registered Sep 12 05:17:11.777077 kernel: registered taskstats version 1 Sep 12 05:17:11.777084 kernel: Loading compiled-in X.509 certificates Sep 12 05:17:11.777093 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: da262a434e4ee1dc4e1f5739600d0c653580deba' Sep 12 05:17:11.777101 kernel: Demotion targets for Node 0: null Sep 12 05:17:11.777108 kernel: Key type .fscrypt registered Sep 12 05:17:11.777115 kernel: Key type fscrypt-provisioning registered Sep 12 05:17:11.777123 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 05:17:11.777130 kernel: ima: Allocated hash algorithm: sha1 Sep 12 05:17:11.777137 kernel: ima: No architecture policies found Sep 12 05:17:11.777145 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 12 05:17:11.777153 kernel: clk: Disabling unused clocks Sep 12 05:17:11.777161 kernel: PM: genpd: Disabling unused power domains Sep 12 05:17:11.777168 kernel: Warning: unable to open an initial console. Sep 12 05:17:11.777176 kernel: Freeing unused kernel memory: 38976K Sep 12 05:17:11.777183 kernel: Run /init as init process Sep 12 05:17:11.777190 kernel: with arguments: Sep 12 05:17:11.777198 kernel: /init Sep 12 05:17:11.777205 kernel: with environment: Sep 12 05:17:11.777212 kernel: HOME=/ Sep 12 05:17:11.777220 kernel: TERM=linux Sep 12 05:17:11.777228 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 05:17:11.777236 systemd[1]: Successfully made /usr/ read-only. Sep 12 05:17:11.777246 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 05:17:11.777255 systemd[1]: Detected virtualization kvm. Sep 12 05:17:11.777262 systemd[1]: Detected architecture arm64. Sep 12 05:17:11.777270 systemd[1]: Running in initrd. Sep 12 05:17:11.777286 systemd[1]: No hostname configured, using default hostname. Sep 12 05:17:11.777297 systemd[1]: Hostname set to . Sep 12 05:17:11.777304 systemd[1]: Initializing machine ID from VM UUID. Sep 12 05:17:11.777312 systemd[1]: Queued start job for default target initrd.target. Sep 12 05:17:11.777330 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 05:17:11.777339 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 05:17:11.777347 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 05:17:11.777355 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 05:17:11.777363 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 05:17:11.777374 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 05:17:11.777383 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 12 05:17:11.777391 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 05:17:11.777399 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 05:17:11.777407 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 05:17:11.777415 systemd[1]: Reached target paths.target - Path Units. Sep 12 05:17:11.777423 systemd[1]: Reached target slices.target - Slice Units. Sep 12 05:17:11.777432 systemd[1]: Reached target swap.target - Swaps. Sep 12 05:17:11.777440 systemd[1]: Reached target timers.target - Timer Units. Sep 12 05:17:11.777447 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 05:17:11.777456 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 05:17:11.777463 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 05:17:11.777471 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 05:17:11.777479 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 05:17:11.777487 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 05:17:11.777496 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 05:17:11.777504 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 05:17:11.777512 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 05:17:11.777520 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 05:17:11.777528 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 05:17:11.777536 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 12 05:17:11.777544 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 05:17:11.777552 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 05:17:11.777560 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 05:17:11.777569 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 05:17:11.777577 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 05:17:11.777585 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 05:17:11.777593 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 05:17:11.777618 systemd-journald[244]: Collecting audit messages is disabled. Sep 12 05:17:11.777637 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 05:17:11.777646 systemd-journald[244]: Journal started Sep 12 05:17:11.777666 systemd-journald[244]: Runtime Journal (/run/log/journal/6ada1d33c60941e88294ad1dc8fbb916) is 6M, max 48.5M, 42.4M free. Sep 12 05:17:11.772341 systemd-modules-load[247]: Inserted module 'overlay' Sep 12 05:17:11.781074 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 05:17:11.783092 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 05:17:11.785076 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 05:17:11.790393 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 12 05:17:11.790413 kernel: Bridge firewalling registered Sep 12 05:17:11.786772 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 05:17:11.788126 systemd-modules-load[247]: Inserted module 'br_netfilter' Sep 12 05:17:11.792295 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 05:17:11.800892 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 05:17:11.802321 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 05:17:11.805137 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 05:17:11.806716 systemd-tmpfiles[269]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 12 05:17:11.809545 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 05:17:11.815998 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 05:17:11.817370 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 05:17:11.822981 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 05:17:11.827349 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 05:17:11.831826 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 05:17:11.855382 systemd-resolved[291]: Positive Trust Anchors: Sep 12 05:17:11.855400 systemd-resolved[291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 05:17:11.855430 systemd-resolved[291]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 12 05:17:11.864693 dracut-cmdline[293]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=45b953e473daf02253cb2b9c2cab5c623bf7f40867b508b0e703cff6ccf15019 Sep 12 05:17:11.860063 systemd-resolved[291]: Defaulting to hostname 'linux'. Sep 12 05:17:11.860994 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 05:17:11.865412 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 05:17:11.926299 kernel: SCSI subsystem initialized Sep 12 05:17:11.930333 kernel: Loading iSCSI transport class v2.0-870. Sep 12 05:17:11.938344 kernel: iscsi: registered transport (tcp) Sep 12 05:17:11.950571 kernel: iscsi: registered transport (qla4xxx) Sep 12 05:17:11.950610 kernel: QLogic iSCSI HBA Driver Sep 12 05:17:11.967346 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 05:17:11.985681 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 05:17:11.987213 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 05:17:12.033350 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 05:17:12.035663 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 05:17:12.096324 kernel: raid6: neonx8 gen() 15708 MB/s Sep 12 05:17:12.113314 kernel: raid6: neonx4 gen() 15728 MB/s Sep 12 05:17:12.130324 kernel: raid6: neonx2 gen() 13148 MB/s Sep 12 05:17:12.147325 kernel: raid6: neonx1 gen() 10403 MB/s Sep 12 05:17:12.164322 kernel: raid6: int64x8 gen() 6859 MB/s Sep 12 05:17:12.181308 kernel: raid6: int64x4 gen() 7325 MB/s Sep 12 05:17:12.198303 kernel: raid6: int64x2 gen() 6060 MB/s Sep 12 05:17:12.215632 kernel: raid6: int64x1 gen() 5017 MB/s Sep 12 05:17:12.215649 kernel: raid6: using algorithm neonx4 gen() 15728 MB/s Sep 12 05:17:12.233386 kernel: raid6: .... xor() 12335 MB/s, rmw enabled Sep 12 05:17:12.233433 kernel: raid6: using neon recovery algorithm Sep 12 05:17:12.239632 kernel: xor: measuring software checksum speed Sep 12 05:17:12.239649 kernel: 8regs : 21641 MB/sec Sep 12 05:17:12.239659 kernel: 32regs : 21681 MB/sec Sep 12 05:17:12.240956 kernel: arm64_neon : 27993 MB/sec Sep 12 05:17:12.240972 kernel: xor: using function: arm64_neon (27993 MB/sec) Sep 12 05:17:12.292320 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 05:17:12.298737 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 05:17:12.301214 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 05:17:12.326612 systemd-udevd[500]: Using default interface naming scheme 'v255'. Sep 12 05:17:12.330596 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 05:17:12.332864 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 05:17:12.355026 dracut-pre-trigger[509]: rd.md=0: removing MD RAID activation Sep 12 05:17:12.376314 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 05:17:12.378518 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 05:17:12.432199 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 05:17:12.435345 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 05:17:12.489471 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 05:17:12.493233 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 12 05:17:12.493394 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 12 05:17:12.489584 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 05:17:12.491900 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 05:17:12.496726 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 05:17:12.502292 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 05:17:12.523296 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 05:17:12.532465 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 12 05:17:12.538203 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 05:17:12.546600 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 12 05:17:12.554374 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 05:17:12.560501 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 12 05:17:12.561697 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 12 05:17:12.564035 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 05:17:12.567015 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 05:17:12.569141 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 05:17:12.571894 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 05:17:12.573778 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 05:17:12.600771 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 05:17:12.603302 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 05:17:13.610309 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 05:17:13.610780 disk-uuid[596]: The operation has completed successfully. Sep 12 05:17:13.631343 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 05:17:13.631443 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 05:17:13.659038 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 05:17:13.672025 sh[613]: Success Sep 12 05:17:13.684361 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 12 05:17:13.684411 kernel: device-mapper: uevent: version 1.0.3 Sep 12 05:17:13.686310 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 12 05:17:13.692340 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Sep 12 05:17:13.711784 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 05:17:13.722551 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 05:17:13.726225 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 05:17:13.733299 kernel: BTRFS: device fsid 3c021d26-fa3e-49e5-9459-cfb0e52bdec0 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (625) Sep 12 05:17:13.735732 kernel: BTRFS info (device dm-0): first mount of filesystem 3c021d26-fa3e-49e5-9459-cfb0e52bdec0 Sep 12 05:17:13.735760 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 12 05:17:13.739646 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 05:17:13.739678 kernel: BTRFS info (device dm-0): enabling free space tree Sep 12 05:17:13.740653 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 05:17:13.741868 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 12 05:17:13.743366 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 12 05:17:13.744043 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 05:17:13.745771 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 05:17:13.767197 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (656) Sep 12 05:17:13.767227 kernel: BTRFS info (device vda6): first mount of filesystem e943e8a3-9c93-44b7-93d2-9a319fc1b485 Sep 12 05:17:13.767238 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 12 05:17:13.770648 kernel: BTRFS info (device vda6): turning on async discard Sep 12 05:17:13.770684 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 05:17:13.775295 kernel: BTRFS info (device vda6): last unmount of filesystem e943e8a3-9c93-44b7-93d2-9a319fc1b485 Sep 12 05:17:13.775980 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 05:17:13.778000 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 05:17:13.844227 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 05:17:13.847800 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 05:17:13.878831 ignition[700]: Ignition 2.22.0 Sep 12 05:17:13.878848 ignition[700]: Stage: fetch-offline Sep 12 05:17:13.878877 ignition[700]: no configs at "/usr/lib/ignition/base.d" Sep 12 05:17:13.878884 ignition[700]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 05:17:13.878958 ignition[700]: parsed url from cmdline: "" Sep 12 05:17:13.878961 ignition[700]: no config URL provided Sep 12 05:17:13.878965 ignition[700]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 05:17:13.883676 systemd-networkd[808]: lo: Link UP Sep 12 05:17:13.878972 ignition[700]: no config at "/usr/lib/ignition/user.ign" Sep 12 05:17:13.883679 systemd-networkd[808]: lo: Gained carrier Sep 12 05:17:13.879007 ignition[700]: op(1): [started] loading QEMU firmware config module Sep 12 05:17:13.884411 systemd-networkd[808]: Enumeration completed Sep 12 05:17:13.879012 ignition[700]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 12 05:17:13.884734 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 05:17:13.884814 systemd-networkd[808]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 05:17:13.892497 ignition[700]: op(1): [finished] loading QEMU firmware config module Sep 12 05:17:13.884818 systemd-networkd[808]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 05:17:13.885577 systemd-networkd[808]: eth0: Link UP Sep 12 05:17:13.885670 systemd-networkd[808]: eth0: Gained carrier Sep 12 05:17:13.885679 systemd-networkd[808]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 05:17:13.886375 systemd[1]: Reached target network.target - Network. Sep 12 05:17:13.900638 ignition[700]: parsing config with SHA512: 0d9a475d80ecb3de85fb880ff7c9d42021082c18a68fe5f4abbdd1e171bf6a3dfc35400180b85f549daad916cfc3d0710f10f0ddcbaadb65d0297e18d6eee981 Sep 12 05:17:13.905323 systemd-networkd[808]: eth0: DHCPv4 address 10.0.0.33/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 05:17:13.906424 unknown[700]: fetched base config from "system" Sep 12 05:17:13.906581 ignition[700]: fetch-offline: fetch-offline passed Sep 12 05:17:13.906431 unknown[700]: fetched user config from "qemu" Sep 12 05:17:13.906641 ignition[700]: Ignition finished successfully Sep 12 05:17:13.910965 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 05:17:13.912401 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 12 05:17:13.913164 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 05:17:13.942406 ignition[816]: Ignition 2.22.0 Sep 12 05:17:13.942426 ignition[816]: Stage: kargs Sep 12 05:17:13.942556 ignition[816]: no configs at "/usr/lib/ignition/base.d" Sep 12 05:17:13.942564 ignition[816]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 05:17:13.945170 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 05:17:13.943065 ignition[816]: kargs: kargs passed Sep 12 05:17:13.943103 ignition[816]: Ignition finished successfully Sep 12 05:17:13.947601 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 05:17:13.970875 ignition[824]: Ignition 2.22.0 Sep 12 05:17:13.970892 ignition[824]: Stage: disks Sep 12 05:17:13.971018 ignition[824]: no configs at "/usr/lib/ignition/base.d" Sep 12 05:17:13.971026 ignition[824]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 05:17:13.973682 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 05:17:13.971498 ignition[824]: disks: disks passed Sep 12 05:17:13.975617 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 05:17:13.971537 ignition[824]: Ignition finished successfully Sep 12 05:17:13.977306 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 05:17:13.978973 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 05:17:13.980847 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 05:17:13.982344 systemd[1]: Reached target basic.target - Basic System. Sep 12 05:17:13.985036 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 05:17:14.018223 systemd-fsck[834]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 12 05:17:14.022582 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 05:17:14.026663 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 05:17:14.083308 kernel: EXT4-fs (vda9): mounted filesystem f6521601-1b03-4664-9778-bd6b168e0b78 r/w with ordered data mode. Quota mode: none. Sep 12 05:17:14.083893 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 05:17:14.085105 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 05:17:14.087488 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 05:17:14.089107 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 05:17:14.090170 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 12 05:17:14.090209 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 12 05:17:14.090231 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 05:17:14.102818 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 05:17:14.105151 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 05:17:14.110256 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (842) Sep 12 05:17:14.110276 kernel: BTRFS info (device vda6): first mount of filesystem e943e8a3-9c93-44b7-93d2-9a319fc1b485 Sep 12 05:17:14.110304 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 12 05:17:14.112298 kernel: BTRFS info (device vda6): turning on async discard Sep 12 05:17:14.112317 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 05:17:14.113141 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 05:17:14.150113 initrd-setup-root[869]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 05:17:14.154364 initrd-setup-root[876]: cut: /sysroot/etc/group: No such file or directory Sep 12 05:17:14.157230 initrd-setup-root[883]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 05:17:14.160131 initrd-setup-root[890]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 05:17:14.223850 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 05:17:14.225863 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 05:17:14.227462 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 05:17:14.247311 kernel: BTRFS info (device vda6): last unmount of filesystem e943e8a3-9c93-44b7-93d2-9a319fc1b485 Sep 12 05:17:14.261404 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 05:17:14.275909 ignition[959]: INFO : Ignition 2.22.0 Sep 12 05:17:14.275909 ignition[959]: INFO : Stage: mount Sep 12 05:17:14.278028 ignition[959]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 05:17:14.278028 ignition[959]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 05:17:14.278028 ignition[959]: INFO : mount: mount passed Sep 12 05:17:14.278028 ignition[959]: INFO : Ignition finished successfully Sep 12 05:17:14.278510 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 05:17:14.281020 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 05:17:14.863087 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 05:17:14.866580 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 05:17:14.893994 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (971) Sep 12 05:17:14.894028 kernel: BTRFS info (device vda6): first mount of filesystem e943e8a3-9c93-44b7-93d2-9a319fc1b485 Sep 12 05:17:14.894039 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 12 05:17:14.897512 kernel: BTRFS info (device vda6): turning on async discard Sep 12 05:17:14.897539 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 05:17:14.899053 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 05:17:14.930626 ignition[988]: INFO : Ignition 2.22.0 Sep 12 05:17:14.930626 ignition[988]: INFO : Stage: files Sep 12 05:17:14.932496 ignition[988]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 05:17:14.932496 ignition[988]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 05:17:14.932496 ignition[988]: DEBUG : files: compiled without relabeling support, skipping Sep 12 05:17:14.932496 ignition[988]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 05:17:14.932496 ignition[988]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 05:17:14.939639 ignition[988]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 05:17:14.939639 ignition[988]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 05:17:14.939639 ignition[988]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 05:17:14.939639 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 05:17:14.939639 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 05:17:14.939639 ignition[988]: INFO : files: op(4): [started] processing unit "docker.service" Sep 12 05:17:14.939639 ignition[988]: INFO : files: op(4): [finished] processing unit "docker.service" Sep 12 05:17:14.939639 ignition[988]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Sep 12 05:17:14.939639 ignition[988]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 05:17:14.934331 unknown[988]: wrote ssh authorized keys file for user: core Sep 12 05:17:14.956265 ignition[988]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 05:17:14.956265 ignition[988]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Sep 12 05:17:14.956265 ignition[988]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Sep 12 05:17:14.956265 ignition[988]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 05:17:14.963702 ignition[988]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 05:17:14.963702 ignition[988]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Sep 12 05:17:14.963702 ignition[988]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Sep 12 05:17:14.963702 ignition[988]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Sep 12 05:17:14.963702 ignition[988]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 05:17:14.963702 ignition[988]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 05:17:14.963702 ignition[988]: INFO : files: files passed Sep 12 05:17:14.963702 ignition[988]: INFO : Ignition finished successfully Sep 12 05:17:14.960151 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 05:17:14.963162 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 05:17:14.965344 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 05:17:14.977136 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 05:17:14.981725 initrd-setup-root-after-ignition[1016]: grep: /sysroot/oem/oem-release: No such file or directory Sep 12 05:17:14.977214 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 05:17:14.985222 initrd-setup-root-after-ignition[1018]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 05:17:14.985222 initrd-setup-root-after-ignition[1018]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 05:17:14.982363 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 05:17:14.991493 initrd-setup-root-after-ignition[1023]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 05:17:14.984431 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 05:17:14.986888 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 05:17:15.018084 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 05:17:15.018222 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 05:17:15.020477 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 05:17:15.022395 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 05:17:15.024184 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 05:17:15.024954 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 05:17:15.051303 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 05:17:15.053935 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 05:17:15.075554 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 05:17:15.076841 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 05:17:15.078938 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 05:17:15.080836 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 05:17:15.080948 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 05:17:15.083530 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 05:17:15.085541 systemd[1]: Stopped target basic.target - Basic System. Sep 12 05:17:15.087317 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 05:17:15.089181 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 05:17:15.091275 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 05:17:15.093434 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 12 05:17:15.095535 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 05:17:15.097452 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 05:17:15.099446 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 05:17:15.101395 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 05:17:15.103228 systemd[1]: Stopped target swap.target - Swaps. Sep 12 05:17:15.104946 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 05:17:15.105074 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 05:17:15.107414 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 05:17:15.109427 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 05:17:15.111499 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 05:17:15.111603 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 05:17:15.113642 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 05:17:15.113758 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 05:17:15.116745 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 05:17:15.116879 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 05:17:15.118986 systemd[1]: Stopped target paths.target - Path Units. Sep 12 05:17:15.120691 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 05:17:15.121356 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 05:17:15.122852 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 05:17:15.124772 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 05:17:15.126401 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 05:17:15.126484 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 05:17:15.128356 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 05:17:15.128438 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 05:17:15.130803 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 05:17:15.130918 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 05:17:15.132789 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 05:17:15.132896 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 05:17:15.135366 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 05:17:15.137155 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 05:17:15.137298 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 05:17:15.158887 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 05:17:15.159830 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 05:17:15.159958 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 05:17:15.161908 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 05:17:15.162013 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 05:17:15.167471 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 05:17:15.167552 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 05:17:15.171984 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 05:17:15.174834 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 05:17:15.174922 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 05:17:15.177958 ignition[1043]: INFO : Ignition 2.22.0 Sep 12 05:17:15.177958 ignition[1043]: INFO : Stage: umount Sep 12 05:17:15.177958 ignition[1043]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 05:17:15.177958 ignition[1043]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 05:17:15.177958 ignition[1043]: INFO : umount: umount passed Sep 12 05:17:15.177958 ignition[1043]: INFO : Ignition finished successfully Sep 12 05:17:15.179153 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 05:17:15.179238 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 05:17:15.182229 systemd[1]: Stopped target network.target - Network. Sep 12 05:17:15.183999 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 05:17:15.184061 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 05:17:15.186073 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 05:17:15.186118 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 05:17:15.187660 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 05:17:15.187709 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 05:17:15.189246 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 05:17:15.189316 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 05:17:15.191071 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 05:17:15.191121 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 05:17:15.193016 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 05:17:15.194575 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 05:17:15.206831 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 05:17:15.206973 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 05:17:15.210154 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 05:17:15.210432 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 05:17:15.210470 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 05:17:15.214132 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 05:17:15.217805 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 05:17:15.217915 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 05:17:15.220924 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 12 05:17:15.222061 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 05:17:15.222092 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 05:17:15.224722 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 05:17:15.225767 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 05:17:15.225828 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 05:17:15.227915 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 05:17:15.227957 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 05:17:15.230570 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 05:17:15.230613 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 05:17:15.232925 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 05:17:15.242475 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 05:17:15.242603 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 05:17:15.244643 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 05:17:15.244678 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 05:17:15.246594 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 05:17:15.246623 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 05:17:15.248395 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 05:17:15.248441 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 05:17:15.251181 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 05:17:15.251227 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 05:17:15.254032 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 05:17:15.254084 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 05:17:15.257542 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 05:17:15.259061 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 12 05:17:15.259116 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 05:17:15.262342 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 05:17:15.262386 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 05:17:15.265430 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 05:17:15.265472 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 05:17:15.268446 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 05:17:15.268548 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 05:17:15.269994 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 05:17:15.271310 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 05:17:15.273681 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 05:17:15.275668 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 05:17:15.299561 systemd[1]: Switching root. Sep 12 05:17:15.331809 systemd-journald[244]: Journal stopped Sep 12 05:17:15.998445 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Sep 12 05:17:15.998494 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 05:17:15.998512 kernel: SELinux: policy capability open_perms=1 Sep 12 05:17:15.998522 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 05:17:15.998531 kernel: SELinux: policy capability always_check_network=0 Sep 12 05:17:15.998540 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 05:17:15.998552 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 05:17:15.998564 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 05:17:15.998578 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 05:17:15.998591 kernel: SELinux: policy capability userspace_initial_context=0 Sep 12 05:17:15.998602 kernel: audit: type=1403 audit(1757654235.419:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 05:17:15.998616 systemd[1]: Successfully loaded SELinux policy in 57.180ms. Sep 12 05:17:15.998632 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.349ms. Sep 12 05:17:15.998644 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 05:17:15.998655 systemd[1]: Detected virtualization kvm. Sep 12 05:17:15.998665 systemd[1]: Detected architecture arm64. Sep 12 05:17:15.998676 systemd[1]: Detected first boot. Sep 12 05:17:15.998686 systemd[1]: Initializing machine ID from VM UUID. Sep 12 05:17:15.998697 zram_generator::config[1090]: No configuration found. Sep 12 05:17:15.998708 kernel: NET: Registered PF_VSOCK protocol family Sep 12 05:17:15.998717 systemd[1]: Populated /etc with preset unit settings. Sep 12 05:17:15.998727 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 05:17:15.998739 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 05:17:15.998749 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 05:17:15.998759 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 05:17:15.998770 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 05:17:15.998791 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 05:17:15.998804 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 05:17:15.998814 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 05:17:15.998824 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 05:17:15.998836 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 05:17:15.998846 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 05:17:15.998855 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 05:17:15.998865 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 05:17:15.998875 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 05:17:15.998886 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 05:17:15.998898 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 05:17:15.998909 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 05:17:15.998918 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 05:17:15.998928 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 12 05:17:15.998938 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 05:17:15.998948 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 05:17:15.998958 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 05:17:15.998970 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 05:17:15.998980 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 05:17:15.998990 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 05:17:15.999000 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 05:17:15.999010 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 05:17:15.999020 systemd[1]: Reached target slices.target - Slice Units. Sep 12 05:17:15.999030 systemd[1]: Reached target swap.target - Swaps. Sep 12 05:17:15.999040 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 05:17:15.999050 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 05:17:15.999061 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 05:17:15.999071 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 05:17:15.999080 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 05:17:15.999090 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 05:17:15.999100 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 05:17:15.999110 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 05:17:15.999120 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 05:17:15.999130 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 05:17:15.999139 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 05:17:15.999154 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 05:17:15.999164 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 05:17:15.999175 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 12 05:17:15.999185 systemd[1]: Reached target machines.target - Containers. Sep 12 05:17:15.999195 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 05:17:15.999205 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 05:17:15.999215 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 05:17:15.999225 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 05:17:15.999235 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 05:17:15.999246 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 05:17:15.999256 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 05:17:15.999266 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 05:17:15.999283 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 05:17:15.999294 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 12 05:17:15.999304 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 05:17:15.999314 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 05:17:15.999324 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 05:17:15.999335 kernel: fuse: init (API version 7.41) Sep 12 05:17:15.999345 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 05:17:15.999356 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 05:17:15.999366 kernel: ACPI: bus type drm_connector registered Sep 12 05:17:15.999376 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 05:17:15.999385 kernel: loop: module loaded Sep 12 05:17:15.999395 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 05:17:15.999405 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 05:17:15.999415 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 05:17:15.999426 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 05:17:15.999436 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 05:17:15.999446 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 05:17:15.999455 systemd[1]: Stopped verity-setup.service. Sep 12 05:17:15.999465 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 05:17:15.999476 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 05:17:15.999486 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 05:17:15.999515 systemd-journald[1165]: Collecting audit messages is disabled. Sep 12 05:17:15.999537 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 05:17:15.999547 systemd-journald[1165]: Journal started Sep 12 05:17:15.999570 systemd-journald[1165]: Runtime Journal (/run/log/journal/6ada1d33c60941e88294ad1dc8fbb916) is 6M, max 48.5M, 42.4M free. Sep 12 05:17:15.759161 systemd[1]: Queued start job for default target multi-user.target. Sep 12 05:17:15.779326 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 12 05:17:15.779675 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 05:17:16.001545 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 05:17:16.002197 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 05:17:16.003564 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 05:17:16.006394 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 05:17:16.007911 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 05:17:16.009581 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 05:17:16.009762 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 05:17:16.011318 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 05:17:16.011480 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 05:17:16.012971 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 05:17:16.013139 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 05:17:16.014716 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 05:17:16.014876 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 05:17:16.016475 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 05:17:16.016642 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 05:17:16.018204 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 05:17:16.018394 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 05:17:16.019834 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 05:17:16.021384 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 05:17:16.023115 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 05:17:16.025663 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 05:17:16.034304 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 05:17:16.040565 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 05:17:16.042914 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 05:17:16.044986 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 05:17:16.046296 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 05:17:16.046330 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 05:17:16.048178 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 05:17:16.055015 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 05:17:16.056248 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 05:17:16.057381 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 05:17:16.059237 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 05:17:16.060613 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 05:17:16.062422 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 05:17:16.063705 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 05:17:16.066476 systemd-journald[1165]: Time spent on flushing to /var/log/journal/6ada1d33c60941e88294ad1dc8fbb916 is 21.918ms for 852 entries. Sep 12 05:17:16.066476 systemd-journald[1165]: System Journal (/var/log/journal/6ada1d33c60941e88294ad1dc8fbb916) is 8M, max 195.6M, 187.6M free. Sep 12 05:17:16.101467 systemd-journald[1165]: Received client request to flush runtime journal. Sep 12 05:17:16.101514 kernel: loop0: detected capacity change from 0 to 100632 Sep 12 05:17:16.066669 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 05:17:16.072326 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 05:17:16.076412 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 05:17:16.078805 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 05:17:16.080254 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 05:17:16.094710 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 05:17:16.096180 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 05:17:16.100002 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 05:17:16.105540 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 05:17:16.102680 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 05:17:16.105978 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 05:17:16.129321 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 05:17:16.131293 kernel: loop1: detected capacity change from 0 to 119368 Sep 12 05:17:16.132037 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 05:17:16.133642 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 05:17:16.137019 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 05:17:16.156347 kernel: loop2: detected capacity change from 0 to 100632 Sep 12 05:17:16.165304 kernel: loop3: detected capacity change from 0 to 119368 Sep 12 05:17:16.166629 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Sep 12 05:17:16.166643 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Sep 12 05:17:16.169724 (sd-merge)[1225]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 12 05:17:16.170880 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 05:17:16.171067 (sd-merge)[1225]: Merged extensions into '/usr'. Sep 12 05:17:16.176342 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 05:17:16.179093 systemd[1]: Starting ensure-sysext.service... Sep 12 05:17:16.182390 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 05:17:16.197920 systemd[1]: Reload requested from client PID 1229 ('systemctl') (unit ensure-sysext.service)... Sep 12 05:17:16.197932 systemd[1]: Reloading... Sep 12 05:17:16.207186 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 12 05:17:16.207273 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 12 05:17:16.207923 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 05:17:16.208194 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 05:17:16.208857 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 05:17:16.209077 systemd-tmpfiles[1230]: ACLs are not supported, ignoring. Sep 12 05:17:16.209172 systemd-tmpfiles[1230]: ACLs are not supported, ignoring. Sep 12 05:17:16.213512 systemd-tmpfiles[1230]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 05:17:16.213524 systemd-tmpfiles[1230]: Skipping /boot Sep 12 05:17:16.221048 systemd-tmpfiles[1230]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 05:17:16.221062 systemd-tmpfiles[1230]: Skipping /boot Sep 12 05:17:16.265309 zram_generator::config[1267]: No configuration found. Sep 12 05:17:16.330431 ldconfig[1202]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 05:17:16.391339 systemd[1]: Reloading finished in 193 ms. Sep 12 05:17:16.424718 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 05:17:16.444313 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 05:17:16.451481 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 05:17:16.453914 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 05:17:16.470014 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 05:17:16.482445 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 05:17:16.486368 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 05:17:16.493808 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 05:17:16.496205 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 05:17:16.498406 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 05:17:16.500648 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 05:17:16.501764 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 05:17:16.501896 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 05:17:16.503739 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 05:17:16.505917 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 05:17:16.506302 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 05:17:16.508766 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 05:17:16.508937 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 05:17:16.519092 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 05:17:16.526239 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 05:17:16.526445 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 05:17:16.531035 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 05:17:16.532589 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 05:17:16.535483 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 05:17:16.537953 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 05:17:16.539162 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 05:17:16.539294 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 05:17:16.541359 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 05:17:16.549845 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 05:17:16.552107 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 05:17:16.553450 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 05:17:16.555197 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 05:17:16.555373 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 05:17:16.559865 augenrules[1330]: No rules Sep 12 05:17:16.560371 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 05:17:16.562174 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 05:17:16.563874 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 05:17:16.565329 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 05:17:16.566737 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 05:17:16.573524 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 05:17:16.583017 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 05:17:16.584265 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 05:17:16.585402 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 05:17:16.592689 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 05:17:16.595946 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 05:17:16.598589 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 05:17:16.599913 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 05:17:16.600037 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 05:17:16.603504 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 05:17:16.605751 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 05:17:16.606916 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 12 05:17:16.608419 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 05:17:16.608565 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 05:17:16.610596 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 05:17:16.611559 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 05:17:16.613673 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 05:17:16.613827 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 05:17:16.616628 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 05:17:16.616792 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 05:17:16.618720 augenrules[1346]: /sbin/augenrules: No change Sep 12 05:17:16.618811 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 05:17:16.622226 systemd-resolved[1299]: Positive Trust Anchors: Sep 12 05:17:16.622246 systemd-resolved[1299]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 05:17:16.622291 systemd-resolved[1299]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 12 05:17:16.625373 systemd[1]: Finished ensure-sysext.service. Sep 12 05:17:16.628509 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 05:17:16.628569 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 05:17:16.629415 systemd-resolved[1299]: Defaulting to hostname 'linux'. Sep 12 05:17:16.630111 augenrules[1374]: No rules Sep 12 05:17:16.631567 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 12 05:17:16.632866 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 05:17:16.634270 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 05:17:16.636325 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 05:17:16.637761 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 05:17:16.638754 systemd-udevd[1357]: Using default interface naming scheme 'v255'. Sep 12 05:17:16.656144 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 05:17:16.660169 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 05:17:16.714829 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 12 05:17:16.716810 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 05:17:16.718869 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 05:17:16.720808 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 12 05:17:16.723451 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 05:17:16.725495 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 12 05:17:16.725526 systemd[1]: Reached target paths.target - Path Units. Sep 12 05:17:16.727139 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 05:17:16.729095 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 05:17:16.731093 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 05:17:16.733461 systemd[1]: Reached target timers.target - Timer Units. Sep 12 05:17:16.736139 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 05:17:16.742186 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 05:17:16.747131 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 05:17:16.748642 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 05:17:16.750044 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 05:17:16.754423 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 05:17:16.756388 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 05:17:16.758317 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 05:17:16.761798 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 12 05:17:16.761829 systemd-networkd[1392]: lo: Link UP Sep 12 05:17:16.761833 systemd-networkd[1392]: lo: Gained carrier Sep 12 05:17:16.762528 systemd-networkd[1392]: Enumeration completed Sep 12 05:17:16.762959 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 05:17:16.768858 systemd[1]: Reached target network.target - Network. Sep 12 05:17:16.769812 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 05:17:16.770866 systemd[1]: Reached target basic.target - Basic System. Sep 12 05:17:16.771864 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 05:17:16.771892 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 05:17:16.772582 systemd-networkd[1392]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 05:17:16.772594 systemd-networkd[1392]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 05:17:16.773030 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 05:17:16.773290 systemd-networkd[1392]: eth0: Link UP Sep 12 05:17:16.773409 systemd-networkd[1392]: eth0: Gained carrier Sep 12 05:17:16.773423 systemd-networkd[1392]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 05:17:16.775096 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 05:17:16.777058 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 05:17:16.780450 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 05:17:16.783450 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 05:17:16.784629 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 12 05:17:16.787361 systemd-networkd[1392]: eth0: DHCPv4 address 10.0.0.33/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 05:17:16.787670 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 05:17:16.787935 systemd-timesyncd[1380]: Network configuration changed, trying to establish connection. Sep 12 05:17:16.790529 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 05:17:16.315033 systemd-resolved[1299]: Clock change detected. Flushing caches. Sep 12 05:17:16.326067 systemd-journald[1165]: Time jumped backwards, rotating. Sep 12 05:17:16.326115 jq[1425]: false Sep 12 05:17:16.315129 systemd-timesyncd[1380]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 12 05:17:16.315154 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 05:17:16.315189 systemd-timesyncd[1380]: Initial clock synchronization to Fri 2025-09-12 05:17:16.314941 UTC. Sep 12 05:17:16.321025 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 05:17:16.325565 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 05:17:16.327894 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 05:17:16.330397 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 05:17:16.331922 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 05:17:16.332811 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 05:17:16.335504 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 05:17:16.346516 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 05:17:16.349838 extend-filesystems[1426]: Found /dev/vda6 Sep 12 05:17:16.348262 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 05:17:16.353139 extend-filesystems[1426]: Found /dev/vda9 Sep 12 05:17:16.348431 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 05:17:16.348764 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 05:17:16.348986 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 05:17:16.358881 jq[1446]: true Sep 12 05:17:16.360572 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 05:17:16.360819 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 05:17:16.364506 extend-filesystems[1426]: Checking size of /dev/vda9 Sep 12 05:17:16.365427 (ntainerd)[1454]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 05:17:16.372665 extend-filesystems[1426]: Old size kept for /dev/vda9 Sep 12 05:17:16.373171 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 05:17:16.374544 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 05:17:16.381296 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 05:17:16.387289 update_engine[1441]: I20250912 05:17:16.387055 1441 main.cc:92] Flatcar Update Engine starting Sep 12 05:17:16.392565 dbus-daemon[1423]: [system] SELinux support is enabled Sep 12 05:17:16.395487 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 12 05:17:16.396814 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 05:17:16.404465 update_engine[1441]: I20250912 05:17:16.402591 1441 update_check_scheduler.cc:74] Next update check in 3m38s Sep 12 05:17:16.404519 jq[1456]: true Sep 12 05:17:16.402758 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 05:17:16.407313 systemd-logind[1430]: New seat seat0. Sep 12 05:17:16.408125 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 12 05:17:16.408166 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 05:17:16.411816 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 12 05:17:16.411840 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 05:17:16.413169 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 05:17:16.414895 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 12 05:17:16.417282 systemd[1]: Started update-engine.service - Update Engine. Sep 12 05:17:16.427737 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 05:17:16.452171 bash[1489]: Updated "/home/core/.ssh/authorized_keys" Sep 12 05:17:16.455577 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 05:17:16.458126 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 05:17:16.555263 systemd-logind[1430]: Watching system buttons on /dev/input/event0 (Power Button) Sep 12 05:17:16.560527 locksmithd[1471]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 05:17:16.565892 containerd[1454]: time="2025-09-12T05:17:16Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 05:17:16.568080 containerd[1454]: time="2025-09-12T05:17:16.568043711Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 05:17:16.573717 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 05:17:16.588739 containerd[1454]: time="2025-09-12T05:17:16.588701191Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.24µs" Sep 12 05:17:16.588788 containerd[1454]: time="2025-09-12T05:17:16.588735391Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 05:17:16.588788 containerd[1454]: time="2025-09-12T05:17:16.588763191Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 05:17:16.588963 containerd[1454]: time="2025-09-12T05:17:16.588942071Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 05:17:16.588985 containerd[1454]: time="2025-09-12T05:17:16.588965191Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 05:17:16.589022 containerd[1454]: time="2025-09-12T05:17:16.589004271Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 05:17:16.589086 containerd[1454]: time="2025-09-12T05:17:16.589068191Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 05:17:16.589105 containerd[1454]: time="2025-09-12T05:17:16.589085351Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.589657311Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.589678191Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.589689511Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.589697911Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.589781351Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.589965711Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.589994831Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.590006511Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.590044151Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.590234711Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 05:17:16.590473 containerd[1454]: time="2025-09-12T05:17:16.590296431Z" level=info msg="metadata content store policy set" policy=shared Sep 12 05:17:16.594270 containerd[1454]: time="2025-09-12T05:17:16.594230791Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 05:17:16.594338 containerd[1454]: time="2025-09-12T05:17:16.594302911Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 05:17:16.594338 containerd[1454]: time="2025-09-12T05:17:16.594320951Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 05:17:16.594338 containerd[1454]: time="2025-09-12T05:17:16.594332871Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 05:17:16.594413 containerd[1454]: time="2025-09-12T05:17:16.594343951Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 05:17:16.594455 containerd[1454]: time="2025-09-12T05:17:16.594412831Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 05:17:16.594455 containerd[1454]: time="2025-09-12T05:17:16.594427471Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 05:17:16.594455 containerd[1454]: time="2025-09-12T05:17:16.594452031Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 05:17:16.594509 containerd[1454]: time="2025-09-12T05:17:16.594471151Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 05:17:16.594509 containerd[1454]: time="2025-09-12T05:17:16.594483111Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 05:17:16.594509 containerd[1454]: time="2025-09-12T05:17:16.594491911Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 05:17:16.594509 containerd[1454]: time="2025-09-12T05:17:16.594504191Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 05:17:16.594709 containerd[1454]: time="2025-09-12T05:17:16.594681831Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 05:17:16.594738 containerd[1454]: time="2025-09-12T05:17:16.594723631Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 05:17:16.594755 containerd[1454]: time="2025-09-12T05:17:16.594740871Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 05:17:16.594755 containerd[1454]: time="2025-09-12T05:17:16.594752271Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 05:17:16.594784 containerd[1454]: time="2025-09-12T05:17:16.594762711Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 05:17:16.594784 containerd[1454]: time="2025-09-12T05:17:16.594775031Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 05:17:16.595490 containerd[1454]: time="2025-09-12T05:17:16.595462991Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 05:17:16.595490 containerd[1454]: time="2025-09-12T05:17:16.595489831Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 05:17:16.595552 containerd[1454]: time="2025-09-12T05:17:16.595503911Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 05:17:16.595552 containerd[1454]: time="2025-09-12T05:17:16.595515831Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 05:17:16.595552 containerd[1454]: time="2025-09-12T05:17:16.595537311Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 05:17:16.595758 containerd[1454]: time="2025-09-12T05:17:16.595736391Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 05:17:16.595758 containerd[1454]: time="2025-09-12T05:17:16.595757431Z" level=info msg="Start snapshots syncer" Sep 12 05:17:16.595918 containerd[1454]: time="2025-09-12T05:17:16.595786151Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 05:17:16.596183 containerd[1454]: time="2025-09-12T05:17:16.596141511Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 05:17:16.596274 containerd[1454]: time="2025-09-12T05:17:16.596198991Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 05:17:16.596455 containerd[1454]: time="2025-09-12T05:17:16.596385631Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 05:17:16.596559 containerd[1454]: time="2025-09-12T05:17:16.596534471Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 05:17:16.596809 containerd[1454]: time="2025-09-12T05:17:16.596780231Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 05:17:16.598550 containerd[1454]: time="2025-09-12T05:17:16.596815231Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 05:17:16.598600 containerd[1454]: time="2025-09-12T05:17:16.598550511Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 05:17:16.598600 containerd[1454]: time="2025-09-12T05:17:16.598575591Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 05:17:16.598699 containerd[1454]: time="2025-09-12T05:17:16.598591871Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 05:17:16.599078 containerd[1454]: time="2025-09-12T05:17:16.599042751Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 05:17:16.599135 containerd[1454]: time="2025-09-12T05:17:16.599117551Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 05:17:16.599158 containerd[1454]: time="2025-09-12T05:17:16.599141791Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 05:17:16.599176 containerd[1454]: time="2025-09-12T05:17:16.599161871Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 05:17:16.599221 containerd[1454]: time="2025-09-12T05:17:16.599201031Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 05:17:16.599246 containerd[1454]: time="2025-09-12T05:17:16.599226391Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 05:17:16.599246 containerd[1454]: time="2025-09-12T05:17:16.599241911Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 05:17:16.599288 containerd[1454]: time="2025-09-12T05:17:16.599257271Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 05:17:16.599288 containerd[1454]: time="2025-09-12T05:17:16.599267191Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 05:17:16.599288 containerd[1454]: time="2025-09-12T05:17:16.599281391Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 05:17:16.599333 containerd[1454]: time="2025-09-12T05:17:16.599296071Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 05:17:16.599569 containerd[1454]: time="2025-09-12T05:17:16.599405511Z" level=info msg="runtime interface created" Sep 12 05:17:16.599569 containerd[1454]: time="2025-09-12T05:17:16.599565871Z" level=info msg="created NRI interface" Sep 12 05:17:16.599618 containerd[1454]: time="2025-09-12T05:17:16.599582831Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 05:17:16.599618 containerd[1454]: time="2025-09-12T05:17:16.599598831Z" level=info msg="Connect containerd service" Sep 12 05:17:16.599665 containerd[1454]: time="2025-09-12T05:17:16.599631671Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 05:17:16.600308 containerd[1454]: time="2025-09-12T05:17:16.600282111Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 12 05:17:16.633806 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 05:17:16.664891 containerd[1454]: time="2025-09-12T05:17:16.664818551Z" level=info msg="Start subscribing containerd event" Sep 12 05:17:16.664969 containerd[1454]: time="2025-09-12T05:17:16.664906311Z" level=info msg="Start recovering state" Sep 12 05:17:16.665014 containerd[1454]: time="2025-09-12T05:17:16.664999031Z" level=info msg="Start event monitor" Sep 12 05:17:16.665034 containerd[1454]: time="2025-09-12T05:17:16.665017671Z" level=info msg="Start cni network conf syncer for default" Sep 12 05:17:16.665034 containerd[1454]: time="2025-09-12T05:17:16.665028271Z" level=info msg="Start streaming server" Sep 12 05:17:16.665065 containerd[1454]: time="2025-09-12T05:17:16.665036551Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 05:17:16.665065 containerd[1454]: time="2025-09-12T05:17:16.665043911Z" level=info msg="runtime interface starting up..." Sep 12 05:17:16.665065 containerd[1454]: time="2025-09-12T05:17:16.665049831Z" level=info msg="starting plugins..." Sep 12 05:17:16.665065 containerd[1454]: time="2025-09-12T05:17:16.665063071Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 05:17:16.665201 containerd[1454]: time="2025-09-12T05:17:16.665173791Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 05:17:16.665245 containerd[1454]: time="2025-09-12T05:17:16.665231991Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 05:17:16.665308 containerd[1454]: time="2025-09-12T05:17:16.665289631Z" level=info msg="containerd successfully booted in 0.099784s" Sep 12 05:17:16.665382 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 05:17:17.316581 systemd-networkd[1392]: eth0: Gained IPv6LL Sep 12 05:17:17.321382 sshd_keygen[1442]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 05:17:17.322218 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 05:17:17.324038 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 05:17:17.326433 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 12 05:17:17.328579 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 05:17:17.344788 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 05:17:17.349318 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 05:17:17.358161 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 05:17:17.360250 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 12 05:17:17.360487 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 12 05:17:17.362617 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 05:17:17.363065 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 05:17:17.364473 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 05:17:17.368568 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 05:17:17.385526 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 05:17:17.388224 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 05:17:17.391739 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 12 05:17:17.393101 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 05:17:17.394216 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 05:17:17.395421 systemd[1]: Startup finished in 2.044s (kernel) + 3.809s (initrd) + 2.511s (userspace) = 8.365s. Sep 12 05:17:23.574913 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 05:17:23.575929 systemd[1]: Started sshd@0-10.0.0.33:22-10.0.0.1:34532.service - OpenSSH per-connection server daemon (10.0.0.1:34532). Sep 12 05:17:23.647208 sshd[1572]: Accepted publickey for core from 10.0.0.1 port 34532 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:23.648937 sshd-session[1572]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:23.654392 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 05:17:23.655204 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 05:17:23.660099 systemd-logind[1430]: New session 1 of user core. Sep 12 05:17:23.676012 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 05:17:23.680126 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 05:17:23.694152 (systemd)[1577]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 05:17:23.696142 systemd-logind[1430]: New session c1 of user core. Sep 12 05:17:23.800285 systemd[1577]: Queued start job for default target default.target. Sep 12 05:17:23.811350 systemd[1577]: Created slice app.slice - User Application Slice. Sep 12 05:17:23.811379 systemd[1577]: Reached target paths.target - Paths. Sep 12 05:17:23.811410 systemd[1577]: Reached target timers.target - Timers. Sep 12 05:17:23.812477 systemd[1577]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 05:17:23.820747 systemd[1577]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 05:17:23.820804 systemd[1577]: Reached target sockets.target - Sockets. Sep 12 05:17:23.820837 systemd[1577]: Reached target basic.target - Basic System. Sep 12 05:17:23.820865 systemd[1577]: Reached target default.target - Main User Target. Sep 12 05:17:23.820898 systemd[1577]: Startup finished in 120ms. Sep 12 05:17:23.821008 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 05:17:23.822192 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 05:17:23.882647 systemd[1]: Started sshd@1-10.0.0.33:22-10.0.0.1:34538.service - OpenSSH per-connection server daemon (10.0.0.1:34538). Sep 12 05:17:23.921646 sshd[1588]: Accepted publickey for core from 10.0.0.1 port 34538 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:23.922763 sshd-session[1588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:23.926916 systemd-logind[1430]: New session 2 of user core. Sep 12 05:17:23.938551 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 05:17:23.989550 sshd[1591]: Connection closed by 10.0.0.1 port 34538 Sep 12 05:17:23.990043 sshd-session[1588]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:24.005977 systemd[1]: sshd@1-10.0.0.33:22-10.0.0.1:34538.service: Deactivated successfully. Sep 12 05:17:24.008708 systemd[1]: session-2.scope: Deactivated successfully. Sep 12 05:17:24.009309 systemd-logind[1430]: Session 2 logged out. Waiting for processes to exit. Sep 12 05:17:24.011320 systemd[1]: Started sshd@2-10.0.0.33:22-10.0.0.1:34554.service - OpenSSH per-connection server daemon (10.0.0.1:34554). Sep 12 05:17:24.011834 systemd-logind[1430]: Removed session 2. Sep 12 05:17:24.073342 sshd[1597]: Accepted publickey for core from 10.0.0.1 port 34554 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:24.074383 sshd-session[1597]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:24.077703 systemd-logind[1430]: New session 3 of user core. Sep 12 05:17:24.092557 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 05:17:24.139588 sshd[1600]: Connection closed by 10.0.0.1 port 34554 Sep 12 05:17:24.139422 sshd-session[1597]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:24.152969 systemd[1]: sshd@2-10.0.0.33:22-10.0.0.1:34554.service: Deactivated successfully. Sep 12 05:17:24.155460 systemd[1]: session-3.scope: Deactivated successfully. Sep 12 05:17:24.156007 systemd-logind[1430]: Session 3 logged out. Waiting for processes to exit. Sep 12 05:17:24.157826 systemd[1]: Started sshd@3-10.0.0.33:22-10.0.0.1:34566.service - OpenSSH per-connection server daemon (10.0.0.1:34566). Sep 12 05:17:24.158239 systemd-logind[1430]: Removed session 3. Sep 12 05:17:24.197730 sshd[1606]: Accepted publickey for core from 10.0.0.1 port 34566 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:24.198797 sshd-session[1606]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:24.202105 systemd-logind[1430]: New session 4 of user core. Sep 12 05:17:24.211595 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 05:17:24.262189 sshd[1609]: Connection closed by 10.0.0.1 port 34566 Sep 12 05:17:24.262496 sshd-session[1606]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:24.271136 systemd[1]: sshd@3-10.0.0.33:22-10.0.0.1:34566.service: Deactivated successfully. Sep 12 05:17:24.273528 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 05:17:24.274119 systemd-logind[1430]: Session 4 logged out. Waiting for processes to exit. Sep 12 05:17:24.276008 systemd[1]: Started sshd@4-10.0.0.33:22-10.0.0.1:34572.service - OpenSSH per-connection server daemon (10.0.0.1:34572). Sep 12 05:17:24.276423 systemd-logind[1430]: Removed session 4. Sep 12 05:17:24.334787 sshd[1615]: Accepted publickey for core from 10.0.0.1 port 34572 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:24.335824 sshd-session[1615]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:24.339335 systemd-logind[1430]: New session 5 of user core. Sep 12 05:17:24.354568 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 05:17:24.409369 sudo[1619]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 12 05:17:24.409655 sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 05:17:24.431185 sudo[1619]: pam_unix(sudo:session): session closed for user root Sep 12 05:17:24.432609 sshd[1618]: Connection closed by 10.0.0.1 port 34572 Sep 12 05:17:24.433041 sshd-session[1615]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:24.446183 systemd[1]: sshd@4-10.0.0.33:22-10.0.0.1:34572.service: Deactivated successfully. Sep 12 05:17:24.448571 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 05:17:24.449166 systemd-logind[1430]: Session 5 logged out. Waiting for processes to exit. Sep 12 05:17:24.451130 systemd[1]: Started sshd@5-10.0.0.33:22-10.0.0.1:34580.service - OpenSSH per-connection server daemon (10.0.0.1:34580). Sep 12 05:17:24.451562 systemd-logind[1430]: Removed session 5. Sep 12 05:17:24.490909 sshd[1625]: Accepted publickey for core from 10.0.0.1 port 34580 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:24.491915 sshd-session[1625]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:24.495108 systemd-logind[1430]: New session 6 of user core. Sep 12 05:17:24.505577 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 12 05:17:24.555396 sudo[1630]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 12 05:17:24.555670 sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 05:17:24.560232 sudo[1630]: pam_unix(sudo:session): session closed for user root Sep 12 05:17:24.564485 sudo[1629]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 12 05:17:24.564736 sudo[1629]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 05:17:24.573370 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 05:17:24.619299 augenrules[1652]: No rules Sep 12 05:17:24.620322 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 05:17:24.621524 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 05:17:24.623115 sudo[1629]: pam_unix(sudo:session): session closed for user root Sep 12 05:17:24.624511 sshd[1628]: Connection closed by 10.0.0.1 port 34580 Sep 12 05:17:24.624737 sshd-session[1625]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:24.633137 systemd[1]: sshd@5-10.0.0.33:22-10.0.0.1:34580.service: Deactivated successfully. Sep 12 05:17:24.634516 systemd[1]: session-6.scope: Deactivated successfully. Sep 12 05:17:24.635093 systemd-logind[1430]: Session 6 logged out. Waiting for processes to exit. Sep 12 05:17:24.636787 systemd[1]: Started sshd@6-10.0.0.33:22-10.0.0.1:34588.service - OpenSSH per-connection server daemon (10.0.0.1:34588). Sep 12 05:17:24.637432 systemd-logind[1430]: Removed session 6. Sep 12 05:17:24.686007 sshd[1661]: Accepted publickey for core from 10.0.0.1 port 34588 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:24.686973 sshd-session[1661]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:24.690454 systemd-logind[1430]: New session 7 of user core. Sep 12 05:17:24.708599 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 12 05:17:24.760680 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 05:17:24.786779 (dockerd)[1666]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 12 05:17:24.983673 dockerd[1666]: time="2025-09-12T05:17:24.983555951Z" level=info msg="Starting up" Sep 12 05:17:24.984334 dockerd[1666]: time="2025-09-12T05:17:24.984314671Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 12 05:17:24.994491 dockerd[1666]: time="2025-09-12T05:17:24.994448271Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 12 05:17:25.023370 dockerd[1666]: time="2025-09-12T05:17:25.023188071Z" level=info msg="Loading containers: start." Sep 12 05:17:25.030459 kernel: Initializing XFRM netlink socket Sep 12 05:17:25.203238 systemd-networkd[1392]: docker0: Link UP Sep 12 05:17:25.206543 dockerd[1666]: time="2025-09-12T05:17:25.206512151Z" level=info msg="Loading containers: done." Sep 12 05:17:25.217919 dockerd[1666]: time="2025-09-12T05:17:25.217868191Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 12 05:17:25.218013 dockerd[1666]: time="2025-09-12T05:17:25.217946231Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 12 05:17:25.218035 dockerd[1666]: time="2025-09-12T05:17:25.218017071Z" level=info msg="Initializing buildkit" Sep 12 05:17:25.239186 dockerd[1666]: time="2025-09-12T05:17:25.239109111Z" level=info msg="Completed buildkit initialization" Sep 12 05:17:25.243640 dockerd[1666]: time="2025-09-12T05:17:25.243612951Z" level=info msg="Daemon has completed initialization" Sep 12 05:17:25.243818 dockerd[1666]: time="2025-09-12T05:17:25.243709911Z" level=info msg="API listen on /run/docker.sock" Sep 12 05:17:25.243829 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 05:17:25.252677 sshd[1664]: Connection closed by 10.0.0.1 port 34588 Sep 12 05:17:25.253151 sshd-session[1661]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:25.263159 systemd[1]: sshd@6-10.0.0.33:22-10.0.0.1:34588.service: Deactivated successfully. Sep 12 05:17:25.265587 systemd[1]: session-7.scope: Deactivated successfully. Sep 12 05:17:25.266611 systemd-logind[1430]: Session 7 logged out. Waiting for processes to exit. Sep 12 05:17:25.268095 systemd[1]: Started sshd@7-10.0.0.33:22-10.0.0.1:34596.service - OpenSSH per-connection server daemon (10.0.0.1:34596). Sep 12 05:17:25.268886 systemd-logind[1430]: Removed session 7. Sep 12 05:17:25.323666 sshd[1878]: Accepted publickey for core from 10.0.0.1 port 34596 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:25.324781 sshd-session[1878]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:25.328966 systemd-logind[1430]: New session 8 of user core. Sep 12 05:17:25.339576 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 12 05:17:26.607333 systemd[1]: var-lib-docker-overlay2-58947e6d01172eb97bbe8fbf7ce9acbefc4d45b072ee72eed5cda9f391dc3972\x2dinit-merged.mount: Deactivated successfully. Sep 12 05:17:26.628573 systemd[1]: var-lib-docker-overlay2-58947e6d01172eb97bbe8fbf7ce9acbefc4d45b072ee72eed5cda9f391dc3972-merged.mount: Deactivated successfully. Sep 12 05:17:26.656020 containerd[1454]: time="2025-09-12T05:17:26.655972871Z" level=info msg="connecting to shim c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de" address="unix:///run/containerd/s/ef3b0e27da5218535654d7bfed08092ac3bb574f58f32a3e0f5dbf807846f59d" namespace=moby protocol=ttrpc version=3 Sep 12 05:17:26.677576 systemd[1]: Started docker-c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de.scope - libcontainer container c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de. Sep 12 05:17:26.688887 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 05:17:26.705841 kernel: docker0: port 1(veth2546e6b) entered blocking state Sep 12 05:17:26.705909 kernel: docker0: port 1(veth2546e6b) entered disabled state Sep 12 05:17:26.705927 kernel: veth2546e6b: entered allmulticast mode Sep 12 05:17:26.707237 systemd-networkd[1392]: veth2546e6b: Link UP Sep 12 05:17:26.707459 kernel: veth2546e6b: entered promiscuous mode Sep 12 05:17:26.715454 kernel: eth0: renamed from veth874758c Sep 12 05:17:26.717135 kernel: docker0: port 1(veth2546e6b) entered blocking state Sep 12 05:17:26.717169 kernel: docker0: port 1(veth2546e6b) entered forwarding state Sep 12 05:17:26.717215 systemd-networkd[1392]: veth2546e6b: Gained carrier Sep 12 05:17:26.717429 systemd-networkd[1392]: docker0: Gained carrier Sep 12 05:17:26.738711 sshd[1881]: Connection closed by 10.0.0.1 port 34596 Sep 12 05:17:26.739390 sshd-session[1878]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:26.751344 systemd[1]: sshd@7-10.0.0.33:22-10.0.0.1:34596.service: Deactivated successfully. Sep 12 05:17:26.752989 systemd[1]: session-8.scope: Deactivated successfully. Sep 12 05:17:26.754916 systemd-logind[1430]: Session 8 logged out. Waiting for processes to exit. Sep 12 05:17:26.757089 systemd[1]: Started sshd@8-10.0.0.33:22-10.0.0.1:34604.service - OpenSSH per-connection server daemon (10.0.0.1:34604). Sep 12 05:17:26.757980 systemd-logind[1430]: Removed session 8. Sep 12 05:17:26.812814 sshd[1958]: Accepted publickey for core from 10.0.0.1 port 34604 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:26.813897 sshd-session[1958]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:26.818270 systemd-logind[1430]: New session 9 of user core. Sep 12 05:17:26.830596 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 12 05:17:26.885405 sshd[1961]: Connection closed by 10.0.0.1 port 34604 Sep 12 05:17:26.885570 sshd-session[1958]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:26.895388 systemd[1]: sshd@8-10.0.0.33:22-10.0.0.1:34604.service: Deactivated successfully. Sep 12 05:17:26.898659 systemd[1]: session-9.scope: Deactivated successfully. Sep 12 05:17:26.899978 systemd-logind[1430]: Session 9 logged out. Waiting for processes to exit. Sep 12 05:17:26.901317 systemd[1]: Started sshd@9-10.0.0.33:22-10.0.0.1:34606.service - OpenSSH per-connection server daemon (10.0.0.1:34606). Sep 12 05:17:26.902247 systemd-logind[1430]: Removed session 9. Sep 12 05:17:26.951611 sshd[1967]: Accepted publickey for core from 10.0.0.1 port 34606 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:26.952727 sshd-session[1967]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:26.956449 systemd-logind[1430]: New session 10 of user core. Sep 12 05:17:26.963593 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 12 05:17:27.013339 sudo[1971]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1454 Sep 12 05:17:27.013630 sudo[1971]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 05:17:27.017201 sudo[1971]: pam_unix(sudo:session): session closed for user root Sep 12 05:17:27.017429 containerd[1454]: time="2025-09-12T05:17:27.017287551Z" level=info msg="Stop CRI service" Sep 12 05:17:27.017478 dockerd[1666]: time="2025-09-12T05:17:27.017415511Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 12 05:17:27.017478 dockerd[1666]: time="2025-09-12T05:17:27.017473511Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 12 05:17:27.017705 dockerd[1666]: time="2025-09-12T05:17:27.017615311Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 12 05:17:27.017705 dockerd[1666]: time="2025-09-12T05:17:27.017639951Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 12 05:17:27.018506 sshd[1970]: Connection closed by 10.0.0.1 port 34606 Sep 12 05:17:27.018779 sshd-session[1967]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:27.027179 systemd[1]: containerd.service: Deactivated successfully. Sep 12 05:17:27.027286 systemd[1]: containerd.service: Unit process 1904 (containerd-shim) remains running after unit stopped. Sep 12 05:17:27.027502 systemd[1]: containerd.service: Consumed 276ms CPU time, 120.4M memory peak. Sep 12 05:17:27.027844 systemd[1]: sshd@9-10.0.0.33:22-10.0.0.1:34606.service: Deactivated successfully. Sep 12 05:17:27.029187 systemd[1]: session-10.scope: Deactivated successfully. Sep 12 05:17:27.029744 systemd-logind[1430]: Session 10 logged out. Waiting for processes to exit. Sep 12 05:17:27.032010 systemd[1]: Started sshd@10-10.0.0.33:22-10.0.0.1:34608.service - OpenSSH per-connection server daemon (10.0.0.1:34608). Sep 12 05:17:27.032458 systemd-logind[1430]: Removed session 10. Sep 12 05:17:27.083456 sshd[1978]: Accepted publickey for core from 10.0.0.1 port 34608 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:27.084629 sshd-session[1978]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:27.088245 systemd-logind[1430]: New session 11 of user core. Sep 12 05:17:27.100591 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 12 05:17:27.153817 sshd[1981]: Connection closed by 10.0.0.1 port 34608 Sep 12 05:17:27.154388 sshd-session[1978]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:27.158083 systemd[1]: sshd@10-10.0.0.33:22-10.0.0.1:34608.service: Deactivated successfully. Sep 12 05:17:27.160731 systemd[1]: session-11.scope: Deactivated successfully. Sep 12 05:17:27.161510 systemd-logind[1430]: Session 11 logged out. Waiting for processes to exit. Sep 12 05:17:27.162624 systemd-logind[1430]: Removed session 11. Sep 12 05:17:28.196630 systemd-networkd[1392]: veth2546e6b: Gained IPv6LL Sep 12 05:17:28.324680 systemd-networkd[1392]: docker0: Gained IPv6LL Sep 12 05:17:32.063128 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Sep 12 05:17:32.063550 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 12 05:17:32.063757 dockerd[1666]: time="2025-09-12T05:17:32.063711511Z" level=info msg="Processing signal 'terminated'" Sep 12 05:17:33.167479 systemd[1]: Started sshd@11-10.0.0.33:22-10.0.0.1:45320.service - OpenSSH per-connection server daemon (10.0.0.1:45320). Sep 12 05:17:33.208612 sshd[1987]: Accepted publickey for core from 10.0.0.1 port 45320 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:33.209806 sshd-session[1987]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:33.213882 systemd-logind[1430]: New session 12 of user core. Sep 12 05:17:33.222604 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 12 05:17:33.275828 sshd[1990]: Connection closed by 10.0.0.1 port 45320 Sep 12 05:17:33.276138 sshd-session[1987]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:33.279247 systemd[1]: sshd@11-10.0.0.33:22-10.0.0.1:45320.service: Deactivated successfully. Sep 12 05:17:33.280667 systemd[1]: session-12.scope: Deactivated successfully. Sep 12 05:17:33.282118 systemd-logind[1430]: Session 12 logged out. Waiting for processes to exit. Sep 12 05:17:33.283390 systemd-logind[1430]: Removed session 12. Sep 12 05:17:39.295481 systemd[1]: Started sshd@12-10.0.0.33:22-10.0.0.1:45322.service - OpenSSH per-connection server daemon (10.0.0.1:45322). Sep 12 05:17:39.343364 sshd[1996]: Accepted publickey for core from 10.0.0.1 port 45322 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:39.344385 sshd-session[1996]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:39.347623 systemd-logind[1430]: New session 13 of user core. Sep 12 05:17:39.365562 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 12 05:17:39.417891 sshd[1999]: Connection closed by 10.0.0.1 port 45322 Sep 12 05:17:39.418151 sshd-session[1996]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:39.421071 systemd[1]: sshd@12-10.0.0.33:22-10.0.0.1:45322.service: Deactivated successfully. Sep 12 05:17:39.422497 systemd[1]: session-13.scope: Deactivated successfully. Sep 12 05:17:39.423086 systemd-logind[1430]: Session 13 logged out. Waiting for processes to exit. Sep 12 05:17:39.424190 systemd-logind[1430]: Removed session 13. Sep 12 05:17:45.435420 systemd[1]: Started sshd@13-10.0.0.33:22-10.0.0.1:39582.service - OpenSSH per-connection server daemon (10.0.0.1:39582). Sep 12 05:17:45.490198 sshd[2005]: Accepted publickey for core from 10.0.0.1 port 39582 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:45.491682 sshd-session[2005]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:45.496021 systemd-logind[1430]: New session 14 of user core. Sep 12 05:17:45.510623 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 12 05:17:45.564539 sshd[2008]: Connection closed by 10.0.0.1 port 39582 Sep 12 05:17:45.564837 sshd-session[2005]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:45.567556 systemd[1]: sshd@13-10.0.0.33:22-10.0.0.1:39582.service: Deactivated successfully. Sep 12 05:17:45.569043 systemd[1]: session-14.scope: Deactivated successfully. Sep 12 05:17:45.570708 systemd-logind[1430]: Session 14 logged out. Waiting for processes to exit. Sep 12 05:17:45.571582 systemd-logind[1430]: Removed session 14. Sep 12 05:17:47.064915 dockerd[1666]: time="2025-09-12T05:17:47.064801991Z" level=error msg="Force shutdown daemon" Sep 12 05:17:47.065610 dockerd[1666]: time="2025-09-12T05:17:47.065012071Z" level=info msg="Daemon shutdown complete" Sep 12 05:17:47.068889 systemd[1]: docker.service: Deactivated successfully. Sep 12 05:17:47.069170 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 12 05:17:47.069246 systemd[1]: docker.service: Consumed 821ms CPU time, 122.7M memory peak. Sep 12 05:17:47.069729 systemd[1]: docker.socket: Deactivated successfully. Sep 12 05:17:47.070057 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 12 05:17:47.070103 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 12 05:17:47.071412 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 05:17:47.071651 systemd[1]: containerd.service: Found left-over process 1904 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 05:17:47.071661 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 05:17:47.084897 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 05:17:47.085398 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 05:17:47.100292 systemd[1]: containerd.service: Found left-over process 1904 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 05:17:47.100304 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 05:17:47.120786 (ntainerd)[2018]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 05:17:47.139665 containerd[2018]: time="2025-09-12T05:17:47Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 05:17:47.143812 containerd[2018]: time="2025-09-12T05:17:47.140544151Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 05:17:47.149451 containerd[2018]: time="2025-09-12T05:17:47.149392871Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.8µs" Sep 12 05:17:47.149557 containerd[2018]: time="2025-09-12T05:17:47.149539071Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 05:17:47.149617 containerd[2018]: time="2025-09-12T05:17:47.149604351Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 05:17:47.149692 containerd[2018]: time="2025-09-12T05:17:47.149678071Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 05:17:47.149775 containerd[2018]: time="2025-09-12T05:17:47.149755871Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 05:17:47.150005 containerd[2018]: time="2025-09-12T05:17:47.149981711Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 05:17:47.150095 containerd[2018]: time="2025-09-12T05:17:47.150072831Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 05:17:47.150145 containerd[2018]: time="2025-09-12T05:17:47.150132231Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 05:17:47.150410 containerd[2018]: time="2025-09-12T05:17:47.150387471Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 05:17:47.150504 containerd[2018]: time="2025-09-12T05:17:47.150487071Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 05:17:47.150563 containerd[2018]: time="2025-09-12T05:17:47.150548951Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 05:17:47.150612 containerd[2018]: time="2025-09-12T05:17:47.150595351Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 05:17:47.150684 containerd[2018]: time="2025-09-12T05:17:47.150666111Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 05:17:47.151515 containerd[2018]: time="2025-09-12T05:17:47.151428791Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 05:17:47.151664 containerd[2018]: time="2025-09-12T05:17:47.151645231Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 05:17:47.151712 containerd[2018]: time="2025-09-12T05:17:47.151700431Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 05:17:47.151839 containerd[2018]: time="2025-09-12T05:17:47.151822751Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 05:17:47.152331 containerd[2018]: time="2025-09-12T05:17:47.152309831Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 05:17:47.152405 containerd[2018]: time="2025-09-12T05:17:47.152393431Z" level=info msg="metadata content store policy set" policy=shared Sep 12 05:17:47.152664 containerd[2018]: time="2025-09-12T05:17:47.152643831Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 05:17:47.152826 containerd[2018]: time="2025-09-12T05:17:47.152810271Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 05:17:47.152972 containerd[2018]: time="2025-09-12T05:17:47.152955511Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 05:17:47.153038 containerd[2018]: time="2025-09-12T05:17:47.153019071Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 05:17:47.153098 containerd[2018]: time="2025-09-12T05:17:47.153085311Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 05:17:47.153151 containerd[2018]: time="2025-09-12T05:17:47.153138711Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 05:17:47.153209 containerd[2018]: time="2025-09-12T05:17:47.153194831Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 05:17:47.153257 containerd[2018]: time="2025-09-12T05:17:47.153245151Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 05:17:47.153313 containerd[2018]: time="2025-09-12T05:17:47.153300871Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 05:17:47.153364 containerd[2018]: time="2025-09-12T05:17:47.153351431Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 05:17:47.153417 containerd[2018]: time="2025-09-12T05:17:47.153404671Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 05:17:47.153541 containerd[2018]: time="2025-09-12T05:17:47.153525031Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 05:17:47.154190 containerd[2018]: time="2025-09-12T05:17:47.154166551Z" level=info msg="connecting to shim c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de" address="unix:///run/containerd/s/ef3b0e27da5218535654d7bfed08092ac3bb574f58f32a3e0f5dbf807846f59d" namespace=moby protocol=ttrpc version=3 Sep 12 05:17:47.186460 containerd[2018]: time="2025-09-12T05:17:47.186368191Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 05:17:47.186460 containerd[2018]: time="2025-09-12T05:17:47.186418231Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 05:17:47.186598 containerd[2018]: time="2025-09-12T05:17:47.186582511Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 05:17:47.186658 containerd[2018]: time="2025-09-12T05:17:47.186645311Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 05:17:47.186709 containerd[2018]: time="2025-09-12T05:17:47.186697711Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 05:17:47.186775 containerd[2018]: time="2025-09-12T05:17:47.186761311Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 05:17:47.186877 containerd[2018]: time="2025-09-12T05:17:47.186861271Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 05:17:47.186928 containerd[2018]: time="2025-09-12T05:17:47.186917271Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 05:17:47.186986 containerd[2018]: time="2025-09-12T05:17:47.186972391Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 05:17:47.187039 containerd[2018]: time="2025-09-12T05:17:47.187026711Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 05:17:47.187093 containerd[2018]: time="2025-09-12T05:17:47.187080671Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 05:17:47.187204 containerd[2018]: time="2025-09-12T05:17:47.187186951Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 05:17:47.187259 containerd[2018]: time="2025-09-12T05:17:47.187247031Z" level=info msg="Start snapshots syncer" Sep 12 05:17:47.187341 containerd[2018]: time="2025-09-12T05:17:47.187325631Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 05:17:47.187644 containerd[2018]: time="2025-09-12T05:17:47.187606871Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 05:17:47.187815 containerd[2018]: time="2025-09-12T05:17:47.187797031Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 05:17:47.187957 containerd[2018]: time="2025-09-12T05:17:47.187939791Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 05:17:47.188057 containerd[2018]: time="2025-09-12T05:17:47.188041791Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 05:17:47.188124 containerd[2018]: time="2025-09-12T05:17:47.188110551Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 05:17:47.188173 containerd[2018]: time="2025-09-12T05:17:47.188161391Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 05:17:47.188234 containerd[2018]: time="2025-09-12T05:17:47.188221191Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 05:17:47.188285 containerd[2018]: time="2025-09-12T05:17:47.188273111Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 05:17:47.188340 containerd[2018]: time="2025-09-12T05:17:47.188327751Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 05:17:47.188396 containerd[2018]: time="2025-09-12T05:17:47.188382551Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 05:17:47.188486 containerd[2018]: time="2025-09-12T05:17:47.188471231Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 05:17:47.188554 containerd[2018]: time="2025-09-12T05:17:47.188539471Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 05:17:47.188609 containerd[2018]: time="2025-09-12T05:17:47.188597271Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 05:17:47.188707 containerd[2018]: time="2025-09-12T05:17:47.188691871Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 05:17:47.188776 containerd[2018]: time="2025-09-12T05:17:47.188760671Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 05:17:47.188821 containerd[2018]: time="2025-09-12T05:17:47.188809151Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 05:17:47.188869 containerd[2018]: time="2025-09-12T05:17:47.188855711Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 05:17:47.188918 containerd[2018]: time="2025-09-12T05:17:47.188905351Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 05:17:47.188974 containerd[2018]: time="2025-09-12T05:17:47.188961751Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 05:17:47.189030 containerd[2018]: time="2025-09-12T05:17:47.189017071Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 05:17:47.189091 containerd[2018]: time="2025-09-12T05:17:47.189079031Z" level=info msg="runtime interface created" Sep 12 05:17:47.189139 containerd[2018]: time="2025-09-12T05:17:47.189126871Z" level=info msg="created NRI interface" Sep 12 05:17:47.189193 containerd[2018]: time="2025-09-12T05:17:47.189179551Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 05:17:47.189246 containerd[2018]: time="2025-09-12T05:17:47.189234511Z" level=info msg="Connect containerd service" Sep 12 05:17:47.189319 containerd[2018]: time="2025-09-12T05:17:47.189305951Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 05:17:47.189954 containerd[2018]: time="2025-09-12T05:17:47.189926151Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 12 05:17:47.202403 containerd[2018]: time="2025-09-12T05:17:47.202341071Z" level=info msg="Start subscribing containerd event" Sep 12 05:17:47.202403 containerd[2018]: time="2025-09-12T05:17:47.202408311Z" level=info msg="Start recovering state" Sep 12 05:17:47.202530 containerd[2018]: time="2025-09-12T05:17:47.202490791Z" level=info msg="Start event monitor" Sep 12 05:17:47.202530 containerd[2018]: time="2025-09-12T05:17:47.202504351Z" level=info msg="Start cni network conf syncer for default" Sep 12 05:17:47.202530 containerd[2018]: time="2025-09-12T05:17:47.202511911Z" level=info msg="Start streaming server" Sep 12 05:17:47.202530 containerd[2018]: time="2025-09-12T05:17:47.202519551Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 05:17:47.202530 containerd[2018]: time="2025-09-12T05:17:47.202525831Z" level=info msg="runtime interface starting up..." Sep 12 05:17:47.202530 containerd[2018]: time="2025-09-12T05:17:47.202531191Z" level=info msg="starting plugins..." Sep 12 05:17:47.202637 containerd[2018]: time="2025-09-12T05:17:47.202539511Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 05:17:47.202784 containerd[2018]: time="2025-09-12T05:17:47.202760151Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 05:17:47.202875 containerd[2018]: time="2025-09-12T05:17:47.202862111Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 05:17:47.203168 containerd[2018]: time="2025-09-12T05:17:47.202970431Z" level=info msg="containerd successfully booted in 0.063708s" Sep 12 05:17:47.203079 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 05:17:47.205665 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 05:17:47.218949 (dockerd)[2048]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 12 05:17:47.250156 dockerd[2048]: time="2025-09-12T05:17:47.250095751Z" level=info msg="Starting up" Sep 12 05:17:47.250868 dockerd[2048]: time="2025-09-12T05:17:47.250840471Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 12 05:17:47.260186 dockerd[2048]: time="2025-09-12T05:17:47.260154191Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 12 05:17:47.277844 dockerd[2048]: time="2025-09-12T05:17:47.277800471Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 12 05:17:47.285590 dockerd[2048]: time="2025-09-12T05:17:47.285531511Z" level=info msg="Loading containers: start." Sep 12 05:17:51.582315 systemd[1]: Started sshd@14-10.0.0.33:22-10.0.0.1:36896.service - OpenSSH per-connection server daemon (10.0.0.1:36896). Sep 12 05:17:51.625863 sshd[2080]: Accepted publickey for core from 10.0.0.1 port 36896 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:51.627116 sshd-session[2080]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:51.630973 systemd-logind[1430]: New session 15 of user core. Sep 12 05:17:51.640699 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 12 05:17:51.694464 sshd[2083]: Connection closed by 10.0.0.1 port 36896 Sep 12 05:17:51.694944 sshd-session[2080]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:51.713395 systemd[1]: sshd@14-10.0.0.33:22-10.0.0.1:36896.service: Deactivated successfully. Sep 12 05:17:51.715036 systemd[1]: session-15.scope: Deactivated successfully. Sep 12 05:17:51.716212 systemd-logind[1430]: Session 15 logged out. Waiting for processes to exit. Sep 12 05:17:51.719341 systemd[1]: Started sshd@15-10.0.0.33:22-10.0.0.1:36900.service - OpenSSH per-connection server daemon (10.0.0.1:36900). Sep 12 05:17:51.719796 systemd-logind[1430]: Removed session 15. Sep 12 05:17:51.774251 sshd[2089]: Accepted publickey for core from 10.0.0.1 port 36900 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:51.775484 sshd-session[2089]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:51.779914 systemd-logind[1430]: New session 16 of user core. Sep 12 05:17:51.788584 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 12 05:17:51.841226 sshd[2092]: Connection closed by 10.0.0.1 port 36900 Sep 12 05:17:51.841473 sshd-session[2089]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:51.858250 systemd[1]: sshd@15-10.0.0.33:22-10.0.0.1:36900.service: Deactivated successfully. Sep 12 05:17:51.860633 systemd[1]: session-16.scope: Deactivated successfully. Sep 12 05:17:51.861253 systemd-logind[1430]: Session 16 logged out. Waiting for processes to exit. Sep 12 05:17:51.863100 systemd[1]: Started sshd@16-10.0.0.33:22-10.0.0.1:36908.service - OpenSSH per-connection server daemon (10.0.0.1:36908). Sep 12 05:17:51.863924 systemd-logind[1430]: Removed session 16. Sep 12 05:17:51.925739 sshd[2098]: Accepted publickey for core from 10.0.0.1 port 36908 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:51.926879 sshd-session[2098]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:51.930677 systemd-logind[1430]: New session 17 of user core. Sep 12 05:17:51.938574 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 12 05:17:57.314172 dockerd[2048]: time="2025-09-12T05:17:57.314086469Z" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de Sep 12 05:17:57.324654 systemd[1]: docker-c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de.scope: Deactivated successfully. Sep 12 05:17:57.337628 dockerd[2048]: time="2025-09-12T05:17:57.337587369Z" level=info msg="ignoring event" container=c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 12 05:17:57.337808 containerd[2018]: time="2025-09-12T05:17:57.337768646Z" level=info msg="shim disconnected" id=c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de namespace=moby Sep 12 05:17:57.338034 containerd[2018]: time="2025-09-12T05:17:57.337802366Z" level=warning msg="cleaning up after shim disconnected" id=c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de namespace=moby Sep 12 05:17:57.338034 containerd[2018]: time="2025-09-12T05:17:57.337831725Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 05:17:57.350577 systemd[1]: var-lib-docker-overlay2-58947e6d01172eb97bbe8fbf7ce9acbefc4d45b072ee72eed5cda9f391dc3972-merged.mount: Deactivated successfully. Sep 12 05:17:57.758086 systemd[1]: run-docker-netns-8cccf74048bb.mount: Deactivated successfully. Sep 12 05:17:57.758630 dockerd[2048]: time="2025-09-12T05:17:57.758548399Z" level=info msg="Removing stale sandbox 8cccf74048bb65b0a81c7a954eaa7ff468bade38d77b8074ef215ac1f7aa2799 (c9d8951a581f17aa165ccc07ba13e5b15781a0a8f22ebb7a68c826e2e21543de)" Sep 12 05:17:57.760107 systemd-networkd[1392]: veth2546e6b: Link DOWN Sep 12 05:17:57.760113 systemd-networkd[1392]: veth2546e6b: Lost carrier Sep 12 05:17:57.761465 kernel: docker0: port 1(veth2546e6b) entered disabled state Sep 12 05:17:57.762366 dockerd[2048]: time="2025-09-12T05:17:57.762342944Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 12 05:17:57.762564 dockerd[2048]: time="2025-09-12T05:17:57.762484382Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 6ff49a0bdc6af6f4472900f7d7002d2d1b15e3b7bfc13fb8d77b599def2b4214 8c7818ec789ab516d89aa7656cc24937d449ef79d1fe5ba64c98615af0cd8d61], retrying...." Sep 12 05:17:57.764119 kernel: veth2546e6b (unregistering): left allmulticast mode Sep 12 05:17:57.764167 kernel: veth2546e6b (unregistering): left promiscuous mode Sep 12 05:17:57.764183 kernel: docker0: port 1(veth2546e6b) entered disabled state Sep 12 05:17:57.766218 systemd-networkd[1392]: docker0: Lost carrier Sep 12 05:17:57.768379 systemd[1]: run-docker-netns-8cccf74048bb.mount: Deactivated successfully. Sep 12 05:17:57.922598 dockerd[2048]: time="2025-09-12T05:17:57.922559346Z" level=warning msg="error locating sandbox id 8cccf74048bb65b0a81c7a954eaa7ff468bade38d77b8074ef215ac1f7aa2799: sandbox 8cccf74048bb65b0a81c7a954eaa7ff468bade38d77b8074ef215ac1f7aa2799 not found" Sep 12 05:17:57.922710 dockerd[2048]: time="2025-09-12T05:17:57.922681664Z" level=info msg="Loading containers: done." Sep 12 05:17:57.935554 dockerd[2048]: time="2025-09-12T05:17:57.935506158Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 12 05:17:57.935666 dockerd[2048]: time="2025-09-12T05:17:57.935584357Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 12 05:17:57.935666 dockerd[2048]: time="2025-09-12T05:17:57.935615237Z" level=info msg="Initializing buildkit" Sep 12 05:17:57.946634 dockerd[2048]: time="2025-09-12T05:17:57.946599158Z" level=info msg="Completed buildkit initialization" Sep 12 05:17:57.953498 dockerd[2048]: time="2025-09-12T05:17:57.953462539Z" level=info msg="Daemon has completed initialization" Sep 12 05:17:57.953591 dockerd[2048]: time="2025-09-12T05:17:57.953521978Z" level=info msg="API listen on /run/docker.sock" Sep 12 05:17:57.953693 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 05:17:57.961876 sshd[2101]: Connection closed by 10.0.0.1 port 36908 Sep 12 05:17:57.961290 sshd-session[2098]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:57.971298 systemd[1]: sshd@16-10.0.0.33:22-10.0.0.1:36908.service: Deactivated successfully. Sep 12 05:17:57.973713 systemd[1]: session-17.scope: Deactivated successfully. Sep 12 05:17:57.974994 systemd-logind[1430]: Session 17 logged out. Waiting for processes to exit. Sep 12 05:17:57.976549 systemd[1]: Started sshd@17-10.0.0.33:22-10.0.0.1:36922.service - OpenSSH per-connection server daemon (10.0.0.1:36922). Sep 12 05:17:57.977763 systemd-logind[1430]: Removed session 17. Sep 12 05:17:58.022295 sshd[2352]: Accepted publickey for core from 10.0.0.1 port 36922 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:58.023374 sshd-session[2352]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:58.027202 systemd-logind[1430]: New session 18 of user core. Sep 12 05:17:58.035647 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 12 05:17:58.136039 containerd[2018]: time="2025-09-12T05:17:58.135987580Z" level=info msg="connecting to shim f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8" address="unix:///run/containerd/s/179b9038dfba8bfa97485e4c88029d38065a081f5d131f77c2792f03ce54aa35" namespace=moby protocol=ttrpc version=3 Sep 12 05:17:58.165684 systemd[1]: Started docker-f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8.scope - libcontainer container f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8. Sep 12 05:17:58.176199 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 05:17:58.192463 kernel: docker0: port 1(veth392792e) entered blocking state Sep 12 05:17:58.192549 kernel: docker0: port 1(veth392792e) entered disabled state Sep 12 05:17:58.192567 kernel: veth392792e: entered allmulticast mode Sep 12 05:17:58.192582 kernel: veth392792e: entered promiscuous mode Sep 12 05:17:58.193267 systemd-networkd[1392]: veth392792e: Link UP Sep 12 05:17:58.228468 kernel: eth0: renamed from veth0226bfa Sep 12 05:17:58.230031 kernel: docker0: port 1(veth392792e) entered blocking state Sep 12 05:17:58.230067 kernel: docker0: port 1(veth392792e) entered forwarding state Sep 12 05:17:58.230166 systemd-networkd[1392]: veth392792e: Gained carrier Sep 12 05:17:58.230497 systemd-networkd[1392]: docker0: Gained carrier Sep 12 05:17:58.248578 sshd[2355]: Connection closed by 10.0.0.1 port 36922 Sep 12 05:17:58.249765 sshd-session[2352]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:58.266168 systemd[1]: sshd@17-10.0.0.33:22-10.0.0.1:36922.service: Deactivated successfully. Sep 12 05:17:58.268691 systemd[1]: session-18.scope: Deactivated successfully. Sep 12 05:17:58.269544 systemd-logind[1430]: Session 18 logged out. Waiting for processes to exit. Sep 12 05:17:58.271776 systemd[1]: Started sshd@18-10.0.0.33:22-10.0.0.1:36934.service - OpenSSH per-connection server daemon (10.0.0.1:36934). Sep 12 05:17:58.272475 systemd-logind[1430]: Removed session 18. Sep 12 05:17:58.326295 sshd[2422]: Accepted publickey for core from 10.0.0.1 port 36934 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:58.327592 sshd-session[2422]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:58.332059 systemd-logind[1430]: New session 19 of user core. Sep 12 05:17:58.339611 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 12 05:17:58.351587 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2938691668-merged.mount: Deactivated successfully. Sep 12 05:17:58.395389 sshd[2425]: Connection closed by 10.0.0.1 port 36934 Sep 12 05:17:58.395884 sshd-session[2422]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:58.404532 systemd[1]: sshd@18-10.0.0.33:22-10.0.0.1:36934.service: Deactivated successfully. Sep 12 05:17:58.406850 systemd[1]: session-19.scope: Deactivated successfully. Sep 12 05:17:58.408681 systemd-logind[1430]: Session 19 logged out. Waiting for processes to exit. Sep 12 05:17:58.410485 systemd[1]: Started sshd@19-10.0.0.33:22-10.0.0.1:36944.service - OpenSSH per-connection server daemon (10.0.0.1:36944). Sep 12 05:17:58.411413 systemd-logind[1430]: Removed session 19. Sep 12 05:17:58.459126 sshd[2431]: Accepted publickey for core from 10.0.0.1 port 36944 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:58.460214 sshd-session[2431]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:58.463870 systemd-logind[1430]: New session 20 of user core. Sep 12 05:17:58.476600 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 12 05:17:58.532851 sudo[2435]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 2018 Sep 12 05:17:58.533102 sudo[2435]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 05:17:58.536506 sudo[2435]: pam_unix(sudo:session): session closed for user root Sep 12 05:17:58.537472 sshd[2434]: Connection closed by 10.0.0.1 port 36944 Sep 12 05:17:58.537841 sshd-session[2431]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:58.538733 dockerd[2048]: time="2025-09-12T05:17:58.538688718Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 12 05:17:58.538733 dockerd[2048]: time="2025-09-12T05:17:58.538725077Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 12 05:17:58.540644 dockerd[2048]: time="2025-09-12T05:17:58.539245990Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 12 05:17:58.540644 dockerd[2048]: time="2025-09-12T05:17:58.539264710Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 12 05:17:58.550273 systemd[1]: containerd.service: Deactivated successfully. Sep 12 05:17:58.550370 systemd[1]: containerd.service: Unit process 2374 (containerd-shim) remains running after unit stopped. Sep 12 05:17:58.550614 systemd[1]: containerd.service: Consumed 220ms CPU time, 123.7M memory peak. Sep 12 05:17:58.550973 systemd[1]: sshd@19-10.0.0.33:22-10.0.0.1:36944.service: Deactivated successfully. Sep 12 05:17:58.552414 systemd[1]: session-20.scope: Deactivated successfully. Sep 12 05:17:58.554634 systemd-logind[1430]: Session 20 logged out. Waiting for processes to exit. Sep 12 05:17:58.557425 systemd[1]: Started sshd@20-10.0.0.33:22-10.0.0.1:36946.service - OpenSSH per-connection server daemon (10.0.0.1:36946). Sep 12 05:17:58.558251 systemd-logind[1430]: Removed session 20. Sep 12 05:17:58.610703 sshd[2442]: Accepted publickey for core from 10.0.0.1 port 36946 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:17:58.611884 sshd-session[2442]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:17:58.615740 systemd-logind[1430]: New session 21 of user core. Sep 12 05:17:58.628668 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 12 05:17:58.681088 sshd[2445]: Connection closed by 10.0.0.1 port 36946 Sep 12 05:17:58.681376 sshd-session[2442]: pam_unix(sshd:session): session closed for user core Sep 12 05:17:58.684460 systemd[1]: sshd@20-10.0.0.33:22-10.0.0.1:36946.service: Deactivated successfully. Sep 12 05:17:58.685977 systemd[1]: session-21.scope: Deactivated successfully. Sep 12 05:17:58.688042 systemd-logind[1430]: Session 21 logged out. Waiting for processes to exit. Sep 12 05:17:58.689077 systemd-logind[1430]: Removed session 21. Sep 12 05:18:00.004638 systemd-networkd[1392]: veth392792e: Gained IPv6LL Sep 12 05:18:02.032991 update_engine[1441]: I20250912 05:18:02.032871 1441 update_attempter.cc:509] Updating boot flags... Sep 12 05:18:03.563202 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Sep 12 05:18:03.563573 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 12 05:18:03.564106 dockerd[2048]: time="2025-09-12T05:18:03.563696379Z" level=info msg="Processing signal 'terminated'" Sep 12 05:18:04.696231 systemd[1]: Started sshd@21-10.0.0.33:22-10.0.0.1:51534.service - OpenSSH per-connection server daemon (10.0.0.1:51534). Sep 12 05:18:04.758706 sshd[2465]: Accepted publickey for core from 10.0.0.1 port 51534 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:18:04.759818 sshd-session[2465]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:18:04.764877 systemd-logind[1430]: New session 22 of user core. Sep 12 05:18:04.777019 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 12 05:18:04.833273 sshd[2468]: Connection closed by 10.0.0.1 port 51534 Sep 12 05:18:04.833739 sshd-session[2465]: pam_unix(sshd:session): session closed for user core Sep 12 05:18:04.838199 systemd[1]: sshd@21-10.0.0.33:22-10.0.0.1:51534.service: Deactivated successfully. Sep 12 05:18:04.839629 systemd[1]: session-22.scope: Deactivated successfully. Sep 12 05:18:04.842134 systemd-logind[1430]: Session 22 logged out. Waiting for processes to exit. Sep 12 05:18:04.843521 systemd-logind[1430]: Removed session 22. Sep 12 05:18:05.565825 dockerd[2048]: time="2025-09-12T05:18:05.565577922Z" level=error msg="Error sending stop (signal 15) to container" container=f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8 error="Cannot kill container f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Sep 12 05:18:05.565825 dockerd[2048]: time="2025-09-12T05:18:05.565647722Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8 Sep 12 05:18:10.848380 systemd[1]: Started sshd@22-10.0.0.33:22-10.0.0.1:44502.service - OpenSSH per-connection server daemon (10.0.0.1:44502). Sep 12 05:18:10.916288 sshd[2474]: Accepted publickey for core from 10.0.0.1 port 44502 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:18:10.917709 sshd-session[2474]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:18:10.922465 systemd-logind[1430]: New session 23 of user core. Sep 12 05:18:10.932568 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 12 05:18:10.985581 sshd[2477]: Connection closed by 10.0.0.1 port 44502 Sep 12 05:18:10.986174 sshd-session[2474]: pam_unix(sshd:session): session closed for user core Sep 12 05:18:10.988771 systemd[1]: sshd@22-10.0.0.33:22-10.0.0.1:44502.service: Deactivated successfully. Sep 12 05:18:10.990128 systemd[1]: session-23.scope: Deactivated successfully. Sep 12 05:18:10.991739 systemd-logind[1430]: Session 23 logged out. Waiting for processes to exit. Sep 12 05:18:10.992644 systemd-logind[1430]: Removed session 23. Sep 12 05:18:15.566103 dockerd[2048]: time="2025-09-12T05:18:15.566028860Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8 error="context deadline exceeded" Sep 12 05:18:15.566899 systemd[1]: docker-f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8.scope: Deactivated successfully. Sep 12 05:18:16.999913 systemd[1]: Started sshd@23-10.0.0.33:22-10.0.0.1:44514.service - OpenSSH per-connection server daemon (10.0.0.1:44514). Sep 12 05:18:17.064619 sshd[2483]: Accepted publickey for core from 10.0.0.1 port 44514 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:18:17.066537 sshd-session[2483]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:18:17.071065 systemd-logind[1430]: New session 24 of user core. Sep 12 05:18:17.090587 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 12 05:18:17.143703 sshd[2486]: Connection closed by 10.0.0.1 port 44514 Sep 12 05:18:17.143984 sshd-session[2483]: pam_unix(sshd:session): session closed for user core Sep 12 05:18:17.147067 systemd[1]: sshd@23-10.0.0.33:22-10.0.0.1:44514.service: Deactivated successfully. Sep 12 05:18:17.148584 systemd[1]: session-24.scope: Deactivated successfully. Sep 12 05:18:17.150745 systemd-logind[1430]: Session 24 logged out. Waiting for processes to exit. Sep 12 05:18:17.151702 systemd-logind[1430]: Removed session 24. Sep 12 05:18:18.565484 dockerd[2048]: time="2025-09-12T05:18:18.565372292Z" level=error msg="Force shutdown daemon" Sep 12 05:18:18.565840 dockerd[2048]: time="2025-09-12T05:18:18.565567411Z" level=info msg="Daemon shutdown complete" Sep 12 05:18:18.565840 dockerd[2048]: time="2025-09-12T05:18:18.565686411Z" level=warning msg="Error while testing if containerd API is ready" error="Canceled: latest balancer error: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Sep 12 05:18:18.565840 dockerd[2048]: time="2025-09-12T05:18:18.565709091Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby Sep 12 05:18:18.569700 systemd[1]: docker.service: Deactivated successfully. Sep 12 05:18:18.570045 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 12 05:18:18.570552 systemd[1]: docker.socket: Deactivated successfully. Sep 12 05:18:18.570811 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 12 05:18:18.570848 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 12 05:18:18.572220 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 05:18:18.572412 systemd[1]: containerd.service: Found left-over process 2374 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 05:18:18.572421 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 05:18:18.582460 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 05:18:18.582712 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 05:18:18.595510 systemd[1]: containerd.service: Found left-over process 2374 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 05:18:18.595556 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 05:18:18.602842 (ntainerd)[2496]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 05:18:18.617577 containerd[2496]: time="2025-09-12T05:18:18Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 05:18:18.618098 containerd[2496]: time="2025-09-12T05:18:18.618059896Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 05:18:18.626548 containerd[2496]: time="2025-09-12T05:18:18.626496824Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.32µs" Sep 12 05:18:18.626548 containerd[2496]: time="2025-09-12T05:18:18.626533464Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 05:18:18.626548 containerd[2496]: time="2025-09-12T05:18:18.626553024Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 05:18:18.626667 containerd[2496]: time="2025-09-12T05:18:18.626587144Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 05:18:18.626667 containerd[2496]: time="2025-09-12T05:18:18.626605184Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 05:18:18.626849 containerd[2496]: time="2025-09-12T05:18:18.626782503Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 05:18:18.626849 containerd[2496]: time="2025-09-12T05:18:18.626814583Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 05:18:18.626849 containerd[2496]: time="2025-09-12T05:18:18.626824663Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627036 containerd[2496]: time="2025-09-12T05:18:18.627014782Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627036 containerd[2496]: time="2025-09-12T05:18:18.627032822Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627131 containerd[2496]: time="2025-09-12T05:18:18.627044062Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627131 containerd[2496]: time="2025-09-12T05:18:18.627052302Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627131 containerd[2496]: time="2025-09-12T05:18:18.627077542Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627195 containerd[2496]: time="2025-09-12T05:18:18.627157582Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627195 containerd[2496]: time="2025-09-12T05:18:18.627178142Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 05:18:18.627195 containerd[2496]: time="2025-09-12T05:18:18.627187102Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 05:18:18.627271 containerd[2496]: time="2025-09-12T05:18:18.627216062Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 05:18:18.627537 containerd[2496]: time="2025-09-12T05:18:18.627487981Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 05:18:18.627537 containerd[2496]: time="2025-09-12T05:18:18.627523420Z" level=info msg="metadata content store policy set" policy=shared Sep 12 05:18:18.627721 containerd[2496]: time="2025-09-12T05:18:18.627673620Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 05:18:18.627721 containerd[2496]: time="2025-09-12T05:18:18.627719140Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 05:18:18.627765 containerd[2496]: time="2025-09-12T05:18:18.627732220Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 05:18:18.627765 containerd[2496]: time="2025-09-12T05:18:18.627755780Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 05:18:18.627799 containerd[2496]: time="2025-09-12T05:18:18.627775379Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 05:18:18.627799 containerd[2496]: time="2025-09-12T05:18:18.627788219Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 05:18:18.627937 containerd[2496]: time="2025-09-12T05:18:18.627798859Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 05:18:18.627937 containerd[2496]: time="2025-09-12T05:18:18.627836179Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 05:18:18.627937 containerd[2496]: time="2025-09-12T05:18:18.627848619Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 05:18:18.627937 containerd[2496]: time="2025-09-12T05:18:18.627858659Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 05:18:18.627937 containerd[2496]: time="2025-09-12T05:18:18.627868139Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 05:18:18.627937 containerd[2496]: time="2025-09-12T05:18:18.627879819Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 05:18:18.628272 containerd[2496]: time="2025-09-12T05:18:18.628065538Z" level=info msg="connecting to shim f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8" address="unix:///run/containerd/s/179b9038dfba8bfa97485e4c88029d38065a081f5d131f77c2792f03ce54aa35" namespace=moby protocol=ttrpc version=3 Sep 12 05:18:18.635843 containerd[2496]: time="2025-09-12T05:18:18.635815469Z" level=info msg="cleaning leaked shim process" id=f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8 namespace=moby Sep 12 05:18:18.645449 containerd[2496]: time="2025-09-12T05:18:18.644922596Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Sep 12 05:18:18.645449 containerd[2496]: time="2025-09-12T05:18:18.645184795Z" level=info msg="shim disconnected" id=f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8 namespace=moby Sep 12 05:18:18.645449 containerd[2496]: time="2025-09-12T05:18:18.645205794Z" level=warning msg="cleaning up after shim disconnected" id=f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8 namespace=moby Sep 12 05:18:18.645449 containerd[2496]: time="2025-09-12T05:18:18.645265354Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657708628Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657748908Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657765468Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657777148Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657788748Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657799187Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657812307Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657822747Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657834547Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657844867Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657855827Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657919067Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657934747Z" level=info msg="Start snapshots syncer" Sep 12 05:18:18.659444 containerd[2496]: time="2025-09-12T05:18:18.657957387Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 05:18:18.659809 containerd[2496]: time="2025-09-12T05:18:18.658156506Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 05:18:18.659809 containerd[2496]: time="2025-09-12T05:18:18.658207906Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658282946Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658332266Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658350625Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658360705Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658370625Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658381665Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658392865Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658403665Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658428825Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658460065Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658475145Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658508345Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658523825Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 05:18:18.659892 containerd[2496]: time="2025-09-12T05:18:18.658532985Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658543065Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658551185Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658565625Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658577785Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658593705Z" level=info msg="runtime interface created" Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658598625Z" level=info msg="created NRI interface" Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658607144Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658617984Z" level=info msg="Connect containerd service" Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.658642424Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 05:18:18.660123 containerd[2496]: time="2025-09-12T05:18:18.659053463Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 12 05:18:18.672295 containerd[2496]: time="2025-09-12T05:18:18.672166254Z" level=info msg="Start subscribing containerd event" Sep 12 05:18:18.672295 containerd[2496]: time="2025-09-12T05:18:18.672236694Z" level=info msg="Start recovering state" Sep 12 05:18:18.672496 containerd[2496]: time="2025-09-12T05:18:18.672480213Z" level=info msg="Start event monitor" Sep 12 05:18:18.672829 containerd[2496]: time="2025-09-12T05:18:18.672763372Z" level=info msg="Start cni network conf syncer for default" Sep 12 05:18:18.679402 containerd[2496]: time="2025-09-12T05:18:18.673819728Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 05:18:18.679520 containerd[2496]: time="2025-09-12T05:18:18.679356827Z" level=info msg="Start streaming server" Sep 12 05:18:18.679570 containerd[2496]: time="2025-09-12T05:18:18.679523466Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 05:18:18.679570 containerd[2496]: time="2025-09-12T05:18:18.679534506Z" level=info msg="runtime interface starting up..." Sep 12 05:18:18.679570 containerd[2496]: time="2025-09-12T05:18:18.679540306Z" level=info msg="starting plugins..." Sep 12 05:18:18.679570 containerd[2496]: time="2025-09-12T05:18:18.679554786Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 05:18:18.679769 containerd[2496]: time="2025-09-12T05:18:18.679502907Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 05:18:18.679769 containerd[2496]: time="2025-09-12T05:18:18.679696386Z" level=info msg="containerd successfully booted in 0.062309s" Sep 12 05:18:18.679992 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 05:18:18.682281 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 05:18:18.706881 (dockerd)[2546]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 12 05:18:18.731768 dockerd[2546]: time="2025-09-12T05:18:18.731057554Z" level=info msg="Starting up" Sep 12 05:18:18.732156 dockerd[2546]: time="2025-09-12T05:18:18.732132750Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 12 05:18:18.741228 dockerd[2546]: time="2025-09-12T05:18:18.741165517Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 12 05:18:18.763219 dockerd[2546]: time="2025-09-12T05:18:18.763182674Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 12 05:18:18.770691 dockerd[2546]: time="2025-09-12T05:18:18.770658927Z" level=info msg="Loading containers: start." Sep 12 05:18:18.789668 systemd[1]: var-lib-docker-overlay2-8b6ebae137a3d6b5d26a9b76b84a2964a14370b834981d7c68058995ea331b55-merged.mount: Deactivated successfully. Sep 12 05:18:19.323963 systemd-networkd[1392]: veth392792e: Link DOWN Sep 12 05:18:19.324676 kernel: docker0: port 1(veth392792e) entered disabled state Sep 12 05:18:19.324702 kernel: veth392792e (unregistering): left allmulticast mode Sep 12 05:18:19.323973 systemd-networkd[1392]: veth392792e: Lost carrier Sep 12 05:18:19.326034 kernel: veth392792e (unregistering): left promiscuous mode Sep 12 05:18:19.326095 kernel: docker0: port 1(veth392792e) entered disabled state Sep 12 05:18:19.328599 systemd-networkd[1392]: docker0: Lost carrier Sep 12 05:18:19.329388 dockerd[2546]: time="2025-09-12T05:18:19.329336799Z" level=info msg="Removing stale sandbox 58292612c1a0b6e2dca6c325f5f37b294f055ca215c847b31dc95e535a82f1ea (f9ab24c3200a308968273780503ba6de8e56cc1d3b7fedfaa8c546bd052184c8)" Sep 12 05:18:19.332805 dockerd[2546]: time="2025-09-12T05:18:19.332726507Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 12 05:18:19.332805 dockerd[2546]: time="2025-09-12T05:18:19.332773067Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 2d65485b0e158c61fbb7f891db432ca748a28880126663b919174619d8409029 818f06054acb020fd59d8fdf4bf6d0b6a50f1f839a938be34ec0e3e05fd58f1b], retrying...." Sep 12 05:18:19.582814 dockerd[2546]: time="2025-09-12T05:18:19.582629233Z" level=warning msg="error locating sandbox id 8cccf74048bb65b0a81c7a954eaa7ff468bade38d77b8074ef215ac1f7aa2799: sandbox 8cccf74048bb65b0a81c7a954eaa7ff468bade38d77b8074ef215ac1f7aa2799 not found" Sep 12 05:18:19.582814 dockerd[2546]: time="2025-09-12T05:18:19.582668633Z" level=warning msg="error locating sandbox id 58292612c1a0b6e2dca6c325f5f37b294f055ca215c847b31dc95e535a82f1ea: sandbox 58292612c1a0b6e2dca6c325f5f37b294f055ca215c847b31dc95e535a82f1ea not found" Sep 12 05:18:19.582814 dockerd[2546]: time="2025-09-12T05:18:19.582736713Z" level=info msg="Loading containers: done." Sep 12 05:18:19.596306 dockerd[2546]: time="2025-09-12T05:18:19.596257865Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 12 05:18:19.596484 dockerd[2546]: time="2025-09-12T05:18:19.596336945Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 12 05:18:19.596484 dockerd[2546]: time="2025-09-12T05:18:19.596369265Z" level=info msg="Initializing buildkit" Sep 12 05:18:19.607278 dockerd[2546]: time="2025-09-12T05:18:19.607250787Z" level=info msg="Completed buildkit initialization" Sep 12 05:18:19.614904 dockerd[2546]: time="2025-09-12T05:18:19.614871240Z" level=info msg="Daemon has completed initialization" Sep 12 05:18:19.614987 dockerd[2546]: time="2025-09-12T05:18:19.614913160Z" level=info msg="API listen on /run/docker.sock" Sep 12 05:18:19.615138 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 05:18:19.748296 systemd[1]: run-docker-netns-58292612c1a0.mount: Deactivated successfully. Sep 12 05:18:23.155614 systemd[1]: Started sshd@24-10.0.0.33:22-10.0.0.1:32884.service - OpenSSH per-connection server daemon (10.0.0.1:32884). Sep 12 05:18:23.209422 sshd[2782]: Accepted publickey for core from 10.0.0.1 port 32884 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:18:23.210543 sshd-session[2782]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:18:23.214684 systemd-logind[1430]: New session 25 of user core. Sep 12 05:18:23.222595 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 12 05:18:23.276120 sshd[2785]: Connection closed by 10.0.0.1 port 32884 Sep 12 05:18:23.276462 sshd-session[2782]: pam_unix(sshd:session): session closed for user core Sep 12 05:18:23.290418 systemd[1]: sshd@24-10.0.0.33:22-10.0.0.1:32884.service: Deactivated successfully. Sep 12 05:18:23.292852 systemd[1]: session-25.scope: Deactivated successfully. Sep 12 05:18:23.294172 systemd-logind[1430]: Session 25 logged out. Waiting for processes to exit. Sep 12 05:18:23.296258 systemd[1]: Started sshd@25-10.0.0.33:22-10.0.0.1:32888.service - OpenSSH per-connection server daemon (10.0.0.1:32888). Sep 12 05:18:23.297288 systemd-logind[1430]: Removed session 25. Sep 12 05:18:23.349356 sshd[2791]: Accepted publickey for core from 10.0.0.1 port 32888 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:18:23.350584 sshd-session[2791]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:18:23.355314 systemd-logind[1430]: New session 26 of user core. Sep 12 05:18:23.364060 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 12 05:18:23.418308 sshd[2794]: Connection closed by 10.0.0.1 port 32888 Sep 12 05:18:23.418945 sshd-session[2791]: pam_unix(sshd:session): session closed for user core Sep 12 05:18:23.431391 systemd[1]: sshd@25-10.0.0.33:22-10.0.0.1:32888.service: Deactivated successfully. Sep 12 05:18:23.433808 systemd[1]: session-26.scope: Deactivated successfully. Sep 12 05:18:23.434478 systemd-logind[1430]: Session 26 logged out. Waiting for processes to exit. Sep 12 05:18:23.437269 systemd[1]: Started sshd@26-10.0.0.33:22-10.0.0.1:32904.service - OpenSSH per-connection server daemon (10.0.0.1:32904). Sep 12 05:18:23.438051 systemd-logind[1430]: Removed session 26. Sep 12 05:18:23.495737 sshd[2800]: Accepted publickey for core from 10.0.0.1 port 32904 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:18:23.496987 sshd-session[2800]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:18:23.502216 systemd-logind[1430]: New session 27 of user core. Sep 12 05:18:23.513596 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 12 05:18:23.572467 sshd[2803]: Connection closed by 10.0.0.1 port 32904 Sep 12 05:18:23.572103 sshd-session[2800]: pam_unix(sshd:session): session closed for user core Sep 12 05:18:23.583220 systemd[1]: sshd@26-10.0.0.33:22-10.0.0.1:32904.service: Deactivated successfully. Sep 12 05:18:23.585683 systemd[1]: session-27.scope: Deactivated successfully. Sep 12 05:18:23.587073 systemd-logind[1430]: Session 27 logged out. Waiting for processes to exit. Sep 12 05:18:23.589166 systemd[1]: Started sshd@27-10.0.0.33:22-10.0.0.1:32908.service - OpenSSH per-connection server daemon (10.0.0.1:32908). Sep 12 05:18:23.590792 systemd-logind[1430]: Removed session 27. Sep 12 05:18:23.637837 sshd[2816]: Accepted publickey for core from 10.0.0.1 port 32908 ssh2: RSA SHA256:PR7i5XslvAYjnQAzV40EIZ3pNaN1zwWfISSZ+xReTV4 Sep 12 05:18:23.639332 sshd-session[2816]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 05:18:23.643124 systemd-logind[1430]: New session 28 of user core. Sep 12 05:18:23.653604 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 12 05:18:23.737132 systemd[1]: var-lib-docker-overlay2-3226d5bf6fe0e805703677b53be53369c4fbd604b60a5e28ddccc3ef76e91f7f\x2dinit-merged.mount: Deactivated successfully. Sep 12 05:18:23.788064 containerd[2496]: time="2025-09-12T05:18:23.788023126Z" level=info msg="connecting to shim b35343abd5e20a0fefc210d44b40483eea226f5b99df7e37e36a0a071fb3b02d" address="unix:///run/containerd/s/bdc911aed7670b51f1321b5c30a63ff5d5b34c1a43ee443f5a2c252aaefe4186" namespace=moby protocol=ttrpc version=3 Sep 12 05:18:23.811608 systemd[1]: Started docker-b35343abd5e20a0fefc210d44b40483eea226f5b99df7e37e36a0a071fb3b02d.scope - libcontainer container b35343abd5e20a0fefc210d44b40483eea226f5b99df7e37e36a0a071fb3b02d. Sep 12 05:18:23.824638 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 05:18:23.848908 kernel: docker0: port 1(veth1e59e0c) entered blocking state Sep 12 05:18:23.848993 kernel: docker0: port 1(veth1e59e0c) entered disabled state Sep 12 05:18:23.849008 kernel: veth1e59e0c: entered allmulticast mode Sep 12 05:18:23.849023 kernel: veth1e59e0c: entered promiscuous mode Sep 12 05:18:23.848858 systemd-networkd[1392]: veth1e59e0c: Link UP Sep 12 05:18:23.856784 kernel: eth0: renamed from vetha796888 Sep 12 05:18:23.861100 kernel: docker0: port 1(veth1e59e0c) entered blocking state Sep 12 05:18:23.861232 kernel: docker0: port 1(veth1e59e0c) entered forwarding state Sep 12 05:18:23.861489 systemd-networkd[1392]: veth1e59e0c: Gained carrier Sep 12 05:18:23.861929 systemd-networkd[1392]: docker0: Gained carrier Sep 12 05:18:23.884799 sshd[2819]: Connection closed by 10.0.0.1 port 32908 Sep 12 05:18:23.885326 sshd-session[2816]: pam_unix(sshd:session): session closed for user core Sep 12 05:18:23.888869 systemd[1]: sshd@27-10.0.0.33:22-10.0.0.1:32908.service: Deactivated successfully. Sep 12 05:18:23.890662 systemd[1]: session-28.scope: Deactivated successfully. Sep 12 05:18:23.892029 systemd-logind[1430]: Session 28 logged out. Waiting for processes to exit. Sep 12 05:18:23.893312 systemd-logind[1430]: Removed session 28. Sep 12 05:18:25.220834 systemd-networkd[1392]: veth1e59e0c: Gained IPv6LL