Nov 23 22:47:23.837977 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 23 22:47:23.838001 kernel: Linux version 6.12.58-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 Sun Nov 23 20:49:09 -00 2025 Nov 23 22:47:23.838011 kernel: KASLR enabled Nov 23 22:47:23.838020 kernel: efi: EFI v2.7 by EDK II Nov 23 22:47:23.838028 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Nov 23 22:47:23.838043 kernel: random: crng init done Nov 23 22:47:23.838050 kernel: secureboot: Secure boot disabled Nov 23 22:47:23.838059 kernel: ACPI: Early table checksum verification disabled Nov 23 22:47:23.838065 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 23 22:47:23.838073 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 23 22:47:23.838079 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838086 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838092 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838098 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838105 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838113 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838120 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838126 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838132 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:47:23.838139 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 23 22:47:23.838145 kernel: ACPI: Use ACPI SPCR as default console: No Nov 23 22:47:23.838152 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 23 22:47:23.838158 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Nov 23 22:47:23.838164 kernel: Zone ranges: Nov 23 22:47:23.838171 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 23 22:47:23.838178 kernel: DMA32 empty Nov 23 22:47:23.838184 kernel: Normal empty Nov 23 22:47:23.838190 kernel: Device empty Nov 23 22:47:23.838196 kernel: Movable zone start for each node Nov 23 22:47:23.838202 kernel: Early memory node ranges Nov 23 22:47:23.838209 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Nov 23 22:47:23.838215 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Nov 23 22:47:23.838221 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Nov 23 22:47:23.838228 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 23 22:47:23.838234 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 23 22:47:23.838240 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 23 22:47:23.838246 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 23 22:47:23.838254 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 23 22:47:23.838260 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 23 22:47:23.838266 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 23 22:47:23.838275 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 23 22:47:23.838282 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 23 22:47:23.838288 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 23 22:47:23.838296 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 23 22:47:23.838303 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Nov 23 22:47:23.838310 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Nov 23 22:47:23.838316 kernel: psci: probing for conduit method from ACPI. Nov 23 22:47:23.838323 kernel: psci: PSCIv1.1 detected in firmware. Nov 23 22:47:23.838330 kernel: psci: Using standard PSCI v0.2 function IDs Nov 23 22:47:23.838337 kernel: psci: Trusted OS migration not required Nov 23 22:47:23.838344 kernel: psci: SMC Calling Convention v1.1 Nov 23 22:47:23.838352 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Nov 23 22:47:23.838358 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Nov 23 22:47:23.838367 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Nov 23 22:47:23.838374 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 23 22:47:23.838381 kernel: Detected PIPT I-cache on CPU0 Nov 23 22:47:23.838387 kernel: CPU features: detected: GIC system register CPU interface Nov 23 22:47:23.838394 kernel: CPU features: detected: Spectre-v4 Nov 23 22:47:23.838401 kernel: CPU features: detected: Spectre-BHB Nov 23 22:47:23.838407 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 23 22:47:23.838414 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 23 22:47:23.838421 kernel: CPU features: detected: ARM erratum 1418040 Nov 23 22:47:23.838428 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 23 22:47:23.838434 kernel: alternatives: applying boot alternatives Nov 23 22:47:23.838442 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=c01798725f53da1d62d166036caa3c72754cb158fe469d9d9e3df0d6cadc7a34 Nov 23 22:47:23.838450 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 23 22:47:23.838457 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 23 22:47:23.838478 kernel: Fallback order for Node 0: 0 Nov 23 22:47:23.838485 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Nov 23 22:47:23.838492 kernel: Policy zone: DMA Nov 23 22:47:23.838498 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 23 22:47:23.838505 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Nov 23 22:47:23.838512 kernel: software IO TLB: area num 4. Nov 23 22:47:23.838518 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Nov 23 22:47:23.838525 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Nov 23 22:47:23.838532 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 23 22:47:23.838542 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 23 22:47:23.838549 kernel: rcu: RCU event tracing is enabled. Nov 23 22:47:23.838556 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 23 22:47:23.838571 kernel: Trampoline variant of Tasks RCU enabled. Nov 23 22:47:23.838578 kernel: Tracing variant of Tasks RCU enabled. Nov 23 22:47:23.838586 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 23 22:47:23.838593 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 23 22:47:23.838601 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 23 22:47:23.838608 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 23 22:47:23.838616 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 23 22:47:23.838623 kernel: GICv3: 256 SPIs implemented Nov 23 22:47:23.838634 kernel: GICv3: 0 Extended SPIs implemented Nov 23 22:47:23.838641 kernel: Root IRQ handler: gic_handle_irq Nov 23 22:47:23.838648 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Nov 23 22:47:23.838655 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Nov 23 22:47:23.838662 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 23 22:47:23.838669 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 23 22:47:23.838676 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Nov 23 22:47:23.838683 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Nov 23 22:47:23.838690 kernel: GICv3: using LPI property table @0x0000000040130000 Nov 23 22:47:23.838697 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Nov 23 22:47:23.838704 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 23 22:47:23.838711 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:47:23.838719 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 23 22:47:23.838727 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 23 22:47:23.838735 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 23 22:47:23.838742 kernel: arm-pv: using stolen time PV Nov 23 22:47:23.838750 kernel: Console: colour dummy device 80x25 Nov 23 22:47:23.838757 kernel: ACPI: Core revision 20240827 Nov 23 22:47:23.838764 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 23 22:47:23.838771 kernel: pid_max: default: 32768 minimum: 301 Nov 23 22:47:23.838778 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 23 22:47:23.838785 kernel: landlock: Up and running. Nov 23 22:47:23.838807 kernel: SELinux: Initializing. Nov 23 22:47:23.838814 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 23 22:47:23.838821 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 23 22:47:23.838828 kernel: rcu: Hierarchical SRCU implementation. Nov 23 22:47:23.838835 kernel: rcu: Max phase no-delay instances is 400. Nov 23 22:47:23.838843 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 23 22:47:23.838849 kernel: Remapping and enabling EFI services. Nov 23 22:47:23.838856 kernel: smp: Bringing up secondary CPUs ... Nov 23 22:47:23.838863 kernel: Detected PIPT I-cache on CPU1 Nov 23 22:47:23.838876 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 23 22:47:23.838882 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Nov 23 22:47:23.838890 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:47:23.838898 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 23 22:47:23.838905 kernel: Detected PIPT I-cache on CPU2 Nov 23 22:47:23.838912 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 23 22:47:23.838919 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Nov 23 22:47:23.838927 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:47:23.838935 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 23 22:47:23.838943 kernel: Detected PIPT I-cache on CPU3 Nov 23 22:47:23.838950 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 23 22:47:23.838957 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Nov 23 22:47:23.838964 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:47:23.838971 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 23 22:47:23.838979 kernel: smp: Brought up 1 node, 4 CPUs Nov 23 22:47:23.838986 kernel: SMP: Total of 4 processors activated. Nov 23 22:47:23.838993 kernel: CPU: All CPU(s) started at EL1 Nov 23 22:47:23.839001 kernel: CPU features: detected: 32-bit EL0 Support Nov 23 22:47:23.839009 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 23 22:47:23.839016 kernel: CPU features: detected: Common not Private translations Nov 23 22:47:23.839023 kernel: CPU features: detected: CRC32 instructions Nov 23 22:47:23.839030 kernel: CPU features: detected: Enhanced Virtualization Traps Nov 23 22:47:23.839037 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 23 22:47:23.839044 kernel: CPU features: detected: LSE atomic instructions Nov 23 22:47:23.839052 kernel: CPU features: detected: Privileged Access Never Nov 23 22:47:23.839059 kernel: CPU features: detected: RAS Extension Support Nov 23 22:47:23.839068 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 23 22:47:23.839075 kernel: alternatives: applying system-wide alternatives Nov 23 22:47:23.839082 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Nov 23 22:47:23.839090 kernel: Memory: 2423776K/2572288K available (11200K kernel code, 2456K rwdata, 9084K rodata, 39552K init, 1038K bss, 126176K reserved, 16384K cma-reserved) Nov 23 22:47:23.839097 kernel: devtmpfs: initialized Nov 23 22:47:23.839104 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 23 22:47:23.839111 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 23 22:47:23.839118 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 23 22:47:23.839125 kernel: 0 pages in range for non-PLT usage Nov 23 22:47:23.839134 kernel: 508400 pages in range for PLT usage Nov 23 22:47:23.839141 kernel: pinctrl core: initialized pinctrl subsystem Nov 23 22:47:23.839148 kernel: SMBIOS 3.0.0 present. Nov 23 22:47:23.839155 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 23 22:47:23.839162 kernel: DMI: Memory slots populated: 1/1 Nov 23 22:47:23.839170 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 23 22:47:23.839177 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 23 22:47:23.839185 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 23 22:47:23.839192 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 23 22:47:23.839200 kernel: audit: initializing netlink subsys (disabled) Nov 23 22:47:23.839208 kernel: audit: type=2000 audit(0.025:1): state=initialized audit_enabled=0 res=1 Nov 23 22:47:23.839215 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 23 22:47:23.839222 kernel: cpuidle: using governor menu Nov 23 22:47:23.839229 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 23 22:47:23.839236 kernel: ASID allocator initialised with 32768 entries Nov 23 22:47:23.839243 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 23 22:47:23.839251 kernel: Serial: AMBA PL011 UART driver Nov 23 22:47:23.839258 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 23 22:47:23.839266 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 23 22:47:23.839273 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 23 22:47:23.839280 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 23 22:47:23.839287 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 23 22:47:23.839294 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 23 22:47:23.839301 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 23 22:47:23.839308 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 23 22:47:23.839315 kernel: ACPI: Added _OSI(Module Device) Nov 23 22:47:23.839322 kernel: ACPI: Added _OSI(Processor Device) Nov 23 22:47:23.839330 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 23 22:47:23.839337 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 23 22:47:23.839344 kernel: ACPI: Interpreter enabled Nov 23 22:47:23.839351 kernel: ACPI: Using GIC for interrupt routing Nov 23 22:47:23.839358 kernel: ACPI: MCFG table detected, 1 entries Nov 23 22:47:23.839365 kernel: ACPI: CPU0 has been hot-added Nov 23 22:47:23.839372 kernel: ACPI: CPU1 has been hot-added Nov 23 22:47:23.839378 kernel: ACPI: CPU2 has been hot-added Nov 23 22:47:23.839385 kernel: ACPI: CPU3 has been hot-added Nov 23 22:47:23.839393 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 23 22:47:23.839401 kernel: printk: legacy console [ttyAMA0] enabled Nov 23 22:47:23.839408 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 23 22:47:23.839584 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 23 22:47:23.839656 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 23 22:47:23.839716 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 23 22:47:23.839781 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 23 22:47:23.839845 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 23 22:47:23.839854 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 23 22:47:23.839861 kernel: PCI host bridge to bus 0000:00 Nov 23 22:47:23.839929 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 23 22:47:23.839985 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 23 22:47:23.840041 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 23 22:47:23.840096 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 23 22:47:23.840179 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Nov 23 22:47:23.840253 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Nov 23 22:47:23.840315 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Nov 23 22:47:23.840375 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Nov 23 22:47:23.840434 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Nov 23 22:47:23.840538 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Nov 23 22:47:23.840612 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Nov 23 22:47:23.840688 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Nov 23 22:47:23.840746 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 23 22:47:23.840798 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 23 22:47:23.840850 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 23 22:47:23.840859 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 23 22:47:23.840867 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 23 22:47:23.840874 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 23 22:47:23.840881 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 23 22:47:23.840890 kernel: iommu: Default domain type: Translated Nov 23 22:47:23.840897 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 23 22:47:23.840904 kernel: efivars: Registered efivars operations Nov 23 22:47:23.840911 kernel: vgaarb: loaded Nov 23 22:47:23.840917 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 23 22:47:23.840924 kernel: VFS: Disk quotas dquot_6.6.0 Nov 23 22:47:23.840932 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 23 22:47:23.840938 kernel: pnp: PnP ACPI init Nov 23 22:47:23.841016 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 23 22:47:23.841027 kernel: pnp: PnP ACPI: found 1 devices Nov 23 22:47:23.841034 kernel: NET: Registered PF_INET protocol family Nov 23 22:47:23.841042 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 23 22:47:23.841049 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 23 22:47:23.841056 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 23 22:47:23.841063 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 23 22:47:23.841070 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 23 22:47:23.841077 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 23 22:47:23.841086 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 23 22:47:23.841093 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 23 22:47:23.841100 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 23 22:47:23.841107 kernel: PCI: CLS 0 bytes, default 64 Nov 23 22:47:23.841119 kernel: kvm [1]: HYP mode not available Nov 23 22:47:23.841125 kernel: Initialise system trusted keyrings Nov 23 22:47:23.841133 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 23 22:47:23.841140 kernel: Key type asymmetric registered Nov 23 22:47:23.841146 kernel: Asymmetric key parser 'x509' registered Nov 23 22:47:23.841155 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Nov 23 22:47:23.841162 kernel: io scheduler mq-deadline registered Nov 23 22:47:23.841170 kernel: io scheduler kyber registered Nov 23 22:47:23.841177 kernel: io scheduler bfq registered Nov 23 22:47:23.841184 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 23 22:47:23.841191 kernel: ACPI: button: Power Button [PWRB] Nov 23 22:47:23.841199 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 23 22:47:23.841262 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 23 22:47:23.841271 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 23 22:47:23.841281 kernel: thunder_xcv, ver 1.0 Nov 23 22:47:23.841288 kernel: thunder_bgx, ver 1.0 Nov 23 22:47:23.841295 kernel: nicpf, ver 1.0 Nov 23 22:47:23.841302 kernel: nicvf, ver 1.0 Nov 23 22:47:23.841373 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 23 22:47:23.841429 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-23T22:47:23 UTC (1763938043) Nov 23 22:47:23.841438 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 23 22:47:23.841446 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Nov 23 22:47:23.841454 kernel: watchdog: NMI not fully supported Nov 23 22:47:23.841484 kernel: watchdog: Hard watchdog permanently disabled Nov 23 22:47:23.841496 kernel: NET: Registered PF_INET6 protocol family Nov 23 22:47:23.841504 kernel: Segment Routing with IPv6 Nov 23 22:47:23.841511 kernel: In-situ OAM (IOAM) with IPv6 Nov 23 22:47:23.841518 kernel: NET: Registered PF_PACKET protocol family Nov 23 22:47:23.841525 kernel: Key type dns_resolver registered Nov 23 22:47:23.841532 kernel: registered taskstats version 1 Nov 23 22:47:23.841539 kernel: Loading compiled-in X.509 certificates Nov 23 22:47:23.841549 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.58-flatcar: 98b0841f2908e51633cd38699ad12796cadb7bd1' Nov 23 22:47:23.841556 kernel: Demotion targets for Node 0: null Nov 23 22:47:23.841610 kernel: Key type .fscrypt registered Nov 23 22:47:23.841618 kernel: Key type fscrypt-provisioning registered Nov 23 22:47:23.841625 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 23 22:47:23.841632 kernel: ima: Allocated hash algorithm: sha1 Nov 23 22:47:23.841639 kernel: ima: No architecture policies found Nov 23 22:47:23.841647 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 23 22:47:23.841654 kernel: clk: Disabling unused clocks Nov 23 22:47:23.841661 kernel: PM: genpd: Disabling unused power domains Nov 23 22:47:23.841673 kernel: Warning: unable to open an initial console. Nov 23 22:47:23.841680 kernel: Freeing unused kernel memory: 39552K Nov 23 22:47:23.841687 kernel: Run /init as init process Nov 23 22:47:23.841694 kernel: with arguments: Nov 23 22:47:23.841702 kernel: /init Nov 23 22:47:23.841709 kernel: with environment: Nov 23 22:47:23.841715 kernel: HOME=/ Nov 23 22:47:23.841722 kernel: TERM=linux Nov 23 22:47:23.841730 systemd[1]: Successfully made /usr/ read-only. Nov 23 22:47:23.841742 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) Nov 23 22:47:23.841751 systemd[1]: Detected virtualization kvm. Nov 23 22:47:23.841758 systemd[1]: Detected architecture arm64. Nov 23 22:47:23.841765 systemd[1]: Running in initrd. Nov 23 22:47:23.841772 systemd[1]: No hostname configured, using default hostname. Nov 23 22:47:23.841781 systemd[1]: Hostname set to . Nov 23 22:47:23.841790 systemd[1]: Initializing machine ID from VM UUID. Nov 23 22:47:23.841798 systemd[1]: Queued start job for default target initrd.target. Nov 23 22:47:23.841805 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 23 22:47:23.841813 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 23 22:47:23.841821 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 23 22:47:23.841829 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 23 22:47:23.841837 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 23 22:47:23.841845 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 23 22:47:23.841855 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 23 22:47:23.841863 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 23 22:47:23.841870 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 23 22:47:23.841878 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 23 22:47:23.841886 systemd[1]: Reached target paths.target - Path Units. Nov 23 22:47:23.841893 systemd[1]: Reached target slices.target - Slice Units. Nov 23 22:47:23.841901 systemd[1]: Reached target swap.target - Swaps. Nov 23 22:47:23.841908 systemd[1]: Reached target timers.target - Timer Units. Nov 23 22:47:23.841918 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 23 22:47:23.841925 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 23 22:47:23.841933 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 23 22:47:23.841941 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 23 22:47:23.841948 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 23 22:47:23.841956 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 23 22:47:23.841964 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 23 22:47:23.841971 systemd[1]: Reached target sockets.target - Socket Units. Nov 23 22:47:23.841981 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 23 22:47:23.841989 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 23 22:47:23.841996 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 23 22:47:23.842004 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). Nov 23 22:47:23.842012 systemd[1]: Starting systemd-fsck-usr.service... Nov 23 22:47:23.842020 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 23 22:47:23.842027 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 23 22:47:23.842035 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:47:23.842043 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 23 22:47:23.842053 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 23 22:47:23.842061 systemd[1]: Finished systemd-fsck-usr.service. Nov 23 22:47:23.842095 systemd-journald[246]: Collecting audit messages is disabled. Nov 23 22:47:23.842117 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 23 22:47:23.842126 systemd-journald[246]: Journal started Nov 23 22:47:23.842155 systemd-journald[246]: Runtime Journal (/run/log/journal/a89c0508d25046c692a9f453dc164e05) is 6M, max 48.5M, 42.4M free. Nov 23 22:47:23.835316 systemd-modules-load[247]: Inserted module 'overlay' Nov 23 22:47:23.844106 systemd[1]: Started systemd-journald.service - Journal Service. Nov 23 22:47:23.849512 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 23 22:47:23.850968 systemd-modules-load[247]: Inserted module 'br_netfilter' Nov 23 22:47:23.851866 kernel: Bridge firewalling registered Nov 23 22:47:23.853661 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:47:23.855002 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 23 22:47:23.858115 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 23 22:47:23.861618 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 23 22:47:23.863670 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 23 22:47:23.865032 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 23 22:47:23.868690 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 23 22:47:23.872803 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 23 22:47:23.877743 systemd-tmpfiles[270]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 23 22:47:23.881305 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 23 22:47:23.884894 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 23 22:47:23.886521 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 23 22:47:23.891423 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 23 22:47:23.893904 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 23 22:47:23.920191 dracut-cmdline[291]: 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=c01798725f53da1d62d166036caa3c72754cb158fe469d9d9e3df0d6cadc7a34 Nov 23 22:47:23.936731 systemd-resolved[292]: Positive Trust Anchors: Nov 23 22:47:23.936751 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 23 22:47:23.936784 systemd-resolved[292]: 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 Nov 23 22:47:23.945395 systemd-resolved[292]: Defaulting to hostname 'linux'. Nov 23 22:47:23.947378 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 23 22:47:23.949186 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 23 22:47:24.010507 kernel: SCSI subsystem initialized Nov 23 22:47:24.015480 kernel: Loading iSCSI transport class v2.0-870. Nov 23 22:47:24.023496 kernel: iscsi: registered transport (tcp) Nov 23 22:47:24.036870 kernel: iscsi: registered transport (qla4xxx) Nov 23 22:47:24.036923 kernel: QLogic iSCSI HBA Driver Nov 23 22:47:24.054649 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 23 22:47:24.078040 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 23 22:47:24.081477 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 23 22:47:24.148594 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 23 22:47:24.151251 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 23 22:47:24.223515 kernel: raid6: neonx8 gen() 15755 MB/s Nov 23 22:47:24.240498 kernel: raid6: neonx4 gen() 15817 MB/s Nov 23 22:47:24.257503 kernel: raid6: neonx2 gen() 13171 MB/s Nov 23 22:47:24.274501 kernel: raid6: neonx1 gen() 10415 MB/s Nov 23 22:47:24.291496 kernel: raid6: int64x8 gen() 6892 MB/s Nov 23 22:47:24.308496 kernel: raid6: int64x4 gen() 7341 MB/s Nov 23 22:47:24.325501 kernel: raid6: int64x2 gen() 6098 MB/s Nov 23 22:47:24.342806 kernel: raid6: int64x1 gen() 5049 MB/s Nov 23 22:47:24.342834 kernel: raid6: using algorithm neonx4 gen() 15817 MB/s Nov 23 22:47:24.360630 kernel: raid6: .... xor() 12360 MB/s, rmw enabled Nov 23 22:47:24.360664 kernel: raid6: using neon recovery algorithm Nov 23 22:47:24.366938 kernel: xor: measuring software checksum speed Nov 23 22:47:24.366961 kernel: 8regs : 21596 MB/sec Nov 23 22:47:24.367639 kernel: 32regs : 21676 MB/sec Nov 23 22:47:24.369052 kernel: arm64_neon : 27908 MB/sec Nov 23 22:47:24.369066 kernel: xor: using function: arm64_neon (27908 MB/sec) Nov 23 22:47:24.421507 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 23 22:47:24.427851 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 23 22:47:24.430537 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 23 22:47:24.462281 systemd-udevd[500]: Using default interface naming scheme 'v255'. Nov 23 22:47:24.466500 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 23 22:47:24.469012 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 23 22:47:24.501422 dracut-pre-trigger[508]: rd.md=0: removing MD RAID activation Nov 23 22:47:24.525576 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 23 22:47:24.527935 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 23 22:47:24.583267 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 23 22:47:24.586293 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 23 22:47:24.638108 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Nov 23 22:47:24.638280 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 23 22:47:24.640486 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 23 22:47:24.643973 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 23 22:47:24.644056 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:47:24.651231 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:47:24.653751 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:47:24.676487 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 23 22:47:24.684741 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 23 22:47:24.692543 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:47:24.695565 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 23 22:47:24.709821 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 23 22:47:24.716271 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 23 22:47:24.717502 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 23 22:47:24.720459 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 23 22:47:24.722513 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 23 22:47:24.724483 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 23 22:47:24.727154 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 23 22:47:24.728899 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 23 22:47:24.750208 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 23 22:47:24.750733 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 23 22:47:25.759503 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 23 22:47:25.759574 disk-uuid[594]: The operation has completed successfully. Nov 23 22:47:25.791645 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 23 22:47:25.791748 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 23 22:47:25.817011 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 23 22:47:25.834568 sh[612]: Success Nov 23 22:47:25.848645 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 23 22:47:25.848703 kernel: device-mapper: uevent: version 1.0.3 Nov 23 22:47:25.848723 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 23 22:47:25.857509 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Nov 23 22:47:25.885020 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 23 22:47:25.887061 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 23 22:47:25.900853 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 23 22:47:25.908809 kernel: BTRFS: device fsid 9fed50bd-c943-4402-9e9a-f39625143eb9 devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (624) Nov 23 22:47:25.908875 kernel: BTRFS info (device dm-0): first mount of filesystem 9fed50bd-c943-4402-9e9a-f39625143eb9 Nov 23 22:47:25.910746 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:47:25.915757 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 23 22:47:25.915806 kernel: BTRFS info (device dm-0): enabling free space tree Nov 23 22:47:25.917041 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 23 22:47:25.918388 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 23 22:47:25.919731 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 23 22:47:25.920615 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 23 22:47:25.922283 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 23 22:47:25.947646 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (656) Nov 23 22:47:25.947712 kernel: BTRFS info (device vda6): first mount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:47:25.947771 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:47:25.953209 kernel: BTRFS info (device vda6): turning on async discard Nov 23 22:47:25.953271 kernel: BTRFS info (device vda6): enabling free space tree Nov 23 22:47:25.964572 kernel: BTRFS info (device vda6): last unmount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:47:25.966873 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 23 22:47:25.969028 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 23 22:47:26.047286 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 23 22:47:26.050739 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 23 22:47:26.083004 ignition[698]: Ignition 2.22.0 Nov 23 22:47:26.083022 ignition[698]: Stage: fetch-offline Nov 23 22:47:26.083061 ignition[698]: no configs at "/usr/lib/ignition/base.d" Nov 23 22:47:26.083069 ignition[698]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:47:26.083152 ignition[698]: parsed url from cmdline: "" Nov 23 22:47:26.083155 ignition[698]: no config URL provided Nov 23 22:47:26.083160 ignition[698]: reading system config file "/usr/lib/ignition/user.ign" Nov 23 22:47:26.083167 ignition[698]: no config at "/usr/lib/ignition/user.ign" Nov 23 22:47:26.083191 ignition[698]: op(1): [started] loading QEMU firmware config module Nov 23 22:47:26.083195 ignition[698]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 23 22:47:26.089586 ignition[698]: op(1): [finished] loading QEMU firmware config module Nov 23 22:47:26.093618 systemd-networkd[803]: lo: Link UP Nov 23 22:47:26.093631 systemd-networkd[803]: lo: Gained carrier Nov 23 22:47:26.096606 ignition[698]: parsing config with SHA512: 4c066489deb02cb8fab93d39f6f4af70beaa0d77d0cb1bbf1423dda9f348dec0c6dd0479ea233f0e4a9b93e9077f47a079f1da418eaab6c1e56ef2d7ef4fab3e Nov 23 22:47:26.094354 systemd-networkd[803]: Enumeration completed Nov 23 22:47:26.094579 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 23 22:47:26.094870 systemd-networkd[803]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:47:26.094875 systemd-networkd[803]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 23 22:47:26.095960 systemd-networkd[803]: eth0: Link UP Nov 23 22:47:26.096060 systemd-networkd[803]: eth0: Gained carrier Nov 23 22:47:26.096071 systemd-networkd[803]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:47:26.104660 ignition[698]: fetch-offline: fetch-offline passed Nov 23 22:47:26.096506 systemd[1]: Reached target network.target - Network. Nov 23 22:47:26.104742 ignition[698]: Ignition finished successfully Nov 23 22:47:26.104440 unknown[698]: fetched base config from "system" Nov 23 22:47:26.104447 unknown[698]: fetched user config from "qemu" Nov 23 22:47:26.106758 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 23 22:47:26.108725 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 23 22:47:26.109544 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 23 22:47:26.113087 systemd-networkd[803]: eth0: DHCPv4 address 10.0.0.3/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 23 22:47:26.142153 ignition[811]: Ignition 2.22.0 Nov 23 22:47:26.142174 ignition[811]: Stage: kargs Nov 23 22:47:26.142317 ignition[811]: no configs at "/usr/lib/ignition/base.d" Nov 23 22:47:26.142326 ignition[811]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:47:26.142883 ignition[811]: kargs: kargs passed Nov 23 22:47:26.142930 ignition[811]: Ignition finished successfully Nov 23 22:47:26.146915 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 23 22:47:26.149756 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 23 22:47:26.168554 systemd-resolved[292]: Detected conflict on linux IN A 10.0.0.3 Nov 23 22:47:26.168570 systemd-resolved[292]: Hostname conflict, changing published hostname from 'linux' to 'linux11'. Nov 23 22:47:26.191823 ignition[820]: Ignition 2.22.0 Nov 23 22:47:26.191839 ignition[820]: Stage: disks Nov 23 22:47:26.191997 ignition[820]: no configs at "/usr/lib/ignition/base.d" Nov 23 22:47:26.192006 ignition[820]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:47:26.194434 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 23 22:47:26.192593 ignition[820]: disks: disks passed Nov 23 22:47:26.196600 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 23 22:47:26.192642 ignition[820]: Ignition finished successfully Nov 23 22:47:26.198337 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 23 22:47:26.200004 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 23 22:47:26.201984 systemd[1]: Reached target sysinit.target - System Initialization. Nov 23 22:47:26.203671 systemd[1]: Reached target basic.target - Basic System. Nov 23 22:47:26.206383 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 23 22:47:26.238268 systemd-fsck[831]: ROOT: clean, 15/553520 files, 52789/553472 blocks Nov 23 22:47:26.243543 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 23 22:47:26.246098 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 23 22:47:26.313512 kernel: EXT4-fs (vda9): mounted filesystem c70a3a7b-80c4-4387-ab29-1bf940859b86 r/w with ordered data mode. Quota mode: none. Nov 23 22:47:26.314091 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 23 22:47:26.315611 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 23 22:47:26.318258 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 23 22:47:26.320044 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 23 22:47:26.321195 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 23 22:47:26.321237 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 23 22:47:26.321261 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 23 22:47:26.334895 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 23 22:47:26.337621 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 23 22:47:26.344834 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (839) Nov 23 22:47:26.344861 kernel: BTRFS info (device vda6): first mount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:47:26.344872 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:47:26.353392 kernel: BTRFS info (device vda6): turning on async discard Nov 23 22:47:26.353538 kernel: BTRFS info (device vda6): enabling free space tree Nov 23 22:47:26.354099 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 23 22:47:26.388639 initrd-setup-root[863]: cut: /sysroot/etc/passwd: No such file or directory Nov 23 22:47:26.393093 initrd-setup-root[870]: cut: /sysroot/etc/group: No such file or directory Nov 23 22:47:26.397633 initrd-setup-root[877]: cut: /sysroot/etc/shadow: No such file or directory Nov 23 22:47:26.400902 initrd-setup-root[884]: cut: /sysroot/etc/gshadow: No such file or directory Nov 23 22:47:26.479068 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 23 22:47:26.482231 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 23 22:47:26.484239 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 23 22:47:26.508522 kernel: BTRFS info (device vda6): last unmount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:47:26.520790 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 23 22:47:26.539224 ignition[953]: INFO : Ignition 2.22.0 Nov 23 22:47:26.539224 ignition[953]: INFO : Stage: mount Nov 23 22:47:26.543053 ignition[953]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 23 22:47:26.543053 ignition[953]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:47:26.543053 ignition[953]: INFO : mount: mount passed Nov 23 22:47:26.543053 ignition[953]: INFO : Ignition finished successfully Nov 23 22:47:26.541943 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 23 22:47:26.547640 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 23 22:47:26.907942 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 23 22:47:26.909564 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 23 22:47:26.936384 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (965) Nov 23 22:47:26.936435 kernel: BTRFS info (device vda6): first mount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:47:26.936446 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:47:26.940489 kernel: BTRFS info (device vda6): turning on async discard Nov 23 22:47:26.940527 kernel: BTRFS info (device vda6): enabling free space tree Nov 23 22:47:26.941950 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 23 22:47:26.971762 ignition[983]: INFO : Ignition 2.22.0 Nov 23 22:47:26.971762 ignition[983]: INFO : Stage: files Nov 23 22:47:26.973448 ignition[983]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 23 22:47:26.973448 ignition[983]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:47:26.973448 ignition[983]: DEBUG : files: compiled without relabeling support, skipping Nov 23 22:47:26.973448 ignition[983]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 23 22:47:26.973448 ignition[983]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 23 22:47:26.979895 ignition[983]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 23 22:47:26.979895 ignition[983]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 23 22:47:26.979895 ignition[983]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 23 22:47:26.979895 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 23 22:47:26.979895 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 23 22:47:26.979895 ignition[983]: INFO : files: op(4): [started] processing unit "docker.service" Nov 23 22:47:26.979895 ignition[983]: INFO : files: op(4): [finished] processing unit "docker.service" Nov 23 22:47:26.979895 ignition[983]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Nov 23 22:47:26.975633 unknown[983]: wrote ssh authorized keys file for user: core Nov 23 22:47:26.993127 ignition[983]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 23 22:47:26.993127 ignition[983]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 23 22:47:26.993127 ignition[983]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Nov 23 22:47:26.993127 ignition[983]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Nov 23 22:47:27.001129 ignition[983]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 23 22:47:27.004250 ignition[983]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 23 22:47:27.006874 ignition[983]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Nov 23 22:47:27.006874 ignition[983]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Nov 23 22:47:27.006874 ignition[983]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Nov 23 22:47:27.006874 ignition[983]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 23 22:47:27.006874 ignition[983]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 23 22:47:27.006874 ignition[983]: INFO : files: files passed Nov 23 22:47:27.006874 ignition[983]: INFO : Ignition finished successfully Nov 23 22:47:27.007652 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 23 22:47:27.010197 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 23 22:47:27.012528 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 23 22:47:27.030388 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 23 22:47:27.030537 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 23 22:47:27.035776 initrd-setup-root-after-ignition[1010]: grep: /sysroot/oem/oem-release: No such file or directory Nov 23 22:47:27.039122 initrd-setup-root-after-ignition[1012]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 23 22:47:27.039122 initrd-setup-root-after-ignition[1012]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 23 22:47:27.043515 initrd-setup-root-after-ignition[1016]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 23 22:47:27.040119 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 23 22:47:27.042643 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 23 22:47:27.045247 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 23 22:47:27.091302 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 23 22:47:27.091436 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 23 22:47:27.094046 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 23 22:47:27.095452 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 23 22:47:27.097285 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 23 22:47:27.098248 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 23 22:47:27.121135 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 23 22:47:27.123951 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 23 22:47:27.158149 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 23 22:47:27.159482 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 23 22:47:27.161947 systemd[1]: Stopped target timers.target - Timer Units. Nov 23 22:47:27.163767 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 23 22:47:27.163903 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 23 22:47:27.166605 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 23 22:47:27.168746 systemd[1]: Stopped target basic.target - Basic System. Nov 23 22:47:27.169858 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 23 22:47:27.171991 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 23 22:47:27.175220 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 23 22:47:27.179877 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 23 22:47:27.182939 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 23 22:47:27.184294 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 23 22:47:27.185838 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 23 22:47:27.189762 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 23 22:47:27.191431 systemd[1]: Stopped target swap.target - Swaps. Nov 23 22:47:27.194701 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 23 22:47:27.194837 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 23 22:47:27.197638 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 23 22:47:27.199569 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 23 22:47:27.201441 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 23 22:47:27.201553 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 23 22:47:27.203540 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 23 22:47:27.203687 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 23 22:47:27.206691 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 23 22:47:27.206822 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 23 22:47:27.208954 systemd[1]: Stopped target paths.target - Path Units. Nov 23 22:47:27.210457 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 23 22:47:27.214559 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 23 22:47:27.216820 systemd[1]: Stopped target slices.target - Slice Units. Nov 23 22:47:27.218615 systemd[1]: Stopped target sockets.target - Socket Units. Nov 23 22:47:27.220338 systemd[1]: iscsid.socket: Deactivated successfully. Nov 23 22:47:27.220434 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 23 22:47:27.222984 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 23 22:47:27.223185 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 23 22:47:27.226129 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 23 22:47:27.226456 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 23 22:47:27.228401 systemd[1]: ignition-files.service: Deactivated successfully. Nov 23 22:47:27.228551 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 23 22:47:27.231237 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 23 22:47:27.233746 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 23 22:47:27.235455 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 23 22:47:27.235623 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 23 22:47:27.237892 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 23 22:47:27.238007 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 23 22:47:27.244666 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 23 22:47:27.248668 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 23 22:47:27.258013 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 23 22:47:27.263886 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 23 22:47:27.264022 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 23 22:47:27.267976 ignition[1039]: INFO : Ignition 2.22.0 Nov 23 22:47:27.267976 ignition[1039]: INFO : Stage: umount Nov 23 22:47:27.267976 ignition[1039]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 23 22:47:27.267976 ignition[1039]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:47:27.267976 ignition[1039]: INFO : umount: umount passed Nov 23 22:47:27.267976 ignition[1039]: INFO : Ignition finished successfully Nov 23 22:47:27.267587 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 23 22:47:27.267712 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 23 22:47:27.269272 systemd[1]: Stopped target network.target - Network. Nov 23 22:47:27.270690 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 23 22:47:27.270758 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 23 22:47:27.272380 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 23 22:47:27.272431 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 23 22:47:27.274291 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 23 22:47:27.274345 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 23 22:47:27.276439 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 23 22:47:27.276496 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 23 22:47:27.278276 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 23 22:47:27.278329 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 23 22:47:27.280393 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 23 22:47:27.282589 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 23 22:47:27.290038 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 23 22:47:27.290142 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 23 22:47:27.294152 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 23 22:47:27.294426 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 23 22:47:27.294624 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 23 22:47:27.298362 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 23 22:47:27.299449 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 23 22:47:27.300920 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 23 22:47:27.300964 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 23 22:47:27.304286 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 23 22:47:27.305373 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 23 22:47:27.305438 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 23 22:47:27.308927 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 23 22:47:27.308993 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 23 22:47:27.311950 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 23 22:47:27.312007 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 23 22:47:27.313986 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 23 22:47:27.314042 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 23 22:47:27.317231 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 23 22:47:27.338233 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 23 22:47:27.344689 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 23 22:47:27.346309 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 23 22:47:27.346348 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 23 22:47:27.348335 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 23 22:47:27.348369 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 23 22:47:27.350231 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 23 22:47:27.350289 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 23 22:47:27.353106 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 23 22:47:27.353168 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 23 22:47:27.356242 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 23 22:47:27.356303 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 23 22:47:27.361814 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 23 22:47:27.363947 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 23 22:47:27.364020 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 23 22:47:27.367366 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 23 22:47:27.367424 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 23 22:47:27.370985 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 23 22:47:27.371041 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 23 22:47:27.374854 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 23 22:47:27.374911 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 23 22:47:27.377947 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 23 22:47:27.377998 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:47:27.381884 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 23 22:47:27.381992 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 23 22:47:27.383675 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 23 22:47:27.383771 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 23 22:47:27.386290 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 23 22:47:27.388394 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 23 22:47:27.410863 systemd[1]: Switching root. Nov 23 22:47:27.445910 systemd-journald[246]: Journal stopped Nov 23 22:47:28.195720 systemd-journald[246]: Received SIGTERM from PID 1 (systemd). Nov 23 22:47:28.195773 kernel: SELinux: policy capability network_peer_controls=1 Nov 23 22:47:28.195787 kernel: SELinux: policy capability open_perms=1 Nov 23 22:47:28.195797 kernel: SELinux: policy capability extended_socket_class=1 Nov 23 22:47:28.195808 kernel: SELinux: policy capability always_check_network=0 Nov 23 22:47:28.195817 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 23 22:47:28.195827 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 23 22:47:28.195836 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 23 22:47:28.195845 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 23 22:47:28.195856 kernel: SELinux: policy capability userspace_initial_context=0 Nov 23 22:47:28.195866 kernel: audit: type=1403 audit(1763938047.549:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 23 22:47:28.195876 systemd[1]: Successfully loaded SELinux policy in 68.223ms. Nov 23 22:47:28.195896 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 6.148ms. Nov 23 22:47:28.195909 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) Nov 23 22:47:28.195920 systemd[1]: Detected virtualization kvm. Nov 23 22:47:28.195930 systemd[1]: Detected architecture arm64. Nov 23 22:47:28.195940 systemd[1]: Detected first boot. Nov 23 22:47:28.195949 systemd[1]: Initializing machine ID from VM UUID. Nov 23 22:47:28.195960 zram_generator::config[1084]: No configuration found. Nov 23 22:47:28.195970 kernel: NET: Registered PF_VSOCK protocol family Nov 23 22:47:28.195980 systemd[1]: Populated /etc with preset unit settings. Nov 23 22:47:28.195991 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 23 22:47:28.196002 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 23 22:47:28.196012 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 23 22:47:28.196022 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 23 22:47:28.196031 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 23 22:47:28.196041 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 23 22:47:28.196054 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 23 22:47:28.196063 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 23 22:47:28.196077 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 23 22:47:28.196087 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 23 22:47:28.196097 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 23 22:47:28.196107 systemd[1]: Created slice user.slice - User and Session Slice. Nov 23 22:47:28.196117 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 23 22:47:28.196128 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 23 22:47:28.196138 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 23 22:47:28.196149 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 23 22:47:28.196159 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 23 22:47:28.196170 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 23 22:47:28.196180 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 23 22:47:28.196192 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 23 22:47:28.196202 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 23 22:47:28.196215 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 23 22:47:28.196226 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 23 22:47:28.196236 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 23 22:47:28.196246 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 23 22:47:28.196256 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 23 22:47:28.196266 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 23 22:47:28.196276 systemd[1]: Reached target slices.target - Slice Units. Nov 23 22:47:28.196287 systemd[1]: Reached target swap.target - Swaps. Nov 23 22:47:28.196297 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 23 22:47:28.196308 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 23 22:47:28.196319 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 23 22:47:28.196329 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 23 22:47:28.196339 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 23 22:47:28.196349 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 23 22:47:28.196359 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 23 22:47:28.196369 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 23 22:47:28.196379 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 23 22:47:28.196390 systemd[1]: Mounting media.mount - External Media Directory... Nov 23 22:47:28.196400 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 23 22:47:28.196411 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 23 22:47:28.196421 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 23 22:47:28.196432 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). Nov 23 22:47:28.196443 systemd[1]: Reached target machines.target - Containers. Nov 23 22:47:28.196453 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 23 22:47:28.196526 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 23 22:47:28.196543 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 23 22:47:28.196556 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 23 22:47:28.196567 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 23 22:47:28.196580 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 23 22:47:28.196591 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 23 22:47:28.196601 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 23 22:47:28.196611 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 23 22:47:28.196622 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 23 22:47:28.196632 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 23 22:47:28.196642 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 23 22:47:28.196652 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 23 22:47:28.196663 kernel: fuse: init (API version 7.41) Nov 23 22:47:28.196673 systemd[1]: Stopped systemd-fsck-usr.service. Nov 23 22:47:28.196684 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). Nov 23 22:47:28.196698 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 23 22:47:28.196709 kernel: loop: module loaded Nov 23 22:47:28.196719 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 23 22:47:28.196729 kernel: ACPI: bus type drm_connector registered Nov 23 22:47:28.196739 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 23 22:47:28.196749 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 23 22:47:28.196761 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 23 22:47:28.196771 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 23 22:47:28.196781 systemd[1]: verity-setup.service: Deactivated successfully. Nov 23 22:47:28.196792 systemd[1]: Stopped verity-setup.service. Nov 23 22:47:28.196802 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 23 22:47:28.196813 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 23 22:47:28.196825 systemd[1]: Mounted media.mount - External Media Directory. Nov 23 22:47:28.196839 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 23 22:47:28.196873 systemd-journald[1159]: Collecting audit messages is disabled. Nov 23 22:47:28.196895 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 23 22:47:28.196907 systemd-journald[1159]: Journal started Nov 23 22:47:28.196928 systemd-journald[1159]: Runtime Journal (/run/log/journal/a89c0508d25046c692a9f453dc164e05) is 6M, max 48.5M, 42.4M free. Nov 23 22:47:27.961431 systemd[1]: Queued start job for default target multi-user.target. Nov 23 22:47:27.985410 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 23 22:47:27.985782 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 23 22:47:28.200045 systemd[1]: Started systemd-journald.service - Journal Service. Nov 23 22:47:28.200766 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 23 22:47:28.202042 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 23 22:47:28.203623 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 23 22:47:28.205330 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 23 22:47:28.205557 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 23 22:47:28.207011 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 23 22:47:28.207169 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 23 22:47:28.208588 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 23 22:47:28.208760 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 23 22:47:28.210137 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 23 22:47:28.211507 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 23 22:47:28.213031 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 23 22:47:28.213193 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 23 22:47:28.214636 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 23 22:47:28.214810 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 23 22:47:28.216586 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 23 22:47:28.218108 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 23 22:47:28.219760 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 23 22:47:28.221275 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 23 22:47:28.233708 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 23 22:47:28.236131 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 23 22:47:28.238351 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 23 22:47:28.239778 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 23 22:47:28.239819 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 23 22:47:28.241770 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 23 22:47:28.244346 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 23 22:47:28.245635 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 23 22:47:28.247149 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 23 22:47:28.249310 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 23 22:47:28.250727 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 23 22:47:28.251749 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 23 22:47:28.253037 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 23 22:47:28.254115 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 23 22:47:28.258651 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 23 22:47:28.261659 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 23 22:47:28.264728 systemd-journald[1159]: Time spent on flushing to /var/log/journal/a89c0508d25046c692a9f453dc164e05 is 19.494ms for 856 entries. Nov 23 22:47:28.264728 systemd-journald[1159]: System Journal (/var/log/journal/a89c0508d25046c692a9f453dc164e05) is 8M, max 195.6M, 187.6M free. Nov 23 22:47:28.334052 systemd-journald[1159]: Received client request to flush runtime journal. Nov 23 22:47:28.334112 kernel: loop0: detected capacity change from 0 to 119840 Nov 23 22:47:28.264859 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 23 22:47:28.268116 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 23 22:47:28.270000 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 23 22:47:28.275376 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 23 22:47:28.277331 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 23 22:47:28.279930 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 23 22:47:28.287168 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 23 22:47:28.330882 systemd-tmpfiles[1202]: ACLs are not supported, ignoring. Nov 23 22:47:28.330893 systemd-tmpfiles[1202]: ACLs are not supported, ignoring. Nov 23 22:47:28.335583 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 23 22:47:28.340748 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 23 22:47:28.342578 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 23 22:47:28.346495 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 23 22:47:28.357204 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 23 22:47:28.363510 kernel: loop1: detected capacity change from 0 to 100632 Nov 23 22:47:28.382516 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 23 22:47:28.387137 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 23 22:47:28.396489 kernel: loop2: detected capacity change from 0 to 119840 Nov 23 22:47:28.405533 kernel: loop3: detected capacity change from 0 to 100632 Nov 23 22:47:28.409584 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Nov 23 22:47:28.409910 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Nov 23 22:47:28.411505 (sd-merge)[1222]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Nov 23 22:47:28.411872 (sd-merge)[1222]: Merged extensions into '/usr'. Nov 23 22:47:28.414136 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 23 22:47:28.422535 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 23 22:47:28.425277 systemd[1]: Starting ensure-sysext.service... Nov 23 22:47:28.428667 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 23 22:47:28.447354 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 23 22:47:28.447359 systemd[1]: Reload requested from client PID 1226 ('systemctl') (unit ensure-sysext.service)... Nov 23 22:47:28.447390 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 23 22:47:28.447738 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 23 22:47:28.447821 systemd[1]: Reloading... Nov 23 22:47:28.447935 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 23 22:47:28.448584 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 23 22:47:28.448784 systemd-tmpfiles[1227]: ACLs are not supported, ignoring. Nov 23 22:47:28.448847 systemd-tmpfiles[1227]: ACLs are not supported, ignoring. Nov 23 22:47:28.452156 systemd-tmpfiles[1227]: Detected autofs mount point /boot during canonicalization of boot. Nov 23 22:47:28.452168 systemd-tmpfiles[1227]: Skipping /boot Nov 23 22:47:28.459241 systemd-tmpfiles[1227]: Detected autofs mount point /boot during canonicalization of boot. Nov 23 22:47:28.459258 systemd-tmpfiles[1227]: Skipping /boot Nov 23 22:47:28.513496 zram_generator::config[1258]: No configuration found. Nov 23 22:47:28.663563 ldconfig[1195]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 23 22:47:28.670286 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 23 22:47:28.670719 systemd[1]: Reloading finished in 222 ms. Nov 23 22:47:28.694484 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 23 22:47:28.711289 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 23 22:47:28.720228 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 23 22:47:28.723331 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 23 22:47:28.733035 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 23 22:47:28.738985 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 23 22:47:28.743482 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 23 22:47:28.748131 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 23 22:47:28.758982 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 23 22:47:28.762639 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 23 22:47:28.765655 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 23 22:47:28.768422 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 23 22:47:28.768969 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). Nov 23 22:47:28.773069 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 23 22:47:28.775817 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 23 22:47:28.780115 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 23 22:47:28.785981 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 23 22:47:28.787452 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 23 22:47:28.790685 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 23 22:47:28.794519 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 23 22:47:28.795810 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 23 22:47:28.799590 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 23 22:47:28.808335 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 23 22:47:28.810013 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 23 22:47:28.812676 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 23 22:47:28.817853 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 23 22:47:28.819360 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 23 22:47:28.819643 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). Nov 23 22:47:28.821459 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 23 22:47:28.825243 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 23 22:47:28.829382 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 23 22:47:28.829656 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 23 22:47:28.833542 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 23 22:47:28.842736 augenrules[1333]: No rules Nov 23 22:47:28.854719 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 23 22:47:28.857981 systemd[1]: audit-rules.service: Deactivated successfully. Nov 23 22:47:28.861577 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 23 22:47:28.863661 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 23 22:47:28.865676 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 23 22:47:28.865847 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 23 22:47:28.867654 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 23 22:47:28.867815 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 23 22:47:28.871501 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 23 22:47:28.894921 systemd-udevd[1325]: Using default interface naming scheme 'v255'. Nov 23 22:47:28.897635 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 23 22:47:28.899003 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 23 22:47:28.900264 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 23 22:47:28.903652 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 23 22:47:28.916792 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 23 22:47:28.919765 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 23 22:47:28.921306 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 23 22:47:28.921373 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). Nov 23 22:47:28.921438 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). Nov 23 22:47:28.922269 systemd[1]: Finished ensure-sysext.service. Nov 23 22:47:28.925913 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 23 22:47:28.926133 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 23 22:47:28.928318 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 23 22:47:28.928517 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 23 22:47:28.929261 systemd-resolved[1295]: Positive Trust Anchors: Nov 23 22:47:28.929646 systemd-resolved[1295]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 23 22:47:28.929743 systemd-resolved[1295]: 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 Nov 23 22:47:28.930202 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 23 22:47:28.930381 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 23 22:47:28.934324 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 23 22:47:28.934672 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 23 22:47:28.937831 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 23 22:47:28.944818 systemd-resolved[1295]: Defaulting to hostname 'linux'. Nov 23 22:47:28.948455 augenrules[1346]: /sbin/augenrules: No change Nov 23 22:47:28.948648 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 23 22:47:28.953969 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 23 22:47:28.961762 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 23 22:47:28.962949 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 23 22:47:28.963026 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 23 22:47:28.968099 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 23 22:47:28.977925 augenrules[1400]: No rules Nov 23 22:47:28.979382 systemd[1]: audit-rules.service: Deactivated successfully. Nov 23 22:47:28.979674 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 23 22:47:28.986022 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 23 22:47:29.058056 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 23 22:47:29.061635 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 23 22:47:29.092565 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 23 22:47:29.108334 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 23 22:47:29.110601 systemd[1]: Reached target sysinit.target - System Initialization. Nov 23 22:47:29.112406 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 23 22:47:29.114630 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 23 22:47:29.116492 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 23 22:47:29.118552 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 23 22:47:29.118591 systemd[1]: Reached target paths.target - Path Units. Nov 23 22:47:29.119970 systemd[1]: Reached target time-set.target - System Time Set. Nov 23 22:47:29.121599 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 23 22:47:29.123526 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 23 22:47:29.125587 systemd[1]: Reached target timers.target - Timer Units. Nov 23 22:47:29.127216 systemd-networkd[1395]: lo: Link UP Nov 23 22:47:29.127226 systemd-networkd[1395]: lo: Gained carrier Nov 23 22:47:29.128129 systemd-networkd[1395]: Enumeration completed Nov 23 22:47:29.128653 systemd-networkd[1395]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:47:29.128657 systemd-networkd[1395]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 23 22:47:29.131175 systemd-networkd[1395]: eth0: Link UP Nov 23 22:47:29.131332 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 23 22:47:29.134453 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 23 22:47:29.136698 systemd-networkd[1395]: eth0: Gained carrier Nov 23 22:47:29.136728 systemd-networkd[1395]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:47:29.137693 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 23 22:47:29.139603 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 23 22:47:29.141167 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 23 22:47:29.155030 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 23 22:47:29.156881 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 23 22:47:29.160375 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 23 22:47:29.160661 systemd-networkd[1395]: eth0: DHCPv4 address 10.0.0.3/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 23 22:47:29.161349 systemd-timesyncd[1397]: Network configuration changed, trying to establish connection. Nov 23 22:47:29.162327 systemd-timesyncd[1397]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 23 22:47:29.162382 systemd-timesyncd[1397]: Initial clock synchronization to Sun 2025-11-23 22:47:29.061571 UTC. Nov 23 22:47:29.162641 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 23 22:47:29.165791 systemd[1]: Reached target network.target - Network. Nov 23 22:47:29.166912 systemd[1]: Reached target sockets.target - Socket Units. Nov 23 22:47:29.169560 systemd[1]: Reached target basic.target - Basic System. Nov 23 22:47:29.170927 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 23 22:47:29.170957 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 23 22:47:29.172145 systemd[1]: Starting containerd.service - containerd container runtime... Nov 23 22:47:29.175672 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 23 22:47:29.182888 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 23 22:47:29.185820 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 23 22:47:29.189443 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 23 22:47:29.191691 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). Nov 23 22:47:29.193065 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 23 22:47:29.197769 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 23 22:47:29.201497 jq[1439]: false Nov 23 22:47:29.200130 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 23 22:47:29.204063 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 23 22:47:29.207416 extend-filesystems[1440]: Found /dev/vda6 Nov 23 22:47:29.209579 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 23 22:47:29.213797 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 23 22:47:29.215604 extend-filesystems[1440]: Found /dev/vda9 Nov 23 22:47:29.216262 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 23 22:47:29.216998 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 23 22:47:29.217195 extend-filesystems[1440]: Checking size of /dev/vda9 Nov 23 22:47:29.218628 systemd[1]: Starting update-engine.service - Update Engine... Nov 23 22:47:29.221204 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 23 22:47:29.232048 extend-filesystems[1440]: Old size kept for /dev/vda9 Nov 23 22:47:29.232651 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 23 22:47:29.237157 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 23 22:47:29.237371 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 23 22:47:29.237720 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 23 22:47:29.237941 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 23 22:47:29.240115 jq[1461]: true Nov 23 22:47:29.240157 systemd[1]: motdgen.service: Deactivated successfully. Nov 23 22:47:29.240359 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 23 22:47:29.243169 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 23 22:47:29.243394 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 23 22:47:29.273958 systemd-logind[1448]: Watching system buttons on /dev/input/event0 (Power Button) Nov 23 22:47:29.274274 systemd-logind[1448]: New seat seat0. Nov 23 22:47:29.274836 jq[1468]: true Nov 23 22:47:29.276554 systemd[1]: Started systemd-logind.service - User Login Management. Nov 23 22:47:29.292051 update_engine[1460]: I20251123 22:47:29.291828 1460 main.cc:92] Flatcar Update Engine starting Nov 23 22:47:29.295433 (ntainerd)[1477]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 23 22:47:29.298142 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:47:29.299911 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 23 22:47:29.309191 dbus-daemon[1437]: [system] SELinux support is enabled Nov 23 22:47:29.309454 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 23 22:47:29.313775 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). Nov 23 22:47:29.314528 dbus-daemon[1437]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 23 22:47:29.313815 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 23 22:47:29.315379 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). Nov 23 22:47:29.315452 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 23 22:47:29.317436 update_engine[1460]: I20251123 22:47:29.317346 1460 update_check_scheduler.cc:74] Next update check in 2m7s Nov 23 22:47:29.319624 systemd[1]: Started update-engine.service - Update Engine. Nov 23 22:47:29.323853 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 23 22:47:29.338886 bash[1498]: Updated "/home/core/.ssh/authorized_keys" Nov 23 22:47:29.340275 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 23 22:47:29.342393 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 23 22:47:29.380594 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:47:29.395268 locksmithd[1494]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 23 22:47:29.458796 containerd[1477]: time="2025-11-23T22:47:29Z" 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 Nov 23 22:47:29.459370 containerd[1477]: time="2025-11-23T22:47:29.459334200Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Nov 23 22:47:29.471843 containerd[1477]: time="2025-11-23T22:47:29.471782000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.52µs" Nov 23 22:47:29.471843 containerd[1477]: time="2025-11-23T22:47:29.471826640Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 23 22:47:29.471976 containerd[1477]: time="2025-11-23T22:47:29.471855320Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 23 22:47:29.472046 containerd[1477]: time="2025-11-23T22:47:29.472025280Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 23 22:47:29.472069 containerd[1477]: time="2025-11-23T22:47:29.472047720Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 23 22:47:29.472087 containerd[1477]: time="2025-11-23T22:47:29.472074080Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472146 containerd[1477]: time="2025-11-23T22:47:29.472127080Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472172 containerd[1477]: time="2025-11-23T22:47:29.472146320Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472408 containerd[1477]: time="2025-11-23T22:47:29.472371960Z" 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 Nov 23 22:47:29.472408 containerd[1477]: time="2025-11-23T22:47:29.472393480Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472448 containerd[1477]: time="2025-11-23T22:47:29.472413400Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472448 containerd[1477]: time="2025-11-23T22:47:29.472423000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472540 containerd[1477]: time="2025-11-23T22:47:29.472516120Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472788 containerd[1477]: time="2025-11-23T22:47:29.472753520Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 23 22:47:29.472814 containerd[1477]: time="2025-11-23T22:47:29.472794480Z" 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 Nov 23 22:47:29.472814 containerd[1477]: time="2025-11-23T22:47:29.472805520Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 23 22:47:29.472863 containerd[1477]: time="2025-11-23T22:47:29.472838640Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 23 22:47:29.473077 containerd[1477]: time="2025-11-23T22:47:29.473062520Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 23 22:47:29.473149 containerd[1477]: time="2025-11-23T22:47:29.473128760Z" level=info msg="metadata content store policy set" policy=shared Nov 23 22:47:29.477680 containerd[1477]: time="2025-11-23T22:47:29.477640480Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 23 22:47:29.477745 containerd[1477]: time="2025-11-23T22:47:29.477709680Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 23 22:47:29.477745 containerd[1477]: time="2025-11-23T22:47:29.477725600Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 23 22:47:29.477745 containerd[1477]: time="2025-11-23T22:47:29.477738200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477751720Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477763240Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477779160Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477793600Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477805880Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477817680Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477829920Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 23 22:47:29.477858 containerd[1477]: time="2025-11-23T22:47:29.477844600Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 23 22:47:29.478023 containerd[1477]: time="2025-11-23T22:47:29.477986640Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 23 22:47:29.478023 containerd[1477]: time="2025-11-23T22:47:29.478008960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 23 22:47:29.478023 containerd[1477]: time="2025-11-23T22:47:29.478022880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 23 22:47:29.478096 containerd[1477]: time="2025-11-23T22:47:29.478035720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 23 22:47:29.478096 containerd[1477]: time="2025-11-23T22:47:29.478047000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 23 22:47:29.478096 containerd[1477]: time="2025-11-23T22:47:29.478060280Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 23 22:47:29.478096 containerd[1477]: time="2025-11-23T22:47:29.478079080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 23 22:47:29.478193 containerd[1477]: time="2025-11-23T22:47:29.478107080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 23 22:47:29.478193 containerd[1477]: time="2025-11-23T22:47:29.478124960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 23 22:47:29.478193 containerd[1477]: time="2025-11-23T22:47:29.478139560Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 23 22:47:29.478193 containerd[1477]: time="2025-11-23T22:47:29.478150760Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 23 22:47:29.479430 containerd[1477]: time="2025-11-23T22:47:29.479381160Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 23 22:47:29.479507 containerd[1477]: time="2025-11-23T22:47:29.479447560Z" level=info msg="Start snapshots syncer" Nov 23 22:47:29.479507 containerd[1477]: time="2025-11-23T22:47:29.479498560Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 23 22:47:29.480209 containerd[1477]: time="2025-11-23T22:47:29.480167160Z" 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\"}" Nov 23 22:47:29.480314 containerd[1477]: time="2025-11-23T22:47:29.480240720Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 23 22:47:29.480314 containerd[1477]: time="2025-11-23T22:47:29.480306280Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 23 22:47:29.480588 containerd[1477]: time="2025-11-23T22:47:29.480561480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 23 22:47:29.480619 containerd[1477]: time="2025-11-23T22:47:29.480608440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 23 22:47:29.480660 containerd[1477]: time="2025-11-23T22:47:29.480627200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 23 22:47:29.480660 containerd[1477]: time="2025-11-23T22:47:29.480640240Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 23 22:47:29.480660 containerd[1477]: time="2025-11-23T22:47:29.480657080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 23 22:47:29.480714 containerd[1477]: time="2025-11-23T22:47:29.480673600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 23 22:47:29.480714 containerd[1477]: time="2025-11-23T22:47:29.480705120Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 23 22:47:29.480754 containerd[1477]: time="2025-11-23T22:47:29.480744880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 23 22:47:29.480774 containerd[1477]: time="2025-11-23T22:47:29.480764400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 23 22:47:29.480794 containerd[1477]: time="2025-11-23T22:47:29.480783280Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 23 22:47:29.480840 containerd[1477]: time="2025-11-23T22:47:29.480820720Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 23 22:47:29.480915 containerd[1477]: time="2025-11-23T22:47:29.480893320Z" 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 Nov 23 22:47:29.480951 containerd[1477]: time="2025-11-23T22:47:29.480914960Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:47:29.480951 containerd[1477]: time="2025-11-23T22:47:29.480931960Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:47:29.480951 containerd[1477]: time="2025-11-23T22:47:29.480941960Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 23 22:47:29.481012 containerd[1477]: time="2025-11-23T22:47:29.480964880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 23 22:47:29.481012 containerd[1477]: time="2025-11-23T22:47:29.480981800Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 23 22:47:29.481193 containerd[1477]: time="2025-11-23T22:47:29.481178920Z" level=info msg="runtime interface created" Nov 23 22:47:29.481193 containerd[1477]: time="2025-11-23T22:47:29.481191560Z" level=info msg="created NRI interface" Nov 23 22:47:29.481246 containerd[1477]: time="2025-11-23T22:47:29.481203880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 23 22:47:29.481246 containerd[1477]: time="2025-11-23T22:47:29.481223200Z" level=info msg="Connect containerd service" Nov 23 22:47:29.481286 containerd[1477]: time="2025-11-23T22:47:29.481256920Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 23 22:47:29.482994 containerd[1477]: time="2025-11-23T22:47:29.482412320Z" 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" Nov 23 22:47:29.561267 containerd[1477]: time="2025-11-23T22:47:29.561142400Z" level=info msg="Start subscribing containerd event" Nov 23 22:47:29.561267 containerd[1477]: time="2025-11-23T22:47:29.561223880Z" level=info msg="Start recovering state" Nov 23 22:47:29.561432 containerd[1477]: time="2025-11-23T22:47:29.561333520Z" level=info msg="Start event monitor" Nov 23 22:47:29.561432 containerd[1477]: time="2025-11-23T22:47:29.561351760Z" level=info msg="Start cni network conf syncer for default" Nov 23 22:47:29.561432 containerd[1477]: time="2025-11-23T22:47:29.561361080Z" level=info msg="Start streaming server" Nov 23 22:47:29.561432 containerd[1477]: time="2025-11-23T22:47:29.561370680Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 23 22:47:29.561432 containerd[1477]: time="2025-11-23T22:47:29.561379440Z" level=info msg="runtime interface starting up..." Nov 23 22:47:29.561432 containerd[1477]: time="2025-11-23T22:47:29.561385840Z" level=info msg="starting plugins..." Nov 23 22:47:29.561432 containerd[1477]: time="2025-11-23T22:47:29.561400080Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 23 22:47:29.561742 containerd[1477]: time="2025-11-23T22:47:29.561716080Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 23 22:47:29.561875 containerd[1477]: time="2025-11-23T22:47:29.561853160Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 23 22:47:29.562016 containerd[1477]: time="2025-11-23T22:47:29.562001640Z" level=info msg="containerd successfully booted in 0.103687s" Nov 23 22:47:29.562191 systemd[1]: Started containerd.service - containerd container runtime. Nov 23 22:47:29.610049 sshd_keygen[1462]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 23 22:47:29.630528 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 23 22:47:29.633379 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 23 22:47:29.651107 systemd[1]: issuegen.service: Deactivated successfully. Nov 23 22:47:29.651308 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 23 22:47:29.654033 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 23 22:47:29.683555 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 23 22:47:29.686323 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 23 22:47:29.688457 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 23 22:47:29.689899 systemd[1]: Reached target getty.target - Login Prompts. Nov 23 22:47:30.956661 systemd-networkd[1395]: eth0: Gained IPv6LL Nov 23 22:47:30.959106 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 23 22:47:30.962212 systemd[1]: Reached target network-online.target - Network is Online. Nov 23 22:47:30.965754 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 23 22:47:30.968880 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 23 22:47:31.011694 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 23 22:47:31.036725 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 23 22:47:31.037032 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 23 22:47:31.039961 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 23 22:47:31.040824 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 23 22:47:31.045283 systemd[1]: Startup finished in 2.183s (kernel) + 3.907s (initrd) + 3.564s (userspace) = 9.655s. Nov 23 22:47:36.541400 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 23 22:47:36.542502 systemd[1]: Started sshd@0-10.0.0.3:22-10.0.0.1:42890.service - OpenSSH per-connection server daemon (10.0.0.1:42890). Nov 23 22:47:36.650169 sshd[1566]: Accepted publickey for core from 10.0.0.1 port 42890 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:36.652800 sshd-session[1566]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:36.664214 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 23 22:47:36.665549 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 23 22:47:36.669456 systemd-logind[1448]: New session 1 of user core. Nov 23 22:47:36.702425 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 23 22:47:36.707127 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 23 22:47:36.729857 (systemd)[1571]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 23 22:47:36.732128 systemd-logind[1448]: New session c1 of user core. Nov 23 22:47:36.851941 systemd[1571]: Queued start job for default target default.target. Nov 23 22:47:36.869545 systemd[1571]: Created slice app.slice - User Application Slice. Nov 23 22:47:36.869575 systemd[1571]: Reached target paths.target - Paths. Nov 23 22:47:36.869612 systemd[1571]: Reached target timers.target - Timers. Nov 23 22:47:36.870976 systemd[1571]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 23 22:47:36.880138 systemd[1571]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 23 22:47:36.880197 systemd[1571]: Reached target sockets.target - Sockets. Nov 23 22:47:36.880231 systemd[1571]: Reached target basic.target - Basic System. Nov 23 22:47:36.880258 systemd[1571]: Reached target default.target - Main User Target. Nov 23 22:47:36.880283 systemd[1571]: Startup finished in 142ms. Nov 23 22:47:36.880528 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 23 22:47:36.881776 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 23 22:47:36.943652 systemd[1]: Started sshd@1-10.0.0.3:22-10.0.0.1:42902.service - OpenSSH per-connection server daemon (10.0.0.1:42902). Nov 23 22:47:37.010630 sshd[1582]: Accepted publickey for core from 10.0.0.1 port 42902 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:37.011973 sshd-session[1582]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:37.016526 systemd-logind[1448]: New session 2 of user core. Nov 23 22:47:37.024733 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 23 22:47:37.077768 sshd[1585]: Connection closed by 10.0.0.1 port 42902 Nov 23 22:47:37.078308 sshd-session[1582]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:37.090654 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:42902.service: Deactivated successfully. Nov 23 22:47:37.092977 systemd[1]: session-2.scope: Deactivated successfully. Nov 23 22:47:37.094771 systemd-logind[1448]: Session 2 logged out. Waiting for processes to exit. Nov 23 22:47:37.096927 systemd[1]: Started sshd@2-10.0.0.3:22-10.0.0.1:42914.service - OpenSSH per-connection server daemon (10.0.0.1:42914). Nov 23 22:47:37.097811 systemd-logind[1448]: Removed session 2. Nov 23 22:47:37.155623 sshd[1591]: Accepted publickey for core from 10.0.0.1 port 42914 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:37.156894 sshd-session[1591]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:37.160983 systemd-logind[1448]: New session 3 of user core. Nov 23 22:47:37.178701 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 23 22:47:37.227552 sshd[1594]: Connection closed by 10.0.0.1 port 42914 Nov 23 22:47:37.227397 sshd-session[1591]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:37.237704 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:42914.service: Deactivated successfully. Nov 23 22:47:37.239195 systemd[1]: session-3.scope: Deactivated successfully. Nov 23 22:47:37.239925 systemd-logind[1448]: Session 3 logged out. Waiting for processes to exit. Nov 23 22:47:37.242264 systemd[1]: Started sshd@3-10.0.0.3:22-10.0.0.1:42918.service - OpenSSH per-connection server daemon (10.0.0.1:42918). Nov 23 22:47:37.242737 systemd-logind[1448]: Removed session 3. Nov 23 22:47:37.299344 sshd[1600]: Accepted publickey for core from 10.0.0.1 port 42918 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:37.300807 sshd-session[1600]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:37.304791 systemd-logind[1448]: New session 4 of user core. Nov 23 22:47:37.312672 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 23 22:47:37.364552 sshd[1603]: Connection closed by 10.0.0.1 port 42918 Nov 23 22:47:37.365268 sshd-session[1600]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:37.386952 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:42918.service: Deactivated successfully. Nov 23 22:47:37.388706 systemd[1]: session-4.scope: Deactivated successfully. Nov 23 22:47:37.389470 systemd-logind[1448]: Session 4 logged out. Waiting for processes to exit. Nov 23 22:47:37.391891 systemd[1]: Started sshd@4-10.0.0.3:22-10.0.0.1:42920.service - OpenSSH per-connection server daemon (10.0.0.1:42920). Nov 23 22:47:37.392345 systemd-logind[1448]: Removed session 4. Nov 23 22:47:37.464690 sshd[1609]: Accepted publickey for core from 10.0.0.1 port 42920 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:37.466010 sshd-session[1609]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:37.470188 systemd-logind[1448]: New session 5 of user core. Nov 23 22:47:37.481671 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 23 22:47:37.547783 sudo[1613]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 23 22:47:37.548046 sudo[1613]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 23 22:47:37.564448 sudo[1613]: pam_unix(sudo:session): session closed for user root Nov 23 22:47:37.566501 sshd[1612]: Connection closed by 10.0.0.1 port 42920 Nov 23 22:47:37.566646 sshd-session[1609]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:37.582740 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:42920.service: Deactivated successfully. Nov 23 22:47:37.584252 systemd[1]: session-5.scope: Deactivated successfully. Nov 23 22:47:37.585398 systemd-logind[1448]: Session 5 logged out. Waiting for processes to exit. Nov 23 22:47:37.588110 systemd[1]: Started sshd@5-10.0.0.3:22-10.0.0.1:42934.service - OpenSSH per-connection server daemon (10.0.0.1:42934). Nov 23 22:47:37.588560 systemd-logind[1448]: Removed session 5. Nov 23 22:47:37.651880 sshd[1619]: Accepted publickey for core from 10.0.0.1 port 42934 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:37.653305 sshd-session[1619]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:37.657261 systemd-logind[1448]: New session 6 of user core. Nov 23 22:47:37.670666 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 23 22:47:37.721539 sudo[1624]: 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 Nov 23 22:47:37.721808 sudo[1624]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 23 22:47:37.731548 sudo[1624]: pam_unix(sudo:session): session closed for user root Nov 23 22:47:37.736944 sudo[1623]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 23 22:47:37.737229 sudo[1623]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 23 22:47:37.746454 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 23 22:47:37.792570 augenrules[1646]: No rules Nov 23 22:47:37.793802 systemd[1]: audit-rules.service: Deactivated successfully. Nov 23 22:47:37.794572 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 23 22:47:37.795524 sudo[1623]: pam_unix(sudo:session): session closed for user root Nov 23 22:47:37.796785 sshd[1622]: Connection closed by 10.0.0.1 port 42934 Nov 23 22:47:37.797793 sshd-session[1619]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:37.808282 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:42934.service: Deactivated successfully. Nov 23 22:47:37.811061 systemd[1]: session-6.scope: Deactivated successfully. Nov 23 22:47:37.811882 systemd-logind[1448]: Session 6 logged out. Waiting for processes to exit. Nov 23 22:47:37.814551 systemd[1]: Started sshd@6-10.0.0.3:22-10.0.0.1:42946.service - OpenSSH per-connection server daemon (10.0.0.1:42946). Nov 23 22:47:37.815060 systemd-logind[1448]: Removed session 6. Nov 23 22:47:37.885169 sshd[1656]: Accepted publickey for core from 10.0.0.1 port 42946 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:37.887516 sshd-session[1656]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:37.896573 systemd-logind[1448]: New session 7 of user core. Nov 23 22:47:37.916666 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 23 22:47:37.973886 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 23 22:47:37.986823 (dockerd)[1661]: 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 Nov 23 22:47:38.237019 dockerd[1661]: time="2025-11-23T22:47:38.236856554Z" level=info msg="Starting up" Nov 23 22:47:38.238038 dockerd[1661]: time="2025-11-23T22:47:38.238011042Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 23 22:47:38.250287 dockerd[1661]: time="2025-11-23T22:47:38.250227399Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 23 22:47:38.299438 dockerd[1661]: time="2025-11-23T22:47:38.299382335Z" level=info msg="Loading containers: start." Nov 23 22:47:38.308489 kernel: Initializing XFRM netlink socket Nov 23 22:47:38.618286 systemd-networkd[1395]: docker0: Link UP Nov 23 22:47:38.623993 dockerd[1661]: time="2025-11-23T22:47:38.623941834Z" level=info msg="Loading containers: done." Nov 23 22:47:38.642012 dockerd[1661]: time="2025-11-23T22:47:38.641934540Z" 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 Nov 23 22:47:38.642179 dockerd[1661]: time="2025-11-23T22:47:38.642054847Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 23 22:47:38.642179 dockerd[1661]: time="2025-11-23T22:47:38.642150685Z" level=info msg="Initializing buildkit" Nov 23 22:47:38.683394 dockerd[1661]: time="2025-11-23T22:47:38.683295491Z" level=info msg="Completed buildkit initialization" Nov 23 22:47:38.690745 dockerd[1661]: time="2025-11-23T22:47:38.690669817Z" level=info msg="Daemon has completed initialization" Nov 23 22:47:38.690859 dockerd[1661]: time="2025-11-23T22:47:38.690737921Z" level=info msg="API listen on /run/docker.sock" Nov 23 22:47:38.690991 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 23 22:47:38.700266 sshd[1659]: Connection closed by 10.0.0.1 port 42946 Nov 23 22:47:38.700693 sshd-session[1656]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:38.714904 systemd[1]: sshd@6-10.0.0.3:22-10.0.0.1:42946.service: Deactivated successfully. Nov 23 22:47:38.718101 systemd[1]: session-7.scope: Deactivated successfully. Nov 23 22:47:38.719123 systemd-logind[1448]: Session 7 logged out. Waiting for processes to exit. Nov 23 22:47:38.721766 systemd[1]: Started sshd@7-10.0.0.3:22-10.0.0.1:42960.service - OpenSSH per-connection server daemon (10.0.0.1:42960). Nov 23 22:47:38.722549 systemd-logind[1448]: Removed session 7. Nov 23 22:47:38.794719 sshd[1875]: Accepted publickey for core from 10.0.0.1 port 42960 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:38.796056 sshd-session[1875]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:38.800546 systemd-logind[1448]: New session 8 of user core. Nov 23 22:47:38.806677 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 23 22:47:40.108219 systemd[1]: var-lib-docker-overlay2-d52a28277ed0081ede2ede7fa2df93d29e7b43cce422aff3b085802ebfd62bc6\x2dinit-merged.mount: Deactivated successfully. Nov 23 22:47:40.195288 containerd[1477]: time="2025-11-23T22:47:40.195227493Z" level=info msg="connecting to shim e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae" address="unix:///run/containerd/s/928599d96ba28a6c2e49babd7d5706fd7a21d4752fb772ca5126aea36cc14626" namespace=moby protocol=ttrpc version=3 Nov 23 22:47:40.220710 systemd[1]: Started docker-e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae.scope - libcontainer container e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae. Nov 23 22:47:40.236800 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 23 22:47:40.262489 kernel: docker0: port 1(vethfa8f358) entered blocking state Nov 23 22:47:40.262581 kernel: docker0: port 1(vethfa8f358) entered disabled state Nov 23 22:47:40.262598 kernel: vethfa8f358: entered allmulticast mode Nov 23 22:47:40.262614 kernel: vethfa8f358: entered promiscuous mode Nov 23 22:47:40.263548 systemd-networkd[1395]: vethfa8f358: Link UP Nov 23 22:47:40.285500 kernel: eth0: renamed from veth59e287e Nov 23 22:47:40.287277 kernel: docker0: port 1(vethfa8f358) entered blocking state Nov 23 22:47:40.287303 kernel: docker0: port 1(vethfa8f358) entered forwarding state Nov 23 22:47:40.287359 systemd-networkd[1395]: vethfa8f358: Gained carrier Nov 23 22:47:40.289299 systemd-networkd[1395]: docker0: Gained carrier Nov 23 22:47:40.342376 sshd[1878]: Connection closed by 10.0.0.1 port 42960 Nov 23 22:47:40.343307 sshd-session[1875]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:40.355730 systemd[1]: sshd@7-10.0.0.3:22-10.0.0.1:42960.service: Deactivated successfully. Nov 23 22:47:40.357430 systemd[1]: session-8.scope: Deactivated successfully. Nov 23 22:47:40.358154 systemd-logind[1448]: Session 8 logged out. Waiting for processes to exit. Nov 23 22:47:40.360730 systemd[1]: Started sshd@8-10.0.0.3:22-10.0.0.1:51324.service - OpenSSH per-connection server daemon (10.0.0.1:51324). Nov 23 22:47:40.361708 systemd-logind[1448]: Removed session 8. Nov 23 22:47:40.426066 sshd[1952]: Accepted publickey for core from 10.0.0.1 port 51324 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:40.427450 sshd-session[1952]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:40.432746 systemd-logind[1448]: New session 9 of user core. Nov 23 22:47:40.454701 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 23 22:47:40.511286 sshd[1955]: Connection closed by 10.0.0.1 port 51324 Nov 23 22:47:40.511650 sshd-session[1952]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:40.529603 systemd[1]: sshd@8-10.0.0.3:22-10.0.0.1:51324.service: Deactivated successfully. Nov 23 22:47:40.531456 systemd[1]: session-9.scope: Deactivated successfully. Nov 23 22:47:40.533118 systemd-logind[1448]: Session 9 logged out. Waiting for processes to exit. Nov 23 22:47:40.534896 systemd[1]: Started sshd@9-10.0.0.3:22-10.0.0.1:51344.service - OpenSSH per-connection server daemon (10.0.0.1:51344). Nov 23 22:47:40.535733 systemd-logind[1448]: Removed session 9. Nov 23 22:47:40.603212 sshd[1961]: Accepted publickey for core from 10.0.0.1 port 51344 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:40.604513 sshd-session[1961]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:40.609511 systemd-logind[1448]: New session 10 of user core. Nov 23 22:47:40.619669 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 23 22:47:40.670724 sudo[1965]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1477 Nov 23 22:47:40.670986 sudo[1965]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 23 22:47:40.674903 sudo[1965]: pam_unix(sudo:session): session closed for user root Nov 23 22:47:40.675406 containerd[1477]: time="2025-11-23T22:47:40.675349214Z" level=info msg="Stop CRI service" Nov 23 22:47:40.675807 dockerd[1661]: time="2025-11-23T22:47:40.675700411Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Nov 23 22:47:40.675807 dockerd[1661]: time="2025-11-23T22:47:40.675752718Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Nov 23 22:47:40.676914 dockerd[1661]: time="2025-11-23T22:47:40.675804905Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Nov 23 22:47:40.676914 dockerd[1661]: time="2025-11-23T22:47:40.675843697Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Nov 23 22:47:40.677265 sshd[1964]: Connection closed by 10.0.0.1 port 51344 Nov 23 22:47:40.677608 sshd-session[1961]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:40.689578 systemd[1]: containerd.service: Deactivated successfully. Nov 23 22:47:40.689677 systemd[1]: containerd.service: Unit process 1904 (containerd-shim) remains running after unit stopped. Nov 23 22:47:40.689903 systemd[1]: containerd.service: Consumed 324ms CPU time, 120.9M memory peak. Nov 23 22:47:40.690256 systemd[1]: sshd@9-10.0.0.3:22-10.0.0.1:51344.service: Deactivated successfully. Nov 23 22:47:40.691944 systemd[1]: session-10.scope: Deactivated successfully. Nov 23 22:47:40.694028 systemd-logind[1448]: Session 10 logged out. Waiting for processes to exit. Nov 23 22:47:40.696863 systemd[1]: Started sshd@10-10.0.0.3:22-10.0.0.1:51356.service - OpenSSH per-connection server daemon (10.0.0.1:51356). Nov 23 22:47:40.698533 systemd-logind[1448]: Removed session 10. Nov 23 22:47:40.775487 sshd[1972]: Accepted publickey for core from 10.0.0.1 port 51356 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:40.778507 sshd-session[1972]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:40.783577 systemd-logind[1448]: New session 11 of user core. Nov 23 22:47:40.794727 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 23 22:47:40.851290 sshd[1975]: Connection closed by 10.0.0.1 port 51356 Nov 23 22:47:40.851642 sshd-session[1972]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:40.855714 systemd[1]: sshd@10-10.0.0.3:22-10.0.0.1:51356.service: Deactivated successfully. Nov 23 22:47:40.857347 systemd[1]: session-11.scope: Deactivated successfully. Nov 23 22:47:40.858211 systemd-logind[1448]: Session 11 logged out. Waiting for processes to exit. Nov 23 22:47:40.859319 systemd-logind[1448]: Removed session 11. Nov 23 22:47:41.580642 systemd-networkd[1395]: docker0: Gained IPv6LL Nov 23 22:47:42.092623 systemd-networkd[1395]: vethfa8f358: Gained IPv6LL Nov 23 22:47:45.905671 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Nov 23 22:47:45.906011 systemd[1]: Stopping docker.service - Docker Application Container Engine... Nov 23 22:47:45.906246 dockerd[1661]: time="2025-11-23T22:47:45.906165866Z" level=info msg="Processing signal 'terminated'" Nov 23 22:47:46.846478 systemd[1]: Started sshd@11-10.0.0.3:22-10.0.0.1:51436.service - OpenSSH per-connection server daemon (10.0.0.1:51436). Nov 23 22:47:46.895331 sshd[1981]: Accepted publickey for core from 10.0.0.1 port 51436 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:46.896718 sshd-session[1981]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:46.902829 systemd-logind[1448]: New session 12 of user core. Nov 23 22:47:46.914670 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 23 22:47:46.968322 sshd[1984]: Connection closed by 10.0.0.1 port 51436 Nov 23 22:47:46.968645 sshd-session[1981]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:46.972579 systemd[1]: sshd@11-10.0.0.3:22-10.0.0.1:51436.service: Deactivated successfully. Nov 23 22:47:46.975850 systemd[1]: session-12.scope: Deactivated successfully. Nov 23 22:47:46.977752 systemd-logind[1448]: Session 12 logged out. Waiting for processes to exit. Nov 23 22:47:46.978893 systemd-logind[1448]: Removed session 12. Nov 23 22:47:52.969762 systemd[1]: Started sshd@12-10.0.0.3:22-10.0.0.1:46638.service - OpenSSH per-connection server daemon (10.0.0.1:46638). Nov 23 22:47:53.031744 sshd[1990]: Accepted publickey for core from 10.0.0.1 port 46638 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:53.033205 sshd-session[1990]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:53.037326 systemd-logind[1448]: New session 13 of user core. Nov 23 22:47:53.052701 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 23 22:47:53.109496 sshd[1993]: Connection closed by 10.0.0.1 port 46638 Nov 23 22:47:53.110616 sshd-session[1990]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:53.114655 systemd[1]: sshd@12-10.0.0.3:22-10.0.0.1:46638.service: Deactivated successfully. Nov 23 22:47:53.116997 systemd[1]: session-13.scope: Deactivated successfully. Nov 23 22:47:53.118693 systemd-logind[1448]: Session 13 logged out. Waiting for processes to exit. Nov 23 22:47:53.120024 systemd-logind[1448]: Removed session 13. Nov 23 22:47:59.123785 systemd[1]: Started sshd@13-10.0.0.3:22-10.0.0.1:46722.service - OpenSSH per-connection server daemon (10.0.0.1:46722). Nov 23 22:47:59.193053 sshd[1999]: Accepted publickey for core from 10.0.0.1 port 46722 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:47:59.195914 sshd-session[1999]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:47:59.202049 systemd-logind[1448]: New session 14 of user core. Nov 23 22:47:59.208708 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 23 22:47:59.262158 sshd[2002]: Connection closed by 10.0.0.1 port 46722 Nov 23 22:47:59.262522 sshd-session[1999]: pam_unix(sshd:session): session closed for user core Nov 23 22:47:59.266837 systemd[1]: sshd@13-10.0.0.3:22-10.0.0.1:46722.service: Deactivated successfully. Nov 23 22:47:59.268535 systemd[1]: session-14.scope: Deactivated successfully. Nov 23 22:47:59.270430 systemd-logind[1448]: Session 14 logged out. Waiting for processes to exit. Nov 23 22:47:59.271992 systemd-logind[1448]: Removed session 14. Nov 23 22:48:00.907236 dockerd[1661]: time="2025-11-23T22:48:00.907139992Z" level=error msg="Force shutdown daemon" Nov 23 22:48:00.907878 dockerd[1661]: time="2025-11-23T22:48:00.907638779Z" level=info msg="Daemon shutdown complete" Nov 23 22:48:00.910681 systemd[1]: docker.service: Deactivated successfully. Nov 23 22:48:00.912514 systemd[1]: Stopped docker.service - Docker Application Container Engine. Nov 23 22:48:00.912581 systemd[1]: docker.service: Consumed 1.047s CPU time, 123.7M memory peak. Nov 23 22:48:00.912942 systemd[1]: docker.socket: Deactivated successfully. Nov 23 22:48:00.913186 systemd[1]: Closed docker.socket - Docker Socket for the API. Nov 23 22:48:00.913221 systemd[1]: Stopping docker.socket - Docker Socket for the API... Nov 23 22:48:00.917621 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 23 22:48:00.917797 systemd[1]: containerd.service: Found left-over process 1904 (containerd-shim) in control group while starting unit. Ignoring. Nov 23 22:48:00.917805 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 23 22:48:00.924604 systemd[1]: Starting containerd.service - containerd container runtime... Nov 23 22:48:00.925891 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 23 22:48:00.935651 systemd[1]: containerd.service: Found left-over process 1904 (containerd-shim) in control group while starting unit. Ignoring. Nov 23 22:48:00.935664 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 23 22:48:00.940871 (ntainerd)[2013]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 23 22:48:00.956950 containerd[2013]: time="2025-11-23T22:48:00Z" 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 Nov 23 22:48:00.957473 containerd[2013]: time="2025-11-23T22:48:00.957432378Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Nov 23 22:48:00.965223 containerd[2013]: time="2025-11-23T22:48:00.965171866Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.391µs" Nov 23 22:48:00.965223 containerd[2013]: time="2025-11-23T22:48:00.965211390Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 23 22:48:00.965223 containerd[2013]: time="2025-11-23T22:48:00.965229414Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 23 22:48:00.965368 containerd[2013]: time="2025-11-23T22:48:00.965263463Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 23 22:48:00.965368 containerd[2013]: time="2025-11-23T22:48:00.965278489Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 23 22:48:00.965523 containerd[2013]: time="2025-11-23T22:48:00.965503525Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965559 containerd[2013]: time="2025-11-23T22:48:00.965538213Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965559 containerd[2013]: time="2025-11-23T22:48:00.965550283Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965788 containerd[2013]: time="2025-11-23T22:48:00.965755176Z" 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 Nov 23 22:48:00.965788 containerd[2013]: time="2025-11-23T22:48:00.965775478Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965788 containerd[2013]: time="2025-11-23T22:48:00.965786188Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965851 containerd[2013]: time="2025-11-23T22:48:00.965795140Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965851 containerd[2013]: time="2025-11-23T22:48:00.965817959Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965933 containerd[2013]: time="2025-11-23T22:48:00.965899725Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 23 22:48:00.965933 containerd[2013]: time="2025-11-23T22:48:00.965919387Z" 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 Nov 23 22:48:00.965933 containerd[2013]: time="2025-11-23T22:48:00.965928099Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 23 22:48:00.965991 containerd[2013]: time="2025-11-23T22:48:00.965962708Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 23 22:48:00.966341 containerd[2013]: time="2025-11-23T22:48:00.966294526Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 23 22:48:00.966372 containerd[2013]: time="2025-11-23T22:48:00.966341803Z" level=info msg="metadata content store policy set" policy=shared Nov 23 22:48:00.967552 containerd[2013]: time="2025-11-23T22:48:00.967474854Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 23 22:48:00.967618 containerd[2013]: time="2025-11-23T22:48:00.967585074Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 23 22:48:00.967618 containerd[2013]: time="2025-11-23T22:48:00.967612609Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 23 22:48:00.967655 containerd[2013]: time="2025-11-23T22:48:00.967632391Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 23 22:48:00.967655 containerd[2013]: time="2025-11-23T22:48:00.967649775Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 23 22:48:00.967690 containerd[2013]: time="2025-11-23T22:48:00.967662244Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 23 22:48:00.967690 containerd[2013]: time="2025-11-23T22:48:00.967677710Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 23 22:48:00.967690 containerd[2013]: time="2025-11-23T22:48:00.967693775Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 23 22:48:00.967758 containerd[2013]: time="2025-11-23T22:48:00.967708681Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 23 22:48:00.967758 containerd[2013]: time="2025-11-23T22:48:00.967719831Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 23 22:48:00.967758 containerd[2013]: time="2025-11-23T22:48:00.967733699Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 23 22:48:00.967758 containerd[2013]: time="2025-11-23T22:48:00.967750364Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 23 22:48:00.968013 containerd[2013]: time="2025-11-23T22:48:00.967983272Z" level=info msg="connecting to shim e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae" address="unix:///run/containerd/s/928599d96ba28a6c2e49babd7d5706fd7a21d4752fb772ca5126aea36cc14626" namespace=moby protocol=ttrpc version=3 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977534194Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977586867Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977603212Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977614641Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977626590Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977637021Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977666874Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977677864Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977688934Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977699804Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977710314Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977758071Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977771499Z" level=info msg="Start snapshots syncer" Nov 23 22:48:00.978519 containerd[2013]: time="2025-11-23T22:48:00.977804109Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 23 22:48:00.978835 containerd[2013]: time="2025-11-23T22:48:00.978037817Z" 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\"}" Nov 23 22:48:00.978835 containerd[2013]: time="2025-11-23T22:48:00.978083575Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978136008Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978183804Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978200869Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978210740Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978221170Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978232960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978244269Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978256218Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978290947Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978305334Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978315964Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978357806Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978372193Z" 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 Nov 23 22:48:00.978939 containerd[2013]: time="2025-11-23T22:48:00.978380825Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:48:00.979170 containerd[2013]: time="2025-11-23T22:48:00.978389897Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:48:00.979170 containerd[2013]: time="2025-11-23T22:48:00.978397490Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 23 22:48:00.979170 containerd[2013]: time="2025-11-23T22:48:00.978408560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 23 22:48:00.979170 containerd[2013]: time="2025-11-23T22:48:00.978421348Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 23 22:48:00.979170 containerd[2013]: time="2025-11-23T22:48:00.978438253Z" level=info msg="runtime interface created" Nov 23 22:48:00.979170 containerd[2013]: time="2025-11-23T22:48:00.978443448Z" level=info msg="created NRI interface" Nov 23 22:48:00.979170 containerd[2013]: time="2025-11-23T22:48:00.978454998Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 23 22:48:00.979341 containerd[2013]: time="2025-11-23T22:48:00.979322490Z" level=info msg="Connect containerd service" Nov 23 22:48:00.979412 containerd[2013]: time="2025-11-23T22:48:00.979398860Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 23 22:48:00.979998 containerd[2013]: time="2025-11-23T22:48:00.979968343Z" 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" Nov 23 22:48:00.990902 containerd[2013]: time="2025-11-23T22:48:00.990813290Z" level=info msg="Start subscribing containerd event" Nov 23 22:48:00.991016 containerd[2013]: time="2025-11-23T22:48:00.990831913Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 23 22:48:00.991016 containerd[2013]: time="2025-11-23T22:48:00.990985973Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 23 22:48:00.991016 containerd[2013]: time="2025-11-23T22:48:00.990927146Z" level=info msg="Start recovering state" Nov 23 22:48:00.991096 containerd[2013]: time="2025-11-23T22:48:00.991076450Z" level=info msg="Start event monitor" Nov 23 22:48:00.991096 containerd[2013]: time="2025-11-23T22:48:00.991098950Z" level=info msg="Start cni network conf syncer for default" Nov 23 22:48:00.991148 containerd[2013]: time="2025-11-23T22:48:00.991107622Z" level=info msg="Start streaming server" Nov 23 22:48:00.991148 containerd[2013]: time="2025-11-23T22:48:00.991115055Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 23 22:48:00.991374 containerd[2013]: time="2025-11-23T22:48:00.991122169Z" level=info msg="runtime interface starting up..." Nov 23 22:48:00.991374 containerd[2013]: time="2025-11-23T22:48:00.991168687Z" level=info msg="starting plugins..." Nov 23 22:48:00.991374 containerd[2013]: time="2025-11-23T22:48:00.991177678Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 23 22:48:00.991374 containerd[2013]: time="2025-11-23T22:48:00.991281544Z" level=info msg="containerd successfully booted in 0.034503s" Nov 23 22:48:00.991405 systemd[1]: Started containerd.service - containerd container runtime. Nov 23 22:48:00.994394 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 23 22:48:01.011874 (dockerd)[2042]: 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 Nov 23 22:48:01.044268 dockerd[2042]: time="2025-11-23T22:48:01.044204528Z" level=info msg="Starting up" Nov 23 22:48:01.044957 dockerd[2042]: time="2025-11-23T22:48:01.044909887Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 23 22:48:01.056826 dockerd[2042]: time="2025-11-23T22:48:01.056737373Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 23 22:48:01.081830 dockerd[2042]: time="2025-11-23T22:48:01.081548840Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Nov 23 22:48:01.091748 dockerd[2042]: time="2025-11-23T22:48:01.091563151Z" level=info msg="Loading containers: start." Nov 23 22:48:05.268534 systemd[1]: Started sshd@14-10.0.0.3:22-10.0.0.1:43922.service - OpenSSH per-connection server daemon (10.0.0.1:43922). Nov 23 22:48:05.325630 sshd[2075]: Accepted publickey for core from 10.0.0.1 port 43922 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:05.326896 sshd-session[2075]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:05.331199 systemd-logind[1448]: New session 15 of user core. Nov 23 22:48:05.340649 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 23 22:48:05.394807 sshd[2078]: Connection closed by 10.0.0.1 port 43922 Nov 23 22:48:05.395172 sshd-session[2075]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:05.408657 systemd[1]: sshd@14-10.0.0.3:22-10.0.0.1:43922.service: Deactivated successfully. Nov 23 22:48:05.410875 systemd[1]: session-15.scope: Deactivated successfully. Nov 23 22:48:05.412620 systemd-logind[1448]: Session 15 logged out. Waiting for processes to exit. Nov 23 22:48:05.414898 systemd[1]: Started sshd@15-10.0.0.3:22-10.0.0.1:43930.service - OpenSSH per-connection server daemon (10.0.0.1:43930). Nov 23 22:48:05.415549 systemd-logind[1448]: Removed session 15. Nov 23 22:48:05.484313 sshd[2084]: Accepted publickey for core from 10.0.0.1 port 43930 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:05.485657 sshd-session[2084]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:05.490146 systemd-logind[1448]: New session 16 of user core. Nov 23 22:48:05.499705 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 23 22:48:05.552826 sshd[2087]: Connection closed by 10.0.0.1 port 43930 Nov 23 22:48:05.553158 sshd-session[2084]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:05.569567 systemd[1]: sshd@15-10.0.0.3:22-10.0.0.1:43930.service: Deactivated successfully. Nov 23 22:48:05.571059 systemd[1]: session-16.scope: Deactivated successfully. Nov 23 22:48:05.573008 systemd-logind[1448]: Session 16 logged out. Waiting for processes to exit. Nov 23 22:48:05.574965 systemd[1]: Started sshd@16-10.0.0.3:22-10.0.0.1:43942.service - OpenSSH per-connection server daemon (10.0.0.1:43942). Nov 23 22:48:05.575887 systemd-logind[1448]: Removed session 16. Nov 23 22:48:05.630373 sshd[2093]: Accepted publickey for core from 10.0.0.1 port 43942 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:05.631669 sshd-session[2093]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:05.635513 systemd-logind[1448]: New session 17 of user core. Nov 23 22:48:05.649698 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 23 22:48:11.180910 dockerd[2042]: time="2025-11-23T22:48:11.180848105Z" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae Nov 23 22:48:11.193083 systemd[1]: docker-e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae.scope: Deactivated successfully. Nov 23 22:48:11.208939 containerd[2013]: time="2025-11-23T22:48:11.208732414Z" level=info msg="shim disconnected" id=e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae namespace=moby Nov 23 22:48:11.209961 dockerd[2042]: time="2025-11-23T22:48:11.208899180Z" level=info msg="ignoring event" container=e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 23 22:48:11.210119 containerd[2013]: time="2025-11-23T22:48:11.210067538Z" level=warning msg="cleaning up after shim disconnected" id=e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae namespace=moby Nov 23 22:48:11.210299 containerd[2013]: time="2025-11-23T22:48:11.210158657Z" level=info msg="cleaning up dead shim" namespace=moby Nov 23 22:48:11.226576 systemd[1]: var-lib-docker-overlay2-d52a28277ed0081ede2ede7fa2df93d29e7b43cce422aff3b085802ebfd62bc6-merged.mount: Deactivated successfully. Nov 23 22:48:11.769175 dockerd[2042]: time="2025-11-23T22:48:11.768459247Z" level=info msg="Removing stale sandbox 4ccb1846abdc090b492700a4c59e711d23d323cebbe494263c7efc72fa464b74 (e773c6c9f02b0f4e26875af13d85215f33d600bbe13fc53c8c976d47bed43dae)" Nov 23 22:48:11.768717 systemd[1]: run-docker-netns-4ccb1846abdc.mount: Deactivated successfully. Nov 23 22:48:11.769940 systemd-networkd[1395]: vethfa8f358: Link DOWN Nov 23 22:48:11.769954 systemd-networkd[1395]: vethfa8f358: Lost carrier Nov 23 22:48:11.773683 kernel: docker0: port 1(vethfa8f358) entered disabled state Nov 23 22:48:11.773763 kernel: vethfa8f358 (unregistering): left allmulticast mode Nov 23 22:48:11.773778 kernel: vethfa8f358 (unregistering): left promiscuous mode Nov 23 22:48:11.773797 kernel: docker0: port 1(vethfa8f358) entered disabled state Nov 23 22:48:11.773817 dockerd[2042]: time="2025-11-23T22:48:11.773501436Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Nov 23 22:48:11.773817 dockerd[2042]: time="2025-11-23T22:48:11.773601191Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 9a67608fe0bfa455b9c85a08525156448a30ffe8e76b0807d4171514dbd9033e da54a1aa2a967babdbc579a8c86100698fcc524000dac15a746537e1af6e1db7], retrying...." Nov 23 22:48:11.777660 systemd-networkd[1395]: docker0: Lost carrier Nov 23 22:48:11.780660 systemd[1]: run-docker-netns-4ccb1846abdc.mount: Deactivated successfully. Nov 23 22:48:11.952561 dockerd[2042]: time="2025-11-23T22:48:11.952497871Z" level=warning msg="error locating sandbox id 4ccb1846abdc090b492700a4c59e711d23d323cebbe494263c7efc72fa464b74: sandbox 4ccb1846abdc090b492700a4c59e711d23d323cebbe494263c7efc72fa464b74 not found" Nov 23 22:48:11.952719 dockerd[2042]: time="2025-11-23T22:48:11.952589230Z" level=info msg="Loading containers: done." Nov 23 22:48:11.969918 dockerd[2042]: time="2025-11-23T22:48:11.969861078Z" 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 Nov 23 22:48:11.970089 dockerd[2042]: time="2025-11-23T22:48:11.969966231Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 23 22:48:11.970089 dockerd[2042]: time="2025-11-23T22:48:11.970002615Z" level=info msg="Initializing buildkit" Nov 23 22:48:11.984175 dockerd[2042]: time="2025-11-23T22:48:11.984034469Z" level=info msg="Completed buildkit initialization" Nov 23 22:48:11.993721 dockerd[2042]: time="2025-11-23T22:48:11.993673805Z" level=info msg="Daemon has completed initialization" Nov 23 22:48:11.993799 dockerd[2042]: time="2025-11-23T22:48:11.993732899Z" level=info msg="API listen on /run/docker.sock" Nov 23 22:48:11.994061 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 23 22:48:12.005489 sshd[2096]: Connection closed by 10.0.0.1 port 43942 Nov 23 22:48:12.005385 sshd-session[2093]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:12.018643 systemd[1]: sshd@16-10.0.0.3:22-10.0.0.1:43942.service: Deactivated successfully. Nov 23 22:48:12.020244 systemd[1]: session-17.scope: Deactivated successfully. Nov 23 22:48:12.023023 systemd-logind[1448]: Session 17 logged out. Waiting for processes to exit. Nov 23 22:48:12.024298 systemd[1]: Started sshd@17-10.0.0.3:22-10.0.0.1:59896.service - OpenSSH per-connection server daemon (10.0.0.1:59896). Nov 23 22:48:12.025146 systemd-logind[1448]: Removed session 17. Nov 23 22:48:12.083920 sshd[2347]: Accepted publickey for core from 10.0.0.1 port 59896 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:12.085262 sshd-session[2347]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:12.089200 systemd-logind[1448]: New session 18 of user core. Nov 23 22:48:12.101673 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 23 22:48:12.211996 containerd[2013]: time="2025-11-23T22:48:12.211947099Z" level=info msg="connecting to shim 940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61" address="unix:///run/containerd/s/a0ea5fc5af15fc4ccbee87f6cfedac92101b2a1efb846517a7438d034aa81c0c" namespace=moby protocol=ttrpc version=3 Nov 23 22:48:12.243683 systemd[1]: Started docker-940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61.scope - libcontainer container 940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61. Nov 23 22:48:12.254317 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 23 22:48:12.270756 kernel: docker0: port 1(vethc820345) entered blocking state Nov 23 22:48:12.270926 kernel: docker0: port 1(vethc820345) entered disabled state Nov 23 22:48:12.270946 kernel: vethc820345: entered allmulticast mode Nov 23 22:48:12.270967 kernel: vethc820345: entered promiscuous mode Nov 23 22:48:12.271712 systemd-networkd[1395]: vethc820345: Link UP Nov 23 22:48:12.279485 kernel: eth0: renamed from veth738f859 Nov 23 22:48:12.284132 kernel: docker0: port 1(vethc820345) entered blocking state Nov 23 22:48:12.284224 kernel: docker0: port 1(vethc820345) entered forwarding state Nov 23 22:48:12.284230 systemd-networkd[1395]: vethc820345: Gained carrier Nov 23 22:48:12.284839 systemd-networkd[1395]: docker0: Gained carrier Nov 23 22:48:12.304959 sshd[2350]: Connection closed by 10.0.0.1 port 59896 Nov 23 22:48:12.305351 sshd-session[2347]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:12.318111 systemd[1]: sshd@17-10.0.0.3:22-10.0.0.1:59896.service: Deactivated successfully. Nov 23 22:48:12.319890 systemd[1]: session-18.scope: Deactivated successfully. Nov 23 22:48:12.323049 systemd-logind[1448]: Session 18 logged out. Waiting for processes to exit. Nov 23 22:48:12.324383 systemd[1]: Started sshd@18-10.0.0.3:22-10.0.0.1:59920.service - OpenSSH per-connection server daemon (10.0.0.1:59920). Nov 23 22:48:12.325993 systemd-logind[1448]: Removed session 18. Nov 23 22:48:12.397514 sshd[2418]: Accepted publickey for core from 10.0.0.1 port 59920 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:12.398886 sshd-session[2418]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:12.403555 systemd-logind[1448]: New session 19 of user core. Nov 23 22:48:12.419682 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 23 22:48:12.473807 sshd[2422]: Connection closed by 10.0.0.1 port 59920 Nov 23 22:48:12.474291 sshd-session[2418]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:12.487671 systemd[1]: sshd@18-10.0.0.3:22-10.0.0.1:59920.service: Deactivated successfully. Nov 23 22:48:12.489243 systemd[1]: session-19.scope: Deactivated successfully. Nov 23 22:48:12.489980 systemd-logind[1448]: Session 19 logged out. Waiting for processes to exit. Nov 23 22:48:12.491986 systemd[1]: Started sshd@19-10.0.0.3:22-10.0.0.1:59926.service - OpenSSH per-connection server daemon (10.0.0.1:59926). Nov 23 22:48:12.493012 systemd-logind[1448]: Removed session 19. Nov 23 22:48:12.551226 sshd[2428]: Accepted publickey for core from 10.0.0.1 port 59926 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:12.552563 sshd-session[2428]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:12.556402 systemd-logind[1448]: New session 20 of user core. Nov 23 22:48:12.566675 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 23 22:48:12.617904 sudo[2432]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 2013 Nov 23 22:48:12.618184 sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 23 22:48:12.621253 sudo[2432]: pam_unix(sudo:session): session closed for user root Nov 23 22:48:12.623560 sshd[2431]: Connection closed by 10.0.0.1 port 59926 Nov 23 22:48:12.623824 dockerd[2042]: time="2025-11-23T22:48:12.623703739Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Nov 23 22:48:12.623824 dockerd[2042]: time="2025-11-23T22:48:12.623736405Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Nov 23 22:48:12.624119 dockerd[2042]: time="2025-11-23T22:48:12.623714055Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Nov 23 22:48:12.624119 dockerd[2042]: time="2025-11-23T22:48:12.623913211Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Nov 23 22:48:12.624368 sshd-session[2428]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:12.635482 systemd[1]: containerd.service: Deactivated successfully. Nov 23 22:48:12.635581 systemd[1]: containerd.service: Unit process 2370 (containerd-shim) remains running after unit stopped. Nov 23 22:48:12.635791 systemd[1]: containerd.service: Consumed 189ms CPU time, 123M memory peak. Nov 23 22:48:12.636157 systemd[1]: sshd@19-10.0.0.3:22-10.0.0.1:59926.service: Deactivated successfully. Nov 23 22:48:12.638846 systemd[1]: session-20.scope: Deactivated successfully. Nov 23 22:48:12.639537 systemd-logind[1448]: Session 20 logged out. Waiting for processes to exit. Nov 23 22:48:12.643142 systemd[1]: Started sshd@20-10.0.0.3:22-10.0.0.1:59936.service - OpenSSH per-connection server daemon (10.0.0.1:59936). Nov 23 22:48:12.643617 systemd-logind[1448]: Removed session 20. Nov 23 22:48:12.703103 sshd[2439]: Accepted publickey for core from 10.0.0.1 port 59936 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:12.704484 sshd-session[2439]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:12.708483 systemd-logind[1448]: New session 21 of user core. Nov 23 22:48:12.729656 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 23 22:48:12.788516 sshd[2442]: Connection closed by 10.0.0.1 port 59936 Nov 23 22:48:12.788575 sshd-session[2439]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:12.792973 systemd[1]: sshd@20-10.0.0.3:22-10.0.0.1:59936.service: Deactivated successfully. Nov 23 22:48:12.796012 systemd[1]: session-21.scope: Deactivated successfully. Nov 23 22:48:12.797276 systemd-logind[1448]: Session 21 logged out. Waiting for processes to exit. Nov 23 22:48:12.798357 systemd-logind[1448]: Removed session 21. Nov 23 22:48:13.772699 systemd-networkd[1395]: vethc820345: Gained IPv6LL Nov 23 22:48:14.391564 update_engine[1460]: I20251123 22:48:14.391067 1460 update_attempter.cc:509] Updating boot flags... Nov 23 22:48:17.655385 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Nov 23 22:48:17.655753 systemd[1]: Stopping docker.service - Docker Application Container Engine... Nov 23 22:48:17.655975 dockerd[2042]: time="2025-11-23T22:48:17.655907620Z" level=info msg="Processing signal 'terminated'" Nov 23 22:48:18.797758 systemd[1]: Started sshd@21-10.0.0.3:22-10.0.0.1:60080.service - OpenSSH per-connection server daemon (10.0.0.1:60080). Nov 23 22:48:18.867660 sshd[2457]: Accepted publickey for core from 10.0.0.1 port 60080 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:18.869147 sshd-session[2457]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:18.873230 systemd-logind[1448]: New session 22 of user core. Nov 23 22:48:18.884717 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 23 22:48:18.938488 sshd[2460]: Connection closed by 10.0.0.1 port 60080 Nov 23 22:48:18.939190 sshd-session[2457]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:18.942869 systemd[1]: sshd@21-10.0.0.3:22-10.0.0.1:60080.service: Deactivated successfully. Nov 23 22:48:18.944498 systemd[1]: session-22.scope: Deactivated successfully. Nov 23 22:48:18.945258 systemd-logind[1448]: Session 22 logged out. Waiting for processes to exit. Nov 23 22:48:18.947305 systemd-logind[1448]: Removed session 22. Nov 23 22:48:19.658838 dockerd[2042]: time="2025-11-23T22:48:19.658731529Z" level=error msg="Error sending stop (signal 15) to container" container=940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61 error="Cannot kill container 940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Nov 23 22:48:19.658838 dockerd[2042]: time="2025-11-23T22:48:19.658806189Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61 Nov 23 22:48:24.961494 systemd[1]: Started sshd@22-10.0.0.3:22-10.0.0.1:47576.service - OpenSSH per-connection server daemon (10.0.0.1:47576). Nov 23 22:48:25.010307 sshd[2466]: Accepted publickey for core from 10.0.0.1 port 47576 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:25.011655 sshd-session[2466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:25.016108 systemd-logind[1448]: New session 23 of user core. Nov 23 22:48:25.025657 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 23 22:48:25.079795 sshd[2469]: Connection closed by 10.0.0.1 port 47576 Nov 23 22:48:25.080092 sshd-session[2466]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:25.083353 systemd[1]: sshd@22-10.0.0.3:22-10.0.0.1:47576.service: Deactivated successfully. Nov 23 22:48:25.085905 systemd[1]: session-23.scope: Deactivated successfully. Nov 23 22:48:25.086656 systemd-logind[1448]: Session 23 logged out. Waiting for processes to exit. Nov 23 22:48:25.087827 systemd-logind[1448]: Removed session 23. Nov 23 22:48:29.659181 dockerd[2042]: time="2025-11-23T22:48:29.659076323Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61 error="context deadline exceeded" Nov 23 22:48:29.659918 systemd[1]: docker-940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61.scope: Deactivated successfully. Nov 23 22:48:31.094207 systemd[1]: Started sshd@23-10.0.0.3:22-10.0.0.1:42736.service - OpenSSH per-connection server daemon (10.0.0.1:42736). Nov 23 22:48:31.164840 sshd[2475]: Accepted publickey for core from 10.0.0.1 port 42736 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:31.166122 sshd-session[2475]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:31.174531 systemd-logind[1448]: New session 24 of user core. Nov 23 22:48:31.186665 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 23 22:48:31.245505 sshd[2478]: Connection closed by 10.0.0.1 port 42736 Nov 23 22:48:31.245514 sshd-session[2475]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:31.248972 systemd-logind[1448]: Session 24 logged out. Waiting for processes to exit. Nov 23 22:48:31.249045 systemd[1]: sshd@23-10.0.0.3:22-10.0.0.1:42736.service: Deactivated successfully. Nov 23 22:48:31.250629 systemd[1]: session-24.scope: Deactivated successfully. Nov 23 22:48:31.253105 systemd-logind[1448]: Removed session 24. Nov 23 22:48:32.656765 dockerd[2042]: time="2025-11-23T22:48:32.656690382Z" level=error msg="Force shutdown daemon" Nov 23 22:48:32.657181 dockerd[2042]: time="2025-11-23T22:48:32.656944713Z" level=info msg="Daemon shutdown complete" Nov 23 22:48:32.660444 systemd[1]: docker.service: Deactivated successfully. Nov 23 22:48:32.660785 systemd[1]: Stopped docker.service - Docker Application Container Engine. Nov 23 22:48:32.661258 systemd[1]: docker.socket: Deactivated successfully. Nov 23 22:48:32.661545 systemd[1]: Closed docker.socket - Docker Socket for the API. Nov 23 22:48:32.661582 systemd[1]: Stopping docker.socket - Docker Socket for the API... Nov 23 22:48:32.663007 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 23 22:48:32.663178 systemd[1]: containerd.service: Found left-over process 2370 (containerd-shim) in control group while starting unit. Ignoring. Nov 23 22:48:32.663186 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 23 22:48:32.673964 systemd[1]: Starting containerd.service - containerd container runtime... Nov 23 22:48:32.674303 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 23 22:48:32.688132 systemd[1]: containerd.service: Found left-over process 2370 (containerd-shim) in control group while starting unit. Ignoring. Nov 23 22:48:32.688142 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 23 22:48:32.693050 (ntainerd)[2489]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 23 22:48:32.709475 containerd[2489]: time="2025-11-23T22:48:32Z" 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 Nov 23 22:48:32.709475 containerd[2489]: time="2025-11-23T22:48:32.709085511Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Nov 23 22:48:32.716780 containerd[2489]: time="2025-11-23T22:48:32.716732271Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="11.359µs" Nov 23 22:48:32.716900 containerd[2489]: time="2025-11-23T22:48:32.716886173Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 23 22:48:32.716955 containerd[2489]: time="2025-11-23T22:48:32.716942767Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 23 22:48:32.717028 containerd[2489]: time="2025-11-23T22:48:32.717016198Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 23 22:48:32.717075 containerd[2489]: time="2025-11-23T22:48:32.717064673Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 23 22:48:32.717332 containerd[2489]: time="2025-11-23T22:48:32.717312444Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:48:32.717428 containerd[2489]: time="2025-11-23T22:48:32.717411633Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:48:32.717485 containerd[2489]: time="2025-11-23T22:48:32.717461187Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 23 22:48:32.717744 containerd[2489]: time="2025-11-23T22:48:32.717723677Z" 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 Nov 23 22:48:32.717799 containerd[2489]: time="2025-11-23T22:48:32.717787030Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:48:32.717860 containerd[2489]: time="2025-11-23T22:48:32.717846863Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:48:32.717913 containerd[2489]: time="2025-11-23T22:48:32.717900256Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 23 22:48:32.717973 containerd[2489]: time="2025-11-23T22:48:32.717961689Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 23 22:48:32.718102 containerd[2489]: time="2025-11-23T22:48:32.718086235Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 23 22:48:32.718171 containerd[2489]: time="2025-11-23T22:48:32.718156587Z" 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 Nov 23 22:48:32.718244 containerd[2489]: time="2025-11-23T22:48:32.718229339Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 23 22:48:32.718325 containerd[2489]: time="2025-11-23T22:48:32.718310929Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 23 22:48:32.718606 containerd[2489]: time="2025-11-23T22:48:32.718589697Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 23 22:48:32.718673 containerd[2489]: time="2025-11-23T22:48:32.718660769Z" level=info msg="metadata content store policy set" policy=shared Nov 23 22:48:32.718862 containerd[2489]: time="2025-11-23T22:48:32.718842908Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 23 22:48:32.718951 containerd[2489]: time="2025-11-23T22:48:32.718937457Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 23 22:48:32.719756 containerd[2489]: time="2025-11-23T22:48:32.719192148Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 23 22:48:32.719866 containerd[2489]: time="2025-11-23T22:48:32.719846552Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 23 22:48:32.719951 containerd[2489]: time="2025-11-23T22:48:32.719937582Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 23 22:48:32.720015 containerd[2489]: time="2025-11-23T22:48:32.720002095Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 23 22:48:32.720109 containerd[2489]: time="2025-11-23T22:48:32.720091324Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 23 22:48:32.720173 containerd[2489]: time="2025-11-23T22:48:32.720157637Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 23 22:48:32.720245 containerd[2489]: time="2025-11-23T22:48:32.720231108Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 23 22:48:32.720307 containerd[2489]: time="2025-11-23T22:48:32.720293941Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 23 22:48:32.720363 containerd[2489]: time="2025-11-23T22:48:32.720348575Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 23 22:48:32.720423 containerd[2489]: time="2025-11-23T22:48:32.720407888Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 23 22:48:32.720846 containerd[2489]: time="2025-11-23T22:48:32.720820880Z" level=info msg="connecting to shim 940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61" address="unix:///run/containerd/s/a0ea5fc5af15fc4ccbee87f6cfedac92101b2a1efb846517a7438d034aa81c0c" namespace=moby protocol=ttrpc version=3 Nov 23 22:48:32.727568 containerd[2489]: time="2025-11-23T22:48:32.727543786Z" level=info msg="cleaning leaked shim process" id=940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61 namespace=moby Nov 23 22:48:32.742135 containerd[2489]: time="2025-11-23T22:48:32.737063571Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Nov 23 22:48:32.742732 containerd[2489]: time="2025-11-23T22:48:32.742680524Z" level=info msg="shim disconnected" id=940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61 namespace=moby Nov 23 22:48:32.742782 containerd[2489]: time="2025-11-23T22:48:32.742731158Z" level=warning msg="cleaning up after shim disconnected" id=940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61 namespace=moby Nov 23 22:48:32.742782 containerd[2489]: time="2025-11-23T22:48:32.742771634Z" level=info msg="cleaning up dead shim" namespace=moby Nov 23 22:48:32.765583 containerd[2489]: time="2025-11-23T22:48:32.765533854Z" level=warning msg="cleanup warnings time=\"2025-11-23T22:48:32Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=moby Nov 23 22:48:32.766224 containerd[2489]: time="2025-11-23T22:48:32.766189458Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 23 22:48:32.766261 containerd[2489]: time="2025-11-23T22:48:32.766237293Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 23 22:48:32.766281 containerd[2489]: time="2025-11-23T22:48:32.766264730Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 23 22:48:32.766300 containerd[2489]: time="2025-11-23T22:48:32.766281008Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 23 22:48:32.766300 containerd[2489]: time="2025-11-23T22:48:32.766294526Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 23 22:48:32.766337 containerd[2489]: time="2025-11-23T22:48:32.766304045Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 23 22:48:32.766337 containerd[2489]: time="2025-11-23T22:48:32.766315484Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 23 22:48:32.766337 containerd[2489]: time="2025-11-23T22:48:32.766325723Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 23 22:48:32.766386 containerd[2489]: time="2025-11-23T22:48:32.766338121Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 23 22:48:32.766386 containerd[2489]: time="2025-11-23T22:48:32.766349720Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 23 22:48:32.766386 containerd[2489]: time="2025-11-23T22:48:32.766361438Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 23 22:48:32.766438 containerd[2489]: time="2025-11-23T22:48:32.766410913Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 23 22:48:32.766438 containerd[2489]: time="2025-11-23T22:48:32.766427631Z" level=info msg="Start snapshots syncer" Nov 23 22:48:32.766488 containerd[2489]: time="2025-11-23T22:48:32.766455588Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 23 22:48:32.766715 containerd[2489]: time="2025-11-23T22:48:32.766680282Z" 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\"}" Nov 23 22:48:32.766797 containerd[2489]: time="2025-11-23T22:48:32.766743114Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 23 22:48:32.766797 containerd[2489]: time="2025-11-23T22:48:32.766791189Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 23 22:48:32.766863 containerd[2489]: time="2025-11-23T22:48:32.766842783Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 23 22:48:32.766888 containerd[2489]: time="2025-11-23T22:48:32.766870500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 23 22:48:32.766888 containerd[2489]: time="2025-11-23T22:48:32.766882178Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 23 22:48:32.766969 containerd[2489]: time="2025-11-23T22:48:32.766892297Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 23 22:48:32.766969 containerd[2489]: time="2025-11-23T22:48:32.766922614Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 23 22:48:32.766969 containerd[2489]: time="2025-11-23T22:48:32.766934452Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 23 22:48:32.766969 containerd[2489]: time="2025-11-23T22:48:32.766946771Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 23 22:48:32.767063 containerd[2489]: time="2025-11-23T22:48:32.766972368Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 23 22:48:32.767063 containerd[2489]: time="2025-11-23T22:48:32.766984167Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 23 22:48:32.767063 containerd[2489]: time="2025-11-23T22:48:32.766994925Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 23 22:48:32.767063 containerd[2489]: time="2025-11-23T22:48:32.767030121Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 23 22:48:32.767063 containerd[2489]: time="2025-11-23T22:48:32.767044480Z" 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 Nov 23 22:48:32.767063 containerd[2489]: time="2025-11-23T22:48:32.767052919Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:48:32.767063 containerd[2489]: time="2025-11-23T22:48:32.767061958Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767070877Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767080356Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767089795Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767104473Z" level=info msg="runtime interface created" Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767109312Z" level=info msg="created NRI interface" Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767119271Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767132990Z" level=info msg="Connect containerd service" Nov 23 22:48:32.767243 containerd[2489]: time="2025-11-23T22:48:32.767154027Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 23 22:48:32.767638 containerd[2489]: time="2025-11-23T22:48:32.767612054Z" 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" Nov 23 22:48:32.776615 containerd[2489]: time="2025-11-23T22:48:32.776538467Z" level=info msg="Start subscribing containerd event" Nov 23 22:48:32.776697 containerd[2489]: time="2025-11-23T22:48:32.776628017Z" level=info msg="Start recovering state" Nov 23 22:48:32.776743 containerd[2489]: time="2025-11-23T22:48:32.776717206Z" level=info msg="Start event monitor" Nov 23 22:48:32.776743 containerd[2489]: time="2025-11-23T22:48:32.776732405Z" level=info msg="Start cni network conf syncer for default" Nov 23 22:48:32.776743 containerd[2489]: time="2025-11-23T22:48:32.776740724Z" level=info msg="Start streaming server" Nov 23 22:48:32.776799 containerd[2489]: time="2025-11-23T22:48:32.776749523Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 23 22:48:32.776799 containerd[2489]: time="2025-11-23T22:48:32.776756602Z" level=info msg="runtime interface starting up..." Nov 23 22:48:32.776799 containerd[2489]: time="2025-11-23T22:48:32.776762041Z" level=info msg="starting plugins..." Nov 23 22:48:32.776799 containerd[2489]: time="2025-11-23T22:48:32.776770560Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 23 22:48:32.777034 containerd[2489]: time="2025-11-23T22:48:32.777012332Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 23 22:48:32.777128 containerd[2489]: time="2025-11-23T22:48:32.777115921Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 23 22:48:32.777245 containerd[2489]: time="2025-11-23T22:48:32.777232427Z" level=info msg="containerd successfully booted in 0.068842s" Nov 23 22:48:32.777335 systemd[1]: Started containerd.service - containerd container runtime. Nov 23 22:48:32.779484 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 23 22:48:32.794907 (dockerd)[2538]: 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 Nov 23 22:48:32.835986 dockerd[2538]: time="2025-11-23T22:48:32.835549555Z" level=info msg="Starting up" Nov 23 22:48:32.836361 dockerd[2538]: time="2025-11-23T22:48:32.836339384Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 23 22:48:32.845552 dockerd[2538]: time="2025-11-23T22:48:32.845521567Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 23 22:48:32.862431 dockerd[2538]: time="2025-11-23T22:48:32.862390065Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Nov 23 22:48:32.870629 dockerd[2538]: time="2025-11-23T22:48:32.870594321Z" level=info msg="Loading containers: start." Nov 23 22:48:32.884909 systemd[1]: var-lib-docker-overlay2-cd2654163f80e0e6d639700225ef33b6b5b9808e4e280fa4153ad42ca6c704c2-merged.mount: Deactivated successfully. Nov 23 22:48:33.327639 systemd-networkd[1395]: vethc820345: Link DOWN Nov 23 22:48:33.328597 kernel: docker0: port 1(vethc820345) entered disabled state Nov 23 22:48:33.327649 systemd-networkd[1395]: vethc820345: Lost carrier Nov 23 22:48:33.330072 kernel: vethc820345 (unregistering): left allmulticast mode Nov 23 22:48:33.330116 kernel: vethc820345 (unregistering): left promiscuous mode Nov 23 22:48:33.330138 kernel: docker0: port 1(vethc820345) entered disabled state Nov 23 22:48:33.332177 systemd-networkd[1395]: docker0: Lost carrier Nov 23 22:48:33.332439 dockerd[2538]: time="2025-11-23T22:48:33.332411088Z" level=info msg="Removing stale sandbox 0c11131c61675b6b4e9a4b94543d1edf4353755bd1493328e8465aad9e2bc2bd (940b6dc95a426ab162180e58f3a7a88ef5cd266e0fcc62bb244ea3c146aa6b61)" Nov 23 22:48:33.335624 dockerd[2538]: time="2025-11-23T22:48:33.335581608Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Nov 23 22:48:33.335680 dockerd[2538]: time="2025-11-23T22:48:33.335638641Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint d2cde13a33d5aaff94d52955f39d38e2297171e0ca58bd4a63254aad8664a1ce 96226b7d6d9193a119d8d5bd724d77445260145da37397a361f9b8093060e661], retrying...." Nov 23 22:48:33.506643 dockerd[2538]: time="2025-11-23T22:48:33.506582374Z" level=warning msg="error locating sandbox id 0c11131c61675b6b4e9a4b94543d1edf4353755bd1493328e8465aad9e2bc2bd: sandbox 0c11131c61675b6b4e9a4b94543d1edf4353755bd1493328e8465aad9e2bc2bd not found" Nov 23 22:48:33.506643 dockerd[2538]: time="2025-11-23T22:48:33.506627849Z" level=warning msg="error locating sandbox id 4ccb1846abdc090b492700a4c59e711d23d323cebbe494263c7efc72fa464b74: sandbox 4ccb1846abdc090b492700a4c59e711d23d323cebbe494263c7efc72fa464b74 not found" Nov 23 22:48:33.506793 dockerd[2538]: time="2025-11-23T22:48:33.506709679Z" level=info msg="Loading containers: done." Nov 23 22:48:33.517664 dockerd[2538]: time="2025-11-23T22:48:33.517599882Z" 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 Nov 23 22:48:33.517822 dockerd[2538]: time="2025-11-23T22:48:33.517682072Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 23 22:48:33.517822 dockerd[2538]: time="2025-11-23T22:48:33.517713549Z" level=info msg="Initializing buildkit" Nov 23 22:48:33.529506 dockerd[2538]: time="2025-11-23T22:48:33.529454535Z" level=info msg="Completed buildkit initialization" Nov 23 22:48:33.536806 dockerd[2538]: time="2025-11-23T22:48:33.536759544Z" level=info msg="Daemon has completed initialization" Nov 23 22:48:33.536880 dockerd[2538]: time="2025-11-23T22:48:33.536839375Z" level=info msg="API listen on /run/docker.sock" Nov 23 22:48:33.536996 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 23 22:48:33.852000 systemd[1]: run-docker-netns-0c11131c6167.mount: Deactivated successfully. Nov 23 22:48:37.268353 systemd[1]: Started sshd@24-10.0.0.3:22-10.0.0.1:42752.service - OpenSSH per-connection server daemon (10.0.0.1:42752). Nov 23 22:48:37.332105 sshd[2777]: Accepted publickey for core from 10.0.0.1 port 42752 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:37.333414 sshd-session[2777]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:37.337793 systemd-logind[1448]: New session 25 of user core. Nov 23 22:48:37.353652 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 23 22:48:37.407616 sshd[2780]: Connection closed by 10.0.0.1 port 42752 Nov 23 22:48:37.408333 sshd-session[2777]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:37.425528 systemd[1]: sshd@24-10.0.0.3:22-10.0.0.1:42752.service: Deactivated successfully. Nov 23 22:48:37.427788 systemd[1]: session-25.scope: Deactivated successfully. Nov 23 22:48:37.428420 systemd-logind[1448]: Session 25 logged out. Waiting for processes to exit. Nov 23 22:48:37.430912 systemd[1]: Started sshd@25-10.0.0.3:22-10.0.0.1:42766.service - OpenSSH per-connection server daemon (10.0.0.1:42766). Nov 23 22:48:37.432893 systemd-logind[1448]: Removed session 25. Nov 23 22:48:37.495853 sshd[2786]: Accepted publickey for core from 10.0.0.1 port 42766 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:37.497327 sshd-session[2786]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:37.502366 systemd-logind[1448]: New session 26 of user core. Nov 23 22:48:37.511645 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 23 22:48:37.566611 sshd[2789]: Connection closed by 10.0.0.1 port 42766 Nov 23 22:48:37.567352 sshd-session[2786]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:37.581114 systemd[1]: sshd@25-10.0.0.3:22-10.0.0.1:42766.service: Deactivated successfully. Nov 23 22:48:37.582835 systemd[1]: session-26.scope: Deactivated successfully. Nov 23 22:48:37.583606 systemd-logind[1448]: Session 26 logged out. Waiting for processes to exit. Nov 23 22:48:37.586012 systemd[1]: Started sshd@26-10.0.0.3:22-10.0.0.1:42778.service - OpenSSH per-connection server daemon (10.0.0.1:42778). Nov 23 22:48:37.587912 systemd-logind[1448]: Removed session 26. Nov 23 22:48:37.646690 sshd[2795]: Accepted publickey for core from 10.0.0.1 port 42778 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:37.648036 sshd-session[2795]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:37.652745 systemd-logind[1448]: New session 27 of user core. Nov 23 22:48:37.669666 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 23 22:48:37.732275 sshd[2798]: Connection closed by 10.0.0.1 port 42778 Nov 23 22:48:37.731932 sshd-session[2795]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:37.746432 systemd[1]: sshd@26-10.0.0.3:22-10.0.0.1:42778.service: Deactivated successfully. Nov 23 22:48:37.747819 systemd[1]: session-27.scope: Deactivated successfully. Nov 23 22:48:37.748480 systemd-logind[1448]: Session 27 logged out. Waiting for processes to exit. Nov 23 22:48:37.751406 systemd[1]: Started sshd@27-10.0.0.3:22-10.0.0.1:42782.service - OpenSSH per-connection server daemon (10.0.0.1:42782). Nov 23 22:48:37.752523 systemd-logind[1448]: Removed session 27. Nov 23 22:48:37.823591 sshd[2811]: Accepted publickey for core from 10.0.0.1 port 42782 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:48:37.825024 sshd-session[2811]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:48:37.830316 systemd-logind[1448]: New session 28 of user core. Nov 23 22:48:37.840639 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 23 22:48:37.913204 systemd[1]: var-lib-docker-overlay2-ba7f4b510badb34e74061f88294b9ab05ec640d8f01c2661f533f09fb8a1e37b\x2dinit-merged.mount: Deactivated successfully. Nov 23 22:48:37.957488 containerd[2489]: time="2025-11-23T22:48:37.956924078Z" level=info msg="connecting to shim a59b1bb3f95b02d3d4a5bac208b30d761f2fa5b473ee852b57ca7e4140b90ae3" address="unix:///run/containerd/s/2ba223b1c6e5c3394f7e52f6707cf2604749b626c0162ec16181cad62a37042b" namespace=moby protocol=ttrpc version=3 Nov 23 22:48:37.985647 systemd[1]: Started docker-a59b1bb3f95b02d3d4a5bac208b30d761f2fa5b473ee852b57ca7e4140b90ae3.scope - libcontainer container a59b1bb3f95b02d3d4a5bac208b30d761f2fa5b473ee852b57ca7e4140b90ae3. Nov 23 22:48:37.998416 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 23 22:48:38.020052 kernel: docker0: port 1(veth2217d2d) entered blocking state Nov 23 22:48:38.020129 kernel: docker0: port 1(veth2217d2d) entered disabled state Nov 23 22:48:38.020153 kernel: veth2217d2d: entered allmulticast mode Nov 23 22:48:38.020168 kernel: veth2217d2d: entered promiscuous mode Nov 23 22:48:38.021168 systemd-networkd[1395]: veth2217d2d: Link UP Nov 23 22:48:38.025488 kernel: eth0: renamed from veth4ea72d8 Nov 23 22:48:38.026522 kernel: docker0: port 1(veth2217d2d) entered blocking state Nov 23 22:48:38.026558 kernel: docker0: port 1(veth2217d2d) entered forwarding state Nov 23 22:48:38.027134 systemd-networkd[1395]: veth2217d2d: Gained carrier Nov 23 22:48:38.027392 systemd-networkd[1395]: docker0: Gained carrier Nov 23 22:48:38.051185 sshd[2814]: Connection closed by 10.0.0.1 port 42782 Nov 23 22:48:38.051052 sshd-session[2811]: pam_unix(sshd:session): session closed for user core Nov 23 22:48:38.055386 systemd[1]: sshd@27-10.0.0.3:22-10.0.0.1:42782.service: Deactivated successfully. Nov 23 22:48:38.056958 systemd[1]: session-28.scope: Deactivated successfully. Nov 23 22:48:38.058160 systemd-logind[1448]: Session 28 logged out. Waiting for processes to exit. Nov 23 22:48:38.059221 systemd-logind[1448]: Removed session 28. Nov 23 22:48:39.244629 systemd-networkd[1395]: veth2217d2d: Gained IPv6LL