Oct 27 23:46:19.786520 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 27 23:46:19.786541 kernel: Linux version 6.12.54-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 Mon Oct 27 22:06:39 -00 2025 Oct 27 23:46:19.786551 kernel: KASLR enabled Oct 27 23:46:19.786557 kernel: efi: EFI v2.7 by EDK II Oct 27 23:46:19.786563 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Oct 27 23:46:19.786568 kernel: random: crng init done Oct 27 23:46:19.786575 kernel: secureboot: Secure boot disabled Oct 27 23:46:19.786581 kernel: ACPI: Early table checksum verification disabled Oct 27 23:46:19.786587 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 27 23:46:19.786594 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 27 23:46:19.786600 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786606 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786611 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786617 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786624 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786632 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786638 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786644 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786650 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:46:19.786656 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 27 23:46:19.786662 kernel: ACPI: Use ACPI SPCR as default console: No Oct 27 23:46:19.786668 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 23:46:19.786674 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Oct 27 23:46:19.786680 kernel: Zone ranges: Oct 27 23:46:19.786686 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 23:46:19.786693 kernel: DMA32 empty Oct 27 23:46:19.786699 kernel: Normal empty Oct 27 23:46:19.786705 kernel: Device empty Oct 27 23:46:19.786710 kernel: Movable zone start for each node Oct 27 23:46:19.786716 kernel: Early memory node ranges Oct 27 23:46:19.786722 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Oct 27 23:46:19.786728 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Oct 27 23:46:19.786734 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Oct 27 23:46:19.786740 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 27 23:46:19.786746 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 27 23:46:19.786752 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 27 23:46:19.786758 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 27 23:46:19.786765 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 27 23:46:19.786771 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 27 23:46:19.786777 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 27 23:46:19.786786 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 27 23:46:19.786801 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 27 23:46:19.786808 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 27 23:46:19.786816 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 23:46:19.786823 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 27 23:46:19.786829 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Oct 27 23:46:19.786835 kernel: psci: probing for conduit method from ACPI. Oct 27 23:46:19.786842 kernel: psci: PSCIv1.1 detected in firmware. Oct 27 23:46:19.786848 kernel: psci: Using standard PSCI v0.2 function IDs Oct 27 23:46:19.786854 kernel: psci: Trusted OS migration not required Oct 27 23:46:19.786861 kernel: psci: SMC Calling Convention v1.1 Oct 27 23:46:19.786867 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 27 23:46:19.786874 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 27 23:46:19.786881 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 27 23:46:19.786888 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 27 23:46:19.786894 kernel: Detected PIPT I-cache on CPU0 Oct 27 23:46:19.786900 kernel: CPU features: detected: GIC system register CPU interface Oct 27 23:46:19.786907 kernel: CPU features: detected: Spectre-v4 Oct 27 23:46:19.786913 kernel: CPU features: detected: Spectre-BHB Oct 27 23:46:19.786920 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 27 23:46:19.786926 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 27 23:46:19.786932 kernel: CPU features: detected: ARM erratum 1418040 Oct 27 23:46:19.786939 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 27 23:46:19.786945 kernel: alternatives: applying boot alternatives Oct 27 23:46:19.786952 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=7da44627248fe1fbee2c83c4ccd30b78ae5d30059ff898a840de6b6417372b60 Oct 27 23:46:19.786961 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 27 23:46:19.786967 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 23:46:19.786973 kernel: Fallback order for Node 0: 0 Oct 27 23:46:19.786980 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 27 23:46:19.786986 kernel: Policy zone: DMA Oct 27 23:46:19.786992 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 23:46:19.786999 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 27 23:46:19.787005 kernel: software IO TLB: area num 4. Oct 27 23:46:19.787011 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 27 23:46:19.787018 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Oct 27 23:46:19.787024 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 27 23:46:19.787032 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 23:46:19.787039 kernel: rcu: RCU event tracing is enabled. Oct 27 23:46:19.787045 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 27 23:46:19.787051 kernel: Trampoline variant of Tasks RCU enabled. Oct 27 23:46:19.787058 kernel: Tracing variant of Tasks RCU enabled. Oct 27 23:46:19.787064 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 27 23:46:19.787071 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 27 23:46:19.787077 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 27 23:46:19.787084 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 27 23:46:19.787090 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 23:46:19.787096 kernel: GICv3: 256 SPIs implemented Oct 27 23:46:19.787104 kernel: GICv3: 0 Extended SPIs implemented Oct 27 23:46:19.787111 kernel: Root IRQ handler: gic_handle_irq Oct 27 23:46:19.787117 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 27 23:46:19.787123 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 27 23:46:19.787139 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 27 23:46:19.787147 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 27 23:46:19.787154 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 27 23:46:19.787161 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 27 23:46:19.787167 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 27 23:46:19.787174 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 27 23:46:19.787180 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 27 23:46:19.787187 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:46:19.787195 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 27 23:46:19.787202 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 27 23:46:19.787209 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 27 23:46:19.787215 kernel: arm-pv: using stolen time PV Oct 27 23:46:19.787222 kernel: Console: colour dummy device 80x25 Oct 27 23:46:19.787228 kernel: ACPI: Core revision 20240827 Oct 27 23:46:19.787235 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 27 23:46:19.787242 kernel: pid_max: default: 32768 minimum: 301 Oct 27 23:46:19.787248 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 27 23:46:19.787255 kernel: landlock: Up and running. Oct 27 23:46:19.787263 kernel: SELinux: Initializing. Oct 27 23:46:19.787270 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 23:46:19.787276 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 23:46:19.787283 kernel: rcu: Hierarchical SRCU implementation. Oct 27 23:46:19.787289 kernel: rcu: Max phase no-delay instances is 400. Oct 27 23:46:19.787296 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 27 23:46:19.787303 kernel: Remapping and enabling EFI services. Oct 27 23:46:19.787309 kernel: smp: Bringing up secondary CPUs ... Oct 27 23:46:19.787316 kernel: Detected PIPT I-cache on CPU1 Oct 27 23:46:19.787328 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 27 23:46:19.787335 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 27 23:46:19.787342 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:46:19.787350 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 27 23:46:19.787357 kernel: Detected PIPT I-cache on CPU2 Oct 27 23:46:19.787364 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 27 23:46:19.787371 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 27 23:46:19.787378 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:46:19.787385 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 27 23:46:19.787392 kernel: Detected PIPT I-cache on CPU3 Oct 27 23:46:19.787399 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 27 23:46:19.787406 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 27 23:46:19.787413 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:46:19.787419 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 27 23:46:19.787426 kernel: smp: Brought up 1 node, 4 CPUs Oct 27 23:46:19.787433 kernel: SMP: Total of 4 processors activated. Oct 27 23:46:19.787440 kernel: CPU: All CPU(s) started at EL1 Oct 27 23:46:19.787448 kernel: CPU features: detected: 32-bit EL0 Support Oct 27 23:46:19.787455 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 27 23:46:19.787462 kernel: CPU features: detected: Common not Private translations Oct 27 23:46:19.787469 kernel: CPU features: detected: CRC32 instructions Oct 27 23:46:19.787475 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 27 23:46:19.787482 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 27 23:46:19.787489 kernel: CPU features: detected: LSE atomic instructions Oct 27 23:46:19.787496 kernel: CPU features: detected: Privileged Access Never Oct 27 23:46:19.787503 kernel: CPU features: detected: RAS Extension Support Oct 27 23:46:19.787511 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 27 23:46:19.787518 kernel: alternatives: applying system-wide alternatives Oct 27 23:46:19.787525 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 27 23:46:19.787532 kernel: Memory: 2424416K/2572288K available (11136K kernel code, 2450K rwdata, 9076K rodata, 38976K init, 1038K bss, 125536K reserved, 16384K cma-reserved) Oct 27 23:46:19.787539 kernel: devtmpfs: initialized Oct 27 23:46:19.787546 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 27 23:46:19.787553 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 27 23:46:19.787560 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 27 23:46:19.787567 kernel: 0 pages in range for non-PLT usage Oct 27 23:46:19.787575 kernel: 508560 pages in range for PLT usage Oct 27 23:46:19.787582 kernel: pinctrl core: initialized pinctrl subsystem Oct 27 23:46:19.787588 kernel: SMBIOS 3.0.0 present. Oct 27 23:46:19.787595 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 27 23:46:19.787602 kernel: DMI: Memory slots populated: 1/1 Oct 27 23:46:19.787609 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 27 23:46:19.787616 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 27 23:46:19.787623 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 23:46:19.787630 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 23:46:19.787638 kernel: audit: initializing netlink subsys (disabled) Oct 27 23:46:19.787645 kernel: audit: type=2000 audit(0.021:1): state=initialized audit_enabled=0 res=1 Oct 27 23:46:19.787652 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 23:46:19.787659 kernel: cpuidle: using governor menu Oct 27 23:46:19.787666 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 23:46:19.787672 kernel: ASID allocator initialised with 32768 entries Oct 27 23:46:19.787679 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 27 23:46:19.787686 kernel: Serial: AMBA PL011 UART driver Oct 27 23:46:19.787693 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 27 23:46:19.787701 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 27 23:46:19.787708 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 27 23:46:19.787715 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 27 23:46:19.787721 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 27 23:46:19.787728 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 27 23:46:19.787735 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 27 23:46:19.787742 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 27 23:46:19.787749 kernel: ACPI: Added _OSI(Module Device) Oct 27 23:46:19.787755 kernel: ACPI: Added _OSI(Processor Device) Oct 27 23:46:19.787763 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 27 23:46:19.787770 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 27 23:46:19.787777 kernel: ACPI: Interpreter enabled Oct 27 23:46:19.787784 kernel: ACPI: Using GIC for interrupt routing Oct 27 23:46:19.787796 kernel: ACPI: MCFG table detected, 1 entries Oct 27 23:46:19.787803 kernel: ACPI: CPU0 has been hot-added Oct 27 23:46:19.787810 kernel: ACPI: CPU1 has been hot-added Oct 27 23:46:19.787817 kernel: ACPI: CPU2 has been hot-added Oct 27 23:46:19.787823 kernel: ACPI: CPU3 has been hot-added Oct 27 23:46:19.787830 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 27 23:46:19.787840 kernel: printk: legacy console [ttyAMA0] enabled Oct 27 23:46:19.787846 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 27 23:46:19.787976 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 27 23:46:19.788042 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 27 23:46:19.788101 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 27 23:46:19.788177 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 27 23:46:19.788236 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 27 23:46:19.788248 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 27 23:46:19.788255 kernel: PCI host bridge to bus 0000:00 Oct 27 23:46:19.788327 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 27 23:46:19.788383 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 27 23:46:19.788437 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 27 23:46:19.788490 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 27 23:46:19.788565 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 27 23:46:19.788637 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 27 23:46:19.788697 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 27 23:46:19.788756 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 27 23:46:19.788828 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 23:46:19.788890 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 27 23:46:19.788948 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 27 23:46:19.789026 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 27 23:46:19.789080 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 27 23:46:19.789147 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 27 23:46:19.789206 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 27 23:46:19.789216 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 27 23:46:19.789223 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 27 23:46:19.789230 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 27 23:46:19.789236 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 27 23:46:19.789246 kernel: iommu: Default domain type: Translated Oct 27 23:46:19.789253 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 27 23:46:19.789260 kernel: efivars: Registered efivars operations Oct 27 23:46:19.789267 kernel: vgaarb: loaded Oct 27 23:46:19.789273 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 23:46:19.789280 kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 23:46:19.789287 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 23:46:19.789294 kernel: pnp: PnP ACPI init Oct 27 23:46:19.789368 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 27 23:46:19.789380 kernel: pnp: PnP ACPI: found 1 devices Oct 27 23:46:19.789387 kernel: NET: Registered PF_INET protocol family Oct 27 23:46:19.789394 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 27 23:46:19.789401 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 27 23:46:19.789408 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 27 23:46:19.789415 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 23:46:19.789422 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 27 23:46:19.789429 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 27 23:46:19.789437 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 23:46:19.789444 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 23:46:19.789451 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 27 23:46:19.789458 kernel: PCI: CLS 0 bytes, default 64 Oct 27 23:46:19.789465 kernel: kvm [1]: HYP mode not available Oct 27 23:46:19.789473 kernel: Initialise system trusted keyrings Oct 27 23:46:19.789480 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 27 23:46:19.789487 kernel: Key type asymmetric registered Oct 27 23:46:19.789493 kernel: Asymmetric key parser 'x509' registered Oct 27 23:46:19.789501 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 27 23:46:19.789508 kernel: io scheduler mq-deadline registered Oct 27 23:46:19.789515 kernel: io scheduler kyber registered Oct 27 23:46:19.789523 kernel: io scheduler bfq registered Oct 27 23:46:19.789530 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 27 23:46:19.789537 kernel: ACPI: button: Power Button [PWRB] Oct 27 23:46:19.789544 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 27 23:46:19.789604 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 27 23:46:19.789614 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 27 23:46:19.789622 kernel: thunder_xcv, ver 1.0 Oct 27 23:46:19.789629 kernel: thunder_bgx, ver 1.0 Oct 27 23:46:19.789635 kernel: nicpf, ver 1.0 Oct 27 23:46:19.789642 kernel: nicvf, ver 1.0 Oct 27 23:46:19.789708 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 27 23:46:19.789765 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-27T23:46:19 UTC (1761608779) Oct 27 23:46:19.789774 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 23:46:19.789781 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 27 23:46:19.789797 kernel: NET: Registered PF_INET6 protocol family Oct 27 23:46:19.789805 kernel: watchdog: NMI not fully supported Oct 27 23:46:19.789812 kernel: watchdog: Hard watchdog permanently disabled Oct 27 23:46:19.789819 kernel: Segment Routing with IPv6 Oct 27 23:46:19.789825 kernel: In-situ OAM (IOAM) with IPv6 Oct 27 23:46:19.789832 kernel: NET: Registered PF_PACKET protocol family Oct 27 23:46:19.789839 kernel: Key type dns_resolver registered Oct 27 23:46:19.789846 kernel: registered taskstats version 1 Oct 27 23:46:19.789853 kernel: Loading compiled-in X.509 certificates Oct 27 23:46:19.789860 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: d36d3f99f7c8356b27e0c5530c216cd6f7ab4d7e' Oct 27 23:46:19.789869 kernel: Demotion targets for Node 0: null Oct 27 23:46:19.789875 kernel: Key type .fscrypt registered Oct 27 23:46:19.789882 kernel: Key type fscrypt-provisioning registered Oct 27 23:46:19.789889 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 27 23:46:19.789896 kernel: ima: Allocated hash algorithm: sha1 Oct 27 23:46:19.789903 kernel: ima: No architecture policies found Oct 27 23:46:19.789909 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 27 23:46:19.789916 kernel: clk: Disabling unused clocks Oct 27 23:46:19.789923 kernel: PM: genpd: Disabling unused power domains Oct 27 23:46:19.789931 kernel: Warning: unable to open an initial console. Oct 27 23:46:19.789938 kernel: Freeing unused kernel memory: 38976K Oct 27 23:46:19.789945 kernel: Run /init as init process Oct 27 23:46:19.789952 kernel: with arguments: Oct 27 23:46:19.789959 kernel: /init Oct 27 23:46:19.789965 kernel: with environment: Oct 27 23:46:19.789972 kernel: HOME=/ Oct 27 23:46:19.789979 kernel: TERM=linux Oct 27 23:46:19.789986 systemd[1]: Successfully made /usr/ read-only. Oct 27 23:46:19.789997 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) Oct 27 23:46:19.790005 systemd[1]: Detected virtualization kvm. Oct 27 23:46:19.790012 systemd[1]: Detected architecture arm64. Oct 27 23:46:19.790019 systemd[1]: Running in initrd. Oct 27 23:46:19.790027 systemd[1]: No hostname configured, using default hostname. Oct 27 23:46:19.790034 systemd[1]: Hostname set to . Oct 27 23:46:19.790041 systemd[1]: Initializing machine ID from VM UUID. Oct 27 23:46:19.790050 systemd[1]: Queued start job for default target initrd.target. Oct 27 23:46:19.790057 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 23:46:19.790065 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 23:46:19.790073 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 27 23:46:19.790080 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 27 23:46:19.790088 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 27 23:46:19.790096 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 27 23:46:19.790105 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 27 23:46:19.790113 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 27 23:46:19.790121 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 23:46:19.790128 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 27 23:46:19.790149 systemd[1]: Reached target paths.target - Path Units. Oct 27 23:46:19.790159 systemd[1]: Reached target slices.target - Slice Units. Oct 27 23:46:19.790169 systemd[1]: Reached target swap.target - Swaps. Oct 27 23:46:19.790177 systemd[1]: Reached target timers.target - Timer Units. Oct 27 23:46:19.790186 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 27 23:46:19.790194 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 27 23:46:19.790201 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 27 23:46:19.790209 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 27 23:46:19.790216 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 27 23:46:19.790223 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 27 23:46:19.790231 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 23:46:19.790239 systemd[1]: Reached target sockets.target - Socket Units. Oct 27 23:46:19.790247 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 27 23:46:19.790255 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 27 23:46:19.790262 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 27 23:46:19.790270 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). Oct 27 23:46:19.790278 systemd[1]: Starting systemd-fsck-usr.service... Oct 27 23:46:19.790285 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 27 23:46:19.790293 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 27 23:46:19.790300 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:46:19.790307 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 27 23:46:19.790317 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 23:46:19.790324 systemd[1]: Finished systemd-fsck-usr.service. Oct 27 23:46:19.790349 systemd-journald[244]: Collecting audit messages is disabled. Oct 27 23:46:19.790369 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 27 23:46:19.790377 systemd-journald[244]: Journal started Oct 27 23:46:19.790394 systemd-journald[244]: Runtime Journal (/run/log/journal/567d23b2614e4cf69ce218348823e291) is 6M, max 48.5M, 42.4M free. Oct 27 23:46:19.781049 systemd-modules-load[247]: Inserted module 'overlay' Oct 27 23:46:19.793447 systemd[1]: Started systemd-journald.service - Journal Service. Oct 27 23:46:19.796186 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 27 23:46:19.798387 systemd-modules-load[247]: Inserted module 'br_netfilter' Oct 27 23:46:19.799426 kernel: Bridge firewalling registered Oct 27 23:46:19.803147 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:46:19.805723 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 27 23:46:19.810220 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 27 23:46:19.812096 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 27 23:46:19.814574 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 27 23:46:19.829443 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 23:46:19.832811 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 27 23:46:19.840412 systemd-tmpfiles[266]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 27 23:46:19.842368 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 27 23:46:19.845440 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 23:46:19.847930 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 23:46:19.852967 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 23:46:19.855970 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 27 23:46:19.859020 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 27 23:46:19.881665 dracut-cmdline[290]: 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=7da44627248fe1fbee2c83c4ccd30b78ae5d30059ff898a840de6b6417372b60 Oct 27 23:46:19.897160 systemd-resolved[291]: Positive Trust Anchors: Oct 27 23:46:19.897180 systemd-resolved[291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 23:46:19.897211 systemd-resolved[291]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 27 23:46:19.902189 systemd-resolved[291]: Defaulting to hostname 'linux'. Oct 27 23:46:19.903173 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 27 23:46:19.908408 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 27 23:46:19.965163 kernel: SCSI subsystem initialized Oct 27 23:46:19.970157 kernel: Loading iSCSI transport class v2.0-870. Oct 27 23:46:19.977161 kernel: iscsi: registered transport (tcp) Oct 27 23:46:19.991162 kernel: iscsi: registered transport (qla4xxx) Oct 27 23:46:19.991220 kernel: QLogic iSCSI HBA Driver Oct 27 23:46:20.008759 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 27 23:46:20.030125 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 27 23:46:20.031888 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 27 23:46:20.096232 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 27 23:46:20.099043 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 27 23:46:20.164178 kernel: raid6: neonx8 gen() 15580 MB/s Oct 27 23:46:20.181159 kernel: raid6: neonx4 gen() 15711 MB/s Oct 27 23:46:20.198157 kernel: raid6: neonx2 gen() 13171 MB/s Oct 27 23:46:20.215163 kernel: raid6: neonx1 gen() 10395 MB/s Oct 27 23:46:20.232163 kernel: raid6: int64x8 gen() 6856 MB/s Oct 27 23:46:20.249160 kernel: raid6: int64x4 gen() 7316 MB/s Oct 27 23:46:20.266161 kernel: raid6: int64x2 gen() 6080 MB/s Oct 27 23:46:20.283542 kernel: raid6: int64x1 gen() 5008 MB/s Oct 27 23:46:20.283601 kernel: raid6: using algorithm neonx4 gen() 15711 MB/s Oct 27 23:46:20.301405 kernel: raid6: .... xor() 12339 MB/s, rmw enabled Oct 27 23:46:20.301455 kernel: raid6: using neon recovery algorithm Oct 27 23:46:20.309376 kernel: xor: measuring software checksum speed Oct 27 23:46:20.309430 kernel: 8regs : 21499 MB/sec Oct 27 23:46:20.310185 kernel: 32regs : 21693 MB/sec Oct 27 23:46:20.310219 kernel: arm64_neon : 23857 MB/sec Oct 27 23:46:20.311407 kernel: xor: using function: arm64_neon (23857 MB/sec) Oct 27 23:46:20.365196 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 27 23:46:20.372797 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 27 23:46:20.375905 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 23:46:20.418008 systemd-udevd[501]: Using default interface naming scheme 'v255'. Oct 27 23:46:20.422346 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 23:46:20.424466 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 27 23:46:20.463937 dracut-pre-trigger[506]: rd.md=0: removing MD RAID activation Oct 27 23:46:20.494105 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 27 23:46:20.496756 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 27 23:46:20.552050 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 23:46:20.555873 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 27 23:46:20.621184 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 27 23:46:20.621389 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 27 23:46:20.621705 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 23:46:20.621847 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:46:20.627275 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:46:20.629299 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:46:20.631959 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 23:46:20.652264 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 27 23:46:20.659987 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:46:20.674203 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 27 23:46:20.683879 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 27 23:46:20.693366 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 27 23:46:20.700878 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 27 23:46:20.702342 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 27 23:46:20.705794 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 27 23:46:20.708346 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 23:46:20.710855 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 27 23:46:20.714002 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 27 23:46:20.716058 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 27 23:46:20.735164 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 23:46:20.736984 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 27 23:46:21.756164 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 23:46:21.756220 disk-uuid[599]: The operation has completed successfully. Oct 27 23:46:21.812858 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 27 23:46:21.812972 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 27 23:46:21.829288 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 27 23:46:21.861844 sh[611]: Success Oct 27 23:46:21.878313 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 27 23:46:21.878365 kernel: device-mapper: uevent: version 1.0.3 Oct 27 23:46:21.878376 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 27 23:46:21.889162 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 27 23:46:21.918043 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 27 23:46:21.920100 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 27 23:46:21.941999 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 27 23:46:21.951159 kernel: BTRFS: device fsid 5a6ca053-244f-4cbd-93f9-9b9e55af9b0a devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (623) Oct 27 23:46:21.953614 kernel: BTRFS info (device dm-0): first mount of filesystem 5a6ca053-244f-4cbd-93f9-9b9e55af9b0a Oct 27 23:46:21.953635 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:46:21.958154 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 27 23:46:21.958184 kernel: BTRFS info (device dm-0): enabling free space tree Oct 27 23:46:21.959017 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 27 23:46:21.962332 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 27 23:46:21.963900 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 27 23:46:21.965388 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 27 23:46:21.969151 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 27 23:46:21.988025 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (652) Oct 27 23:46:21.988083 kernel: BTRFS info (device vda6): first mount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:46:21.988094 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:46:21.992208 kernel: BTRFS info (device vda6): turning on async discard Oct 27 23:46:21.992251 kernel: BTRFS info (device vda6): enabling free space tree Oct 27 23:46:21.997197 kernel: BTRFS info (device vda6): last unmount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:46:21.999296 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 27 23:46:22.001449 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 27 23:46:22.066565 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 27 23:46:22.070320 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 27 23:46:22.112447 ignition[706]: Ignition 2.22.0 Oct 27 23:46:22.113359 ignition[706]: Stage: fetch-offline Oct 27 23:46:22.113407 ignition[706]: no configs at "/usr/lib/ignition/base.d" Oct 27 23:46:22.113416 ignition[706]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:46:22.115383 systemd-networkd[804]: lo: Link UP Oct 27 23:46:22.113495 ignition[706]: parsed url from cmdline: "" Oct 27 23:46:22.115388 systemd-networkd[804]: lo: Gained carrier Oct 27 23:46:22.113498 ignition[706]: no config URL provided Oct 27 23:46:22.116075 systemd-networkd[804]: Enumeration completed Oct 27 23:46:22.113502 ignition[706]: reading system config file "/usr/lib/ignition/user.ign" Oct 27 23:46:22.116183 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 27 23:46:22.113509 ignition[706]: no config at "/usr/lib/ignition/user.ign" Oct 27 23:46:22.118182 systemd[1]: Reached target network.target - Network. Oct 27 23:46:22.113527 ignition[706]: op(1): [started] loading QEMU firmware config module Oct 27 23:46:22.120216 systemd-networkd[804]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:46:22.113531 ignition[706]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 27 23:46:22.120220 systemd-networkd[804]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 27 23:46:22.123953 ignition[706]: op(1): [finished] loading QEMU firmware config module Oct 27 23:46:22.120656 systemd-networkd[804]: eth0: Link UP Oct 27 23:46:22.132168 ignition[706]: parsing config with SHA512: 0fb9f76034ca730cfa52374432554fde4fbb5062b3a723a8cda8ceb920697c745b8896659023575f678c64d767e45afe6a7b6468f7c6eaed8804b5725096eb91 Oct 27 23:46:22.120976 systemd-networkd[804]: eth0: Gained carrier Oct 27 23:46:22.120987 systemd-networkd[804]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:46:22.139030 unknown[706]: fetched base config from "system" Oct 27 23:46:22.139254 ignition[706]: fetch-offline: fetch-offline passed Oct 27 23:46:22.139039 unknown[706]: fetched user config from "qemu" Oct 27 23:46:22.139341 ignition[706]: Ignition finished successfully Oct 27 23:46:22.141020 systemd-networkd[804]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 27 23:46:22.143983 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 27 23:46:22.146308 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 27 23:46:22.147176 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 27 23:46:22.179848 ignition[814]: Ignition 2.22.0 Oct 27 23:46:22.179865 ignition[814]: Stage: kargs Oct 27 23:46:22.180004 ignition[814]: no configs at "/usr/lib/ignition/base.d" Oct 27 23:46:22.180012 ignition[814]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:46:22.180531 ignition[814]: kargs: kargs passed Oct 27 23:46:22.184799 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 27 23:46:22.180575 ignition[814]: Ignition finished successfully Oct 27 23:46:22.186888 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 27 23:46:22.211764 ignition[824]: Ignition 2.22.0 Oct 27 23:46:22.211848 ignition[824]: Stage: disks Oct 27 23:46:22.212024 ignition[824]: no configs at "/usr/lib/ignition/base.d" Oct 27 23:46:22.212033 ignition[824]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:46:22.214410 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 27 23:46:22.212535 ignition[824]: disks: disks passed Oct 27 23:46:22.216678 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 27 23:46:22.212577 ignition[824]: Ignition finished successfully Oct 27 23:46:22.218659 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 27 23:46:22.220465 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 27 23:46:22.222522 systemd[1]: Reached target sysinit.target - System Initialization. Oct 27 23:46:22.224299 systemd[1]: Reached target basic.target - Basic System. Oct 27 23:46:22.227108 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 27 23:46:22.259700 systemd-fsck[835]: ROOT: clean, 15/553520 files, 52789/553472 blocks Oct 27 23:46:22.264723 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 27 23:46:22.267301 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 27 23:46:22.331159 kernel: EXT4-fs (vda9): mounted filesystem 8f9c7d7f-b094-48f2-af83-87ee7d7d8042 r/w with ordered data mode. Quota mode: none. Oct 27 23:46:22.332481 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 27 23:46:22.333604 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 27 23:46:22.336337 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 27 23:46:22.338971 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 27 23:46:22.340210 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 27 23:46:22.340252 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 27 23:46:22.340277 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 27 23:46:22.357826 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 27 23:46:22.360571 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 27 23:46:22.366247 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (843) Oct 27 23:46:22.366271 kernel: BTRFS info (device vda6): first mount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:46:22.366281 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:46:22.369598 kernel: BTRFS info (device vda6): turning on async discard Oct 27 23:46:22.369646 kernel: BTRFS info (device vda6): enabling free space tree Oct 27 23:46:22.371387 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 27 23:46:22.399448 initrd-setup-root[867]: cut: /sysroot/etc/passwd: No such file or directory Oct 27 23:46:22.403360 initrd-setup-root[874]: cut: /sysroot/etc/group: No such file or directory Oct 27 23:46:22.407675 initrd-setup-root[881]: cut: /sysroot/etc/shadow: No such file or directory Oct 27 23:46:22.410999 initrd-setup-root[888]: cut: /sysroot/etc/gshadow: No such file or directory Oct 27 23:46:22.475417 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 27 23:46:22.477863 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 27 23:46:22.479525 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 27 23:46:22.502170 kernel: BTRFS info (device vda6): last unmount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:46:22.510433 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 27 23:46:22.528887 ignition[957]: INFO : Ignition 2.22.0 Oct 27 23:46:22.528887 ignition[957]: INFO : Stage: mount Oct 27 23:46:22.530676 ignition[957]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 23:46:22.530676 ignition[957]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:46:22.530676 ignition[957]: INFO : mount: mount passed Oct 27 23:46:22.530676 ignition[957]: INFO : Ignition finished successfully Oct 27 23:46:22.531745 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 27 23:46:22.534860 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 27 23:46:22.950578 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 27 23:46:22.952189 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 27 23:46:22.971200 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (969) Oct 27 23:46:22.971232 kernel: BTRFS info (device vda6): first mount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:46:22.973294 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:46:22.976166 kernel: BTRFS info (device vda6): turning on async discard Oct 27 23:46:22.976194 kernel: BTRFS info (device vda6): enabling free space tree Oct 27 23:46:22.977559 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 27 23:46:23.008285 ignition[986]: INFO : Ignition 2.22.0 Oct 27 23:46:23.008285 ignition[986]: INFO : Stage: files Oct 27 23:46:23.010013 ignition[986]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 23:46:23.010013 ignition[986]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:46:23.010013 ignition[986]: DEBUG : files: compiled without relabeling support, skipping Oct 27 23:46:23.010013 ignition[986]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 27 23:46:23.010013 ignition[986]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 27 23:46:23.017110 ignition[986]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 27 23:46:23.017110 ignition[986]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 27 23:46:23.017110 ignition[986]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 27 23:46:23.017110 ignition[986]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 27 23:46:23.017110 ignition[986]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 27 23:46:23.017110 ignition[986]: INFO : files: op(4): [started] processing unit "docker.service" Oct 27 23:46:23.017110 ignition[986]: INFO : files: op(4): [finished] processing unit "docker.service" Oct 27 23:46:23.017110 ignition[986]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Oct 27 23:46:23.017110 ignition[986]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 27 23:46:23.011661 unknown[986]: wrote ssh authorized keys file for user: core Oct 27 23:46:23.034471 ignition[986]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 27 23:46:23.034471 ignition[986]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Oct 27 23:46:23.034471 ignition[986]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Oct 27 23:46:23.034471 ignition[986]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 27 23:46:23.043110 ignition[986]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 27 23:46:23.043110 ignition[986]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Oct 27 23:46:23.043110 ignition[986]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Oct 27 23:46:23.043110 ignition[986]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Oct 27 23:46:23.043110 ignition[986]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 27 23:46:23.043110 ignition[986]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 27 23:46:23.043110 ignition[986]: INFO : files: files passed Oct 27 23:46:23.043110 ignition[986]: INFO : Ignition finished successfully Oct 27 23:46:23.039486 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 27 23:46:23.042608 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 27 23:46:23.044825 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 27 23:46:23.059434 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 27 23:46:23.059552 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 27 23:46:23.063286 initrd-setup-root-after-ignition[1015]: grep: /sysroot/oem/oem-release: No such file or directory Oct 27 23:46:23.064675 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 27 23:46:23.064675 initrd-setup-root-after-ignition[1017]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 27 23:46:23.064119 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 27 23:46:23.072312 initrd-setup-root-after-ignition[1021]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 27 23:46:23.066229 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 27 23:46:23.069113 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 27 23:46:23.099651 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 27 23:46:23.100732 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 27 23:46:23.102212 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 27 23:46:23.104125 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 27 23:46:23.106178 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 27 23:46:23.106887 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 27 23:46:23.130091 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 27 23:46:23.132418 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 27 23:46:23.153099 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 27 23:46:23.154371 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 23:46:23.156439 systemd[1]: Stopped target timers.target - Timer Units. Oct 27 23:46:23.158252 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 27 23:46:23.158374 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 27 23:46:23.161029 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 27 23:46:23.162203 systemd[1]: Stopped target basic.target - Basic System. Oct 27 23:46:23.164090 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 27 23:46:23.166036 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 27 23:46:23.167933 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 27 23:46:23.169952 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 27 23:46:23.172059 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 27 23:46:23.174032 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 27 23:46:23.176232 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 27 23:46:23.178156 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 27 23:46:23.180298 systemd[1]: Stopped target swap.target - Swaps. Oct 27 23:46:23.181949 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 27 23:46:23.182084 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 27 23:46:23.184617 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 27 23:46:23.186596 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 23:46:23.188625 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 27 23:46:23.192196 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 23:46:23.193513 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 27 23:46:23.193637 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 27 23:46:23.196926 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 27 23:46:23.197047 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 27 23:46:23.199385 systemd[1]: Stopped target paths.target - Path Units. Oct 27 23:46:23.201110 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 23:46:23.201239 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 23:46:23.203485 systemd[1]: Stopped target slices.target - Slice Units. Oct 27 23:46:23.205200 systemd[1]: Stopped target sockets.target - Socket Units. Oct 27 23:46:23.207146 systemd[1]: iscsid.socket: Deactivated successfully. Oct 27 23:46:23.207239 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 27 23:46:23.209478 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 27 23:46:23.209562 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 27 23:46:23.211268 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 27 23:46:23.211388 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 27 23:46:23.213330 systemd[1]: ignition-files.service: Deactivated successfully. Oct 27 23:46:23.213436 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 27 23:46:23.215851 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 27 23:46:23.218348 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 27 23:46:23.219505 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 27 23:46:23.219624 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 23:46:23.221768 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 27 23:46:23.221889 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 27 23:46:23.227678 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 27 23:46:23.229269 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 27 23:46:23.237495 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 27 23:46:23.241416 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 27 23:46:23.242358 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 27 23:46:23.245905 ignition[1041]: INFO : Ignition 2.22.0 Oct 27 23:46:23.245905 ignition[1041]: INFO : Stage: umount Oct 27 23:46:23.247584 ignition[1041]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 23:46:23.247584 ignition[1041]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:46:23.247584 ignition[1041]: INFO : umount: umount passed Oct 27 23:46:23.247584 ignition[1041]: INFO : Ignition finished successfully Oct 27 23:46:23.248214 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 27 23:46:23.250166 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 27 23:46:23.251709 systemd[1]: Stopped target network.target - Network. Oct 27 23:46:23.253181 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 27 23:46:23.253236 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 27 23:46:23.255002 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 27 23:46:23.255046 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 27 23:46:23.256863 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 27 23:46:23.256915 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 27 23:46:23.258575 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 27 23:46:23.258615 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 27 23:46:23.260367 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 27 23:46:23.260412 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 27 23:46:23.262341 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 27 23:46:23.264200 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 27 23:46:23.268534 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 27 23:46:23.268614 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 27 23:46:23.272059 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 27 23:46:23.272551 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 27 23:46:23.272621 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 23:46:23.278026 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 27 23:46:23.282452 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 27 23:46:23.282585 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 27 23:46:23.286341 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 27 23:46:23.287765 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 27 23:46:23.287812 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 27 23:46:23.290437 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 27 23:46:23.291602 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 27 23:46:23.291658 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 27 23:46:23.293832 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 23:46:23.293878 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 27 23:46:23.296714 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 27 23:46:23.296757 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 27 23:46:23.298016 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 23:46:23.308058 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 27 23:46:23.308190 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 27 23:46:23.310306 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 27 23:46:23.310459 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 23:46:23.313487 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 27 23:46:23.313534 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 27 23:46:23.314715 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 27 23:46:23.314747 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 23:46:23.316683 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 27 23:46:23.316735 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 27 23:46:23.319825 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 27 23:46:23.319872 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 27 23:46:23.322811 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 23:46:23.322864 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 23:46:23.326780 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 27 23:46:23.328374 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 27 23:46:23.328432 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 27 23:46:23.331593 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 23:46:23.331641 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 23:46:23.334811 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 27 23:46:23.334857 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 23:46:23.338607 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 27 23:46:23.338651 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 23:46:23.341006 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 23:46:23.341050 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:46:23.345023 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 27 23:46:23.346170 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 27 23:46:23.348619 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 27 23:46:23.351386 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 27 23:46:23.375508 systemd[1]: Switching root. Oct 27 23:46:23.398976 systemd-journald[244]: Journal stopped Oct 27 23:46:24.127333 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Oct 27 23:46:24.127389 kernel: SELinux: policy capability network_peer_controls=1 Oct 27 23:46:24.127400 kernel: SELinux: policy capability open_perms=1 Oct 27 23:46:24.127409 kernel: SELinux: policy capability extended_socket_class=1 Oct 27 23:46:24.127418 kernel: SELinux: policy capability always_check_network=0 Oct 27 23:46:24.127427 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 27 23:46:24.127437 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 27 23:46:24.127450 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 27 23:46:24.127461 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 27 23:46:24.127472 kernel: SELinux: policy capability userspace_initial_context=0 Oct 27 23:46:24.127486 kernel: audit: type=1403 audit(1761608783.486:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 27 23:46:24.127497 systemd[1]: Successfully loaded SELinux policy in 56.741ms. Oct 27 23:46:24.127517 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.414ms. Oct 27 23:46:24.127529 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) Oct 27 23:46:24.127540 systemd[1]: Detected virtualization kvm. Oct 27 23:46:24.127554 systemd[1]: Detected architecture arm64. Oct 27 23:46:24.127565 systemd[1]: Detected first boot. Oct 27 23:46:24.127575 systemd[1]: Initializing machine ID from VM UUID. Oct 27 23:46:24.127586 zram_generator::config[1086]: No configuration found. Oct 27 23:46:24.127599 kernel: NET: Registered PF_VSOCK protocol family Oct 27 23:46:24.127609 systemd[1]: Populated /etc with preset unit settings. Oct 27 23:46:24.127621 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 27 23:46:24.127631 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 27 23:46:24.127641 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 27 23:46:24.127654 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 27 23:46:24.127665 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 27 23:46:24.127675 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 27 23:46:24.127685 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 27 23:46:24.127695 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 27 23:46:24.127705 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 27 23:46:24.127716 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 27 23:46:24.127726 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 27 23:46:24.127738 systemd[1]: Created slice user.slice - User and Session Slice. Oct 27 23:46:24.127748 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 23:46:24.127759 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 23:46:24.127777 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 27 23:46:24.127789 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 27 23:46:24.127799 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 27 23:46:24.127809 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 27 23:46:24.127819 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 27 23:46:24.127829 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 23:46:24.127840 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 27 23:46:24.127850 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 27 23:46:24.127860 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 27 23:46:24.127870 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 27 23:46:24.127880 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 27 23:46:24.127890 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 23:46:24.127900 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 27 23:46:24.127910 systemd[1]: Reached target slices.target - Slice Units. Oct 27 23:46:24.127921 systemd[1]: Reached target swap.target - Swaps. Oct 27 23:46:24.127931 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 27 23:46:24.127940 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 27 23:46:24.127951 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 27 23:46:24.127961 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 27 23:46:24.127971 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 27 23:46:24.127981 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 23:46:24.127991 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 27 23:46:24.128001 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 27 23:46:24.128012 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 27 23:46:24.128022 systemd[1]: Mounting media.mount - External Media Directory... Oct 27 23:46:24.128032 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 27 23:46:24.128042 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 27 23:46:24.128052 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 27 23:46:24.128062 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). Oct 27 23:46:24.128072 systemd[1]: Reached target machines.target - Containers. Oct 27 23:46:24.128082 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 27 23:46:24.128096 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:46:24.128107 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 27 23:46:24.128117 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 27 23:46:24.128126 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 23:46:24.128198 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 27 23:46:24.128210 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 23:46:24.128220 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 27 23:46:24.128230 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 23:46:24.128240 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 27 23:46:24.128252 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 27 23:46:24.128264 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 27 23:46:24.128289 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 27 23:46:24.128299 systemd[1]: Stopped systemd-fsck-usr.service. Oct 27 23:46:24.128308 kernel: fuse: init (API version 7.41) Oct 27 23:46:24.128319 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). Oct 27 23:46:24.128329 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 27 23:46:24.128338 kernel: ACPI: bus type drm_connector registered Oct 27 23:46:24.128348 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 27 23:46:24.128359 kernel: loop: module loaded Oct 27 23:46:24.128369 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 27 23:46:24.128380 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 27 23:46:24.128414 systemd-journald[1154]: Collecting audit messages is disabled. Oct 27 23:46:24.128440 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 27 23:46:24.128451 systemd-journald[1154]: Journal started Oct 27 23:46:24.128473 systemd-journald[1154]: Runtime Journal (/run/log/journal/567d23b2614e4cf69ce218348823e291) is 6M, max 48.5M, 42.4M free. Oct 27 23:46:23.854515 systemd[1]: Queued start job for default target multi-user.target. Oct 27 23:46:23.878186 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 27 23:46:23.878575 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 27 23:46:24.134788 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 27 23:46:24.137205 systemd[1]: verity-setup.service: Deactivated successfully. Oct 27 23:46:24.137252 systemd[1]: Stopped verity-setup.service. Oct 27 23:46:24.141171 systemd[1]: Started systemd-journald.service - Journal Service. Oct 27 23:46:24.142808 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 27 23:46:24.144219 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 27 23:46:24.145555 systemd[1]: Mounted media.mount - External Media Directory. Oct 27 23:46:24.146895 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 27 23:46:24.148382 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 27 23:46:24.149754 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 27 23:46:24.151147 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 23:46:24.152714 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 27 23:46:24.152905 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 27 23:46:24.154549 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 23:46:24.154733 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 23:46:24.156377 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 23:46:24.156553 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 27 23:46:24.158099 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 23:46:24.158316 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 23:46:24.159882 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 27 23:46:24.160037 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 27 23:46:24.161521 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 23:46:24.161679 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 23:46:24.163424 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 27 23:46:24.164971 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 27 23:46:24.167060 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 27 23:46:24.168806 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 27 23:46:24.180594 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 27 23:46:24.182992 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 27 23:46:24.185270 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 27 23:46:24.186615 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 27 23:46:24.186642 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 27 23:46:24.188703 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 27 23:46:24.194947 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 27 23:46:24.196230 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:46:24.210837 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 27 23:46:24.213105 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 27 23:46:24.214541 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 27 23:46:24.221930 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 27 23:46:24.223348 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 27 23:46:24.226320 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 27 23:46:24.228642 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 27 23:46:24.231545 systemd-journald[1154]: Time spent on flushing to /var/log/journal/567d23b2614e4cf69ce218348823e291 is 20.828ms for 852 entries. Oct 27 23:46:24.231545 systemd-journald[1154]: System Journal (/var/log/journal/567d23b2614e4cf69ce218348823e291) is 8M, max 195.6M, 187.6M free. Oct 27 23:46:24.318633 systemd-journald[1154]: Received client request to flush runtime journal. Oct 27 23:46:24.318703 kernel: loop0: detected capacity change from 0 to 119368 Oct 27 23:46:24.318729 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 27 23:46:24.232245 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 27 23:46:24.238312 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 27 23:46:24.241549 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 23:46:24.243033 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 27 23:46:24.244631 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 27 23:46:24.252510 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 27 23:46:24.261637 systemd-tmpfiles[1203]: ACLs are not supported, ignoring. Oct 27 23:46:24.261647 systemd-tmpfiles[1203]: ACLs are not supported, ignoring. Oct 27 23:46:24.264867 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 23:46:24.267831 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 27 23:46:24.278346 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 27 23:46:24.280040 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 27 23:46:24.284290 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 27 23:46:24.321433 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 27 23:46:24.335175 kernel: loop1: detected capacity change from 0 to 100632 Oct 27 23:46:24.336580 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 27 23:46:24.339152 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 27 23:46:24.356163 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 27 23:46:24.358800 systemd-tmpfiles[1224]: ACLs are not supported, ignoring. Oct 27 23:46:24.358820 systemd-tmpfiles[1224]: ACLs are not supported, ignoring. Oct 27 23:46:24.361818 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 23:46:24.364283 kernel: loop2: detected capacity change from 0 to 119368 Oct 27 23:46:24.371172 kernel: loop3: detected capacity change from 0 to 100632 Oct 27 23:46:24.374908 (sd-merge)[1229]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Oct 27 23:46:24.375323 (sd-merge)[1229]: Merged extensions into '/usr'. Oct 27 23:46:24.379184 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 27 23:46:24.382201 systemd[1]: Starting ensure-sysext.service... Oct 27 23:46:24.385283 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 27 23:46:24.402796 systemd[1]: Reload requested from client PID 1232 ('systemctl') (unit ensure-sysext.service)... Oct 27 23:46:24.402824 systemd[1]: Reloading... Oct 27 23:46:24.412420 systemd-tmpfiles[1233]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 27 23:46:24.412463 systemd-tmpfiles[1233]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 27 23:46:24.412686 systemd-tmpfiles[1233]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 27 23:46:24.412897 systemd-tmpfiles[1233]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 27 23:46:24.418665 systemd-tmpfiles[1233]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 27 23:46:24.418900 systemd-tmpfiles[1233]: ACLs are not supported, ignoring. Oct 27 23:46:24.418945 systemd-tmpfiles[1233]: ACLs are not supported, ignoring. Oct 27 23:46:24.422924 systemd-tmpfiles[1233]: Detected autofs mount point /boot during canonicalization of boot. Oct 27 23:46:24.422934 systemd-tmpfiles[1233]: Skipping /boot Oct 27 23:46:24.430667 systemd-tmpfiles[1233]: Detected autofs mount point /boot during canonicalization of boot. Oct 27 23:46:24.430678 systemd-tmpfiles[1233]: Skipping /boot Oct 27 23:46:24.452198 zram_generator::config[1258]: No configuration found. Oct 27 23:46:24.536753 ldconfig[1197]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 27 23:46:24.602984 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 27 23:46:24.603452 systemd[1]: Reloading finished in 200 ms. Oct 27 23:46:24.620948 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 27 23:46:24.637664 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 23:46:24.645000 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 27 23:46:24.647851 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 27 23:46:24.659987 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 27 23:46:24.662972 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 27 23:46:24.665053 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 27 23:46:24.670208 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:46:24.671294 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 23:46:24.673366 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 23:46:24.676367 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 23:46:24.677577 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:46:24.677707 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). Oct 27 23:46:24.679298 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 27 23:46:24.681584 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 23:46:24.681796 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 23:46:24.685883 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 23:46:24.686066 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 23:46:24.688007 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 23:46:24.688270 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 23:46:24.695528 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:46:24.696947 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 23:46:24.699709 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 23:46:24.703461 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 23:46:24.704668 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:46:24.704847 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). Oct 27 23:46:24.715383 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 27 23:46:24.718893 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 27 23:46:24.721147 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 27 23:46:24.723026 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 23:46:24.723187 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 23:46:24.725083 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 23:46:24.725245 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 23:46:24.728684 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 23:46:24.730254 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 23:46:24.731707 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 27 23:46:24.738686 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:46:24.744023 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 27 23:46:24.745658 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:46:24.745707 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). Oct 27 23:46:24.745740 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 27 23:46:24.745793 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 27 23:46:24.748262 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 23:46:24.749190 augenrules[1344]: No rules Oct 27 23:46:24.750593 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 27 23:46:24.753362 systemd[1]: Finished ensure-sysext.service. Oct 27 23:46:24.754537 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 23:46:24.758375 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 27 23:46:24.760042 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 27 23:46:24.761874 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 23:46:24.762046 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 27 23:46:24.764515 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 27 23:46:24.771790 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 27 23:46:24.773018 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). Oct 27 23:46:24.779904 systemd-udevd[1343]: Using default interface naming scheme 'v255'. Oct 27 23:46:24.787720 systemd-resolved[1300]: Positive Trust Anchors: Oct 27 23:46:24.787736 systemd-resolved[1300]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 23:46:24.787777 systemd-resolved[1300]: 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 Oct 27 23:46:24.794957 systemd-resolved[1300]: Defaulting to hostname 'linux'. Oct 27 23:46:24.797491 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 27 23:46:24.798859 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 27 23:46:24.800940 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 23:46:24.805357 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 27 23:46:24.833715 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 27 23:46:24.835257 systemd[1]: Reached target sysinit.target - System Initialization. Oct 27 23:46:24.837038 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 27 23:46:24.838537 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 23:46:24.839961 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 27 23:46:24.841396 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 27 23:46:24.841436 systemd[1]: Reached target paths.target - Path Units. Oct 27 23:46:24.842435 systemd[1]: Reached target time-set.target - System Time Set. Oct 27 23:46:24.843849 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 27 23:46:24.845262 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 27 23:46:24.846699 systemd[1]: Reached target timers.target - Timer Units. Oct 27 23:46:24.848620 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 27 23:46:24.852107 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 27 23:46:24.855171 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 27 23:46:24.856696 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 27 23:46:24.858189 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 27 23:46:24.862517 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 27 23:46:24.863918 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 27 23:46:24.865850 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 27 23:46:24.868037 systemd[1]: Reached target sockets.target - Socket Units. Oct 27 23:46:24.869164 systemd[1]: Reached target basic.target - Basic System. Oct 27 23:46:24.870327 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 27 23:46:24.870358 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 27 23:46:24.872368 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 27 23:46:24.875006 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 27 23:46:24.878326 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 27 23:46:24.893700 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 27 23:46:24.894940 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). Oct 27 23:46:24.896270 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 27 23:46:24.898847 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 27 23:46:24.900949 jq[1391]: false Oct 27 23:46:24.903485 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 27 23:46:24.906854 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 27 23:46:24.910385 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 27 23:46:24.911064 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 27 23:46:24.912683 systemd[1]: Starting update-engine.service - Update Engine... Oct 27 23:46:24.914867 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 27 23:46:24.920126 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 27 23:46:24.922169 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 27 23:46:24.922366 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 27 23:46:24.922606 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 27 23:46:24.922776 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 27 23:46:24.927422 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 27 23:46:24.933758 systemd-networkd[1364]: lo: Link UP Oct 27 23:46:24.933774 systemd-networkd[1364]: lo: Gained carrier Oct 27 23:46:24.936277 extend-filesystems[1392]: Found /dev/vda6 Oct 27 23:46:24.936082 systemd-networkd[1364]: Enumeration completed Oct 27 23:46:24.942185 jq[1399]: true Oct 27 23:46:24.936286 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 27 23:46:24.937786 systemd[1]: Reached target network.target - Network. Oct 27 23:46:24.943270 extend-filesystems[1392]: Found /dev/vda9 Oct 27 23:46:24.944644 systemd[1]: Starting containerd.service - containerd container runtime... Oct 27 23:46:24.948238 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 27 23:46:24.950026 extend-filesystems[1392]: Checking size of /dev/vda9 Oct 27 23:46:24.951251 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 27 23:46:24.959199 update_engine[1398]: I20251027 23:46:24.958518 1398 main.cc:92] Flatcar Update Engine starting Oct 27 23:46:24.963472 extend-filesystems[1392]: Old size kept for /dev/vda9 Oct 27 23:46:24.963940 systemd[1]: motdgen.service: Deactivated successfully. Oct 27 23:46:24.964161 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 27 23:46:24.966815 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 27 23:46:24.967202 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 27 23:46:24.969443 jq[1423]: true Oct 27 23:46:24.982280 dbus-daemon[1389]: [system] SELinux support is enabled Oct 27 23:46:24.982527 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 27 23:46:24.985714 update_engine[1398]: I20251027 23:46:24.985668 1398 update_check_scheduler.cc:74] Next update check in 2m12s Oct 27 23:46:24.988721 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). Oct 27 23:46:24.988756 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 27 23:46:24.990723 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). Oct 27 23:46:24.990745 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 27 23:46:24.992654 systemd[1]: Started update-engine.service - Update Engine. Oct 27 23:46:24.995903 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 27 23:46:24.997847 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 27 23:46:25.001903 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 27 23:46:25.006274 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 27 23:46:25.006992 (ntainerd)[1437]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 27 23:46:25.008900 systemd-networkd[1364]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:46:25.008905 systemd-networkd[1364]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 27 23:46:25.014074 systemd-networkd[1364]: eth0: Link UP Oct 27 23:46:25.014742 systemd-networkd[1364]: eth0: Gained carrier Oct 27 23:46:25.014775 systemd-networkd[1364]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:46:25.022706 bash[1453]: Updated "/home/core/.ssh/authorized_keys" Oct 27 23:46:25.023394 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 27 23:46:25.027035 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 27 23:46:25.029363 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 27 23:46:25.033183 systemd-networkd[1364]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 27 23:46:25.034488 systemd-timesyncd[1355]: Network configuration changed, trying to establish connection. Oct 27 23:46:25.496325 systemd-resolved[1300]: Clock change detected. Flushing caches. Oct 27 23:46:25.497064 systemd-timesyncd[1355]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 27 23:46:25.497129 systemd-timesyncd[1355]: Initial clock synchronization to Mon 2025-10-27 23:46:25.496275 UTC. Oct 27 23:46:25.502132 systemd-logind[1397]: New seat seat0. Oct 27 23:46:25.502854 systemd[1]: Started systemd-logind.service - User Login Management. Oct 27 23:46:25.540765 locksmithd[1447]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 27 23:46:25.613357 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:46:25.668010 systemd-logind[1397]: Watching system buttons on /dev/input/event0 (Power Button) Oct 27 23:46:25.668276 containerd[1437]: time="2025-10-27T23:46:25Z" 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 Oct 27 23:46:25.669078 containerd[1437]: time="2025-10-27T23:46:25.669034633Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 27 23:46:25.691477 containerd[1437]: time="2025-10-27T23:46:25.691426753Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.96µs" Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.691591473Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.691618113Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.691785073Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.691800713Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.691824153Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.691872433Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.691882473Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.692108753Z" 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 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.692125033Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.692135193Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.692143873Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692574 containerd[1437]: time="2025-10-27T23:46:25.692207833Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692829 containerd[1437]: time="2025-10-27T23:46:25.692393353Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 27 23:46:25.692829 containerd[1437]: time="2025-10-27T23:46:25.692421233Z" 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 Oct 27 23:46:25.692829 containerd[1437]: time="2025-10-27T23:46:25.692431713Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 27 23:46:25.692829 containerd[1437]: time="2025-10-27T23:46:25.692467713Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 27 23:46:25.692829 containerd[1437]: time="2025-10-27T23:46:25.692690113Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 27 23:46:25.692829 containerd[1437]: time="2025-10-27T23:46:25.692759393Z" level=info msg="metadata content store policy set" policy=shared Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699261193Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699336393Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699361233Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699374873Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699387033Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699401273Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699414033Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699433033Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699446673Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699457353Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699467433Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 27 23:46:25.699961 containerd[1437]: time="2025-10-27T23:46:25.699480273Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 27 23:46:25.702041 containerd[1437]: time="2025-10-27T23:46:25.702001473Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 27 23:46:25.702041 containerd[1437]: time="2025-10-27T23:46:25.702039793Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 27 23:46:25.702121 containerd[1437]: time="2025-10-27T23:46:25.702057153Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 27 23:46:25.702121 containerd[1437]: time="2025-10-27T23:46:25.702069593Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 27 23:46:25.702121 containerd[1437]: time="2025-10-27T23:46:25.702080513Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 27 23:46:25.702121 containerd[1437]: time="2025-10-27T23:46:25.702099713Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 27 23:46:25.702121 containerd[1437]: time="2025-10-27T23:46:25.702112993Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 27 23:46:25.702121 containerd[1437]: time="2025-10-27T23:46:25.702122753Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 27 23:46:25.702227 containerd[1437]: time="2025-10-27T23:46:25.702134873Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 27 23:46:25.702227 containerd[1437]: time="2025-10-27T23:46:25.702147673Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 27 23:46:25.702227 containerd[1437]: time="2025-10-27T23:46:25.702157873Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 27 23:46:25.702369 containerd[1437]: time="2025-10-27T23:46:25.702339593Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 27 23:46:25.702369 containerd[1437]: time="2025-10-27T23:46:25.702367713Z" level=info msg="Start snapshots syncer" Oct 27 23:46:25.702426 containerd[1437]: time="2025-10-27T23:46:25.702396033Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 27 23:46:25.702683 containerd[1437]: time="2025-10-27T23:46:25.702643353Z" 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\"}" Oct 27 23:46:25.702791 containerd[1437]: time="2025-10-27T23:46:25.702697753Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 27 23:46:25.702791 containerd[1437]: time="2025-10-27T23:46:25.702768273Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 27 23:46:25.702951 containerd[1437]: time="2025-10-27T23:46:25.702899313Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 27 23:46:25.702951 containerd[1437]: time="2025-10-27T23:46:25.702949113Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 27 23:46:25.703005 containerd[1437]: time="2025-10-27T23:46:25.702961273Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 27 23:46:25.703005 containerd[1437]: time="2025-10-27T23:46:25.702973953Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 27 23:46:25.703005 containerd[1437]: time="2025-10-27T23:46:25.702986393Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 27 23:46:25.703060 containerd[1437]: time="2025-10-27T23:46:25.703004393Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 27 23:46:25.703060 containerd[1437]: time="2025-10-27T23:46:25.703015353Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 27 23:46:25.703060 containerd[1437]: time="2025-10-27T23:46:25.703039233Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 27 23:46:25.703060 containerd[1437]: time="2025-10-27T23:46:25.703049953Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 27 23:46:25.703128 containerd[1437]: time="2025-10-27T23:46:25.703061073Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 27 23:46:25.703128 containerd[1437]: time="2025-10-27T23:46:25.703096873Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 27 23:46:25.703128 containerd[1437]: time="2025-10-27T23:46:25.703110633Z" 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 Oct 27 23:46:25.703128 containerd[1437]: time="2025-10-27T23:46:25.703119753Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:46:25.703193 containerd[1437]: time="2025-10-27T23:46:25.703128673Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:46:25.703193 containerd[1437]: time="2025-10-27T23:46:25.703136713Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 27 23:46:25.703193 containerd[1437]: time="2025-10-27T23:46:25.703145633Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 27 23:46:25.703193 containerd[1437]: time="2025-10-27T23:46:25.703155433Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 27 23:46:25.703255 containerd[1437]: time="2025-10-27T23:46:25.703230553Z" level=info msg="runtime interface created" Oct 27 23:46:25.703255 containerd[1437]: time="2025-10-27T23:46:25.703235993Z" level=info msg="created NRI interface" Oct 27 23:46:25.703255 containerd[1437]: time="2025-10-27T23:46:25.703244833Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 27 23:46:25.703304 containerd[1437]: time="2025-10-27T23:46:25.703255273Z" level=info msg="Connect containerd service" Oct 27 23:46:25.703304 containerd[1437]: time="2025-10-27T23:46:25.703279433Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 27 23:46:25.704159 containerd[1437]: time="2025-10-27T23:46:25.704122153Z" 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" Oct 27 23:46:25.711977 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.773953873Z" level=info msg="Start subscribing containerd event" Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774018953Z" level=info msg="Start recovering state" Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774110393Z" level=info msg="Start event monitor" Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774122193Z" level=info msg="Start cni network conf syncer for default" Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774129913Z" level=info msg="Start streaming server" Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774137993Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774145153Z" level=info msg="runtime interface starting up..." Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774150793Z" level=info msg="starting plugins..." Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774164553Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774203993Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774255713Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 27 23:46:25.774956 containerd[1437]: time="2025-10-27T23:46:25.774314073Z" level=info msg="containerd successfully booted in 0.106378s" Oct 27 23:46:25.774423 systemd[1]: Started containerd.service - containerd container runtime. Oct 27 23:46:26.321057 sshd_keygen[1407]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 27 23:46:26.340623 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 27 23:46:26.343337 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 27 23:46:26.377370 systemd[1]: issuegen.service: Deactivated successfully. Oct 27 23:46:26.377600 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 27 23:46:26.380367 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 27 23:46:26.401863 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 27 23:46:26.404841 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 27 23:46:26.407139 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 27 23:46:26.408570 systemd[1]: Reached target getty.target - Login Prompts. Oct 27 23:46:27.458063 systemd-networkd[1364]: eth0: Gained IPv6LL Oct 27 23:46:27.460636 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 27 23:46:27.465166 systemd[1]: Reached target network-online.target - Network is Online. Oct 27 23:46:27.473100 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 27 23:46:27.492352 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 27 23:46:27.511883 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 27 23:46:27.512164 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 27 23:46:27.514504 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 27 23:46:27.520002 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 27 23:46:27.521584 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 27 23:46:27.525199 systemd[1]: Startup finished in 2.058s (kernel) + 3.874s (initrd) + 3.637s (userspace) = 9.570s. Oct 27 23:46:33.362724 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 27 23:46:33.366859 systemd[1]: Started sshd@0-10.0.0.129:22-10.0.0.1:36932.service - OpenSSH per-connection server daemon (10.0.0.1:36932). Oct 27 23:46:33.454263 sshd[1544]: Accepted publickey for core from 10.0.0.1 port 36932 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:33.456456 sshd-session[1544]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:33.463207 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 27 23:46:33.464348 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 27 23:46:33.471535 systemd-logind[1397]: New session 1 of user core. Oct 27 23:46:33.482804 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 27 23:46:33.490294 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 27 23:46:33.507487 (systemd)[1549]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 27 23:46:33.511131 systemd-logind[1397]: New session c1 of user core. Oct 27 23:46:33.631417 systemd[1549]: Queued start job for default target default.target. Oct 27 23:46:33.649057 systemd[1549]: Created slice app.slice - User Application Slice. Oct 27 23:46:33.649094 systemd[1549]: Reached target paths.target - Paths. Oct 27 23:46:33.649138 systemd[1549]: Reached target timers.target - Timers. Oct 27 23:46:33.650495 systemd[1549]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 27 23:46:33.661603 systemd[1549]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 27 23:46:33.661730 systemd[1549]: Reached target sockets.target - Sockets. Oct 27 23:46:33.661802 systemd[1549]: Reached target basic.target - Basic System. Oct 27 23:46:33.661851 systemd[1549]: Reached target default.target - Main User Target. Oct 27 23:46:33.661885 systemd[1549]: Startup finished in 144ms. Oct 27 23:46:33.662032 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 27 23:46:33.663484 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 27 23:46:33.732144 systemd[1]: Started sshd@1-10.0.0.129:22-10.0.0.1:36948.service - OpenSSH per-connection server daemon (10.0.0.1:36948). Oct 27 23:46:33.813782 sshd[1560]: Accepted publickey for core from 10.0.0.1 port 36948 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:33.815241 sshd-session[1560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:33.820069 systemd-logind[1397]: New session 2 of user core. Oct 27 23:46:33.837136 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 27 23:46:33.893296 sshd[1563]: Connection closed by 10.0.0.1 port 36948 Oct 27 23:46:33.893638 sshd-session[1560]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:33.907223 systemd[1]: sshd@1-10.0.0.129:22-10.0.0.1:36948.service: Deactivated successfully. Oct 27 23:46:33.909394 systemd[1]: session-2.scope: Deactivated successfully. Oct 27 23:46:33.910277 systemd-logind[1397]: Session 2 logged out. Waiting for processes to exit. Oct 27 23:46:33.913032 systemd[1]: Started sshd@2-10.0.0.129:22-10.0.0.1:36964.service - OpenSSH per-connection server daemon (10.0.0.1:36964). Oct 27 23:46:33.913529 systemd-logind[1397]: Removed session 2. Oct 27 23:46:33.973483 sshd[1569]: Accepted publickey for core from 10.0.0.1 port 36964 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:33.974823 sshd-session[1569]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:33.979561 systemd-logind[1397]: New session 3 of user core. Oct 27 23:46:33.994096 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 27 23:46:34.043555 sshd[1572]: Connection closed by 10.0.0.1 port 36964 Oct 27 23:46:34.043931 sshd-session[1569]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:34.058041 systemd[1]: sshd@2-10.0.0.129:22-10.0.0.1:36964.service: Deactivated successfully. Oct 27 23:46:34.060521 systemd[1]: session-3.scope: Deactivated successfully. Oct 27 23:46:34.061386 systemd-logind[1397]: Session 3 logged out. Waiting for processes to exit. Oct 27 23:46:34.063793 systemd[1]: Started sshd@3-10.0.0.129:22-10.0.0.1:36974.service - OpenSSH per-connection server daemon (10.0.0.1:36974). Oct 27 23:46:34.064427 systemd-logind[1397]: Removed session 3. Oct 27 23:46:34.131060 sshd[1578]: Accepted publickey for core from 10.0.0.1 port 36974 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:34.132972 sshd-session[1578]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:34.137813 systemd-logind[1397]: New session 4 of user core. Oct 27 23:46:34.147127 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 27 23:46:34.203586 sshd[1581]: Connection closed by 10.0.0.1 port 36974 Oct 27 23:46:34.204099 sshd-session[1578]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:34.216327 systemd[1]: sshd@3-10.0.0.129:22-10.0.0.1:36974.service: Deactivated successfully. Oct 27 23:46:34.219437 systemd[1]: session-4.scope: Deactivated successfully. Oct 27 23:46:34.220181 systemd-logind[1397]: Session 4 logged out. Waiting for processes to exit. Oct 27 23:46:34.222686 systemd[1]: Started sshd@4-10.0.0.129:22-10.0.0.1:36980.service - OpenSSH per-connection server daemon (10.0.0.1:36980). Oct 27 23:46:34.223172 systemd-logind[1397]: Removed session 4. Oct 27 23:46:34.283000 sshd[1587]: Accepted publickey for core from 10.0.0.1 port 36980 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:34.284490 sshd-session[1587]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:34.291261 systemd-logind[1397]: New session 5 of user core. Oct 27 23:46:34.311136 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 27 23:46:34.370292 sudo[1591]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 27 23:46:34.370589 sudo[1591]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 27 23:46:34.382679 sudo[1591]: pam_unix(sudo:session): session closed for user root Oct 27 23:46:34.384176 sshd[1590]: Connection closed by 10.0.0.1 port 36980 Oct 27 23:46:34.384697 sshd-session[1587]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:34.398928 systemd[1]: sshd@4-10.0.0.129:22-10.0.0.1:36980.service: Deactivated successfully. Oct 27 23:46:34.401158 systemd[1]: session-5.scope: Deactivated successfully. Oct 27 23:46:34.403732 systemd-logind[1397]: Session 5 logged out. Waiting for processes to exit. Oct 27 23:46:34.406618 systemd[1]: Started sshd@5-10.0.0.129:22-10.0.0.1:36982.service - OpenSSH per-connection server daemon (10.0.0.1:36982). Oct 27 23:46:34.407203 systemd-logind[1397]: Removed session 5. Oct 27 23:46:34.471654 sshd[1597]: Accepted publickey for core from 10.0.0.1 port 36982 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:34.473010 sshd-session[1597]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:34.476708 systemd-logind[1397]: New session 6 of user core. Oct 27 23:46:34.492400 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 27 23:46:34.546862 sudo[1602]: 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 Oct 27 23:46:34.547145 sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 27 23:46:34.551895 sudo[1602]: pam_unix(sudo:session): session closed for user root Oct 27 23:46:34.556252 sudo[1601]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 27 23:46:34.556486 sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 27 23:46:34.565733 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 27 23:46:34.621118 augenrules[1624]: No rules Oct 27 23:46:34.622226 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 23:46:34.623633 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 27 23:46:34.624486 sudo[1601]: pam_unix(sudo:session): session closed for user root Oct 27 23:46:34.625613 sshd[1600]: Connection closed by 10.0.0.1 port 36982 Oct 27 23:46:34.626042 sshd-session[1597]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:34.634720 systemd[1]: sshd@5-10.0.0.129:22-10.0.0.1:36982.service: Deactivated successfully. Oct 27 23:46:34.637102 systemd[1]: session-6.scope: Deactivated successfully. Oct 27 23:46:34.638536 systemd-logind[1397]: Session 6 logged out. Waiting for processes to exit. Oct 27 23:46:34.639856 systemd[1]: Started sshd@6-10.0.0.129:22-10.0.0.1:36994.service - OpenSSH per-connection server daemon (10.0.0.1:36994). Oct 27 23:46:34.640728 systemd-logind[1397]: Removed session 6. Oct 27 23:46:34.703639 sshd[1633]: Accepted publickey for core from 10.0.0.1 port 36994 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:34.704791 sshd-session[1633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:34.708689 systemd-logind[1397]: New session 7 of user core. Oct 27 23:46:34.720068 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 27 23:46:34.772259 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 27 23:46:34.799240 (dockerd)[1638]: 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 Oct 27 23:46:34.997330 dockerd[1638]: time="2025-10-27T23:46:34.996309673Z" level=info msg="Starting up" Oct 27 23:46:34.997731 dockerd[1638]: time="2025-10-27T23:46:34.997683953Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 27 23:46:35.008254 dockerd[1638]: time="2025-10-27T23:46:35.008206953Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 27 23:46:35.050280 dockerd[1638]: time="2025-10-27T23:46:35.050218033Z" level=info msg="Loading containers: start." Oct 27 23:46:35.058936 kernel: Initializing XFRM netlink socket Oct 27 23:46:35.260891 systemd-networkd[1364]: docker0: Link UP Oct 27 23:46:35.264468 dockerd[1638]: time="2025-10-27T23:46:35.264428313Z" level=info msg="Loading containers: done." Oct 27 23:46:35.278367 dockerd[1638]: time="2025-10-27T23:46:35.278307753Z" 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 Oct 27 23:46:35.278518 dockerd[1638]: time="2025-10-27T23:46:35.278395873Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 27 23:46:35.278518 dockerd[1638]: time="2025-10-27T23:46:35.278476353Z" level=info msg="Initializing buildkit" Oct 27 23:46:35.300318 dockerd[1638]: time="2025-10-27T23:46:35.300268913Z" level=info msg="Completed buildkit initialization" Oct 27 23:46:35.307395 dockerd[1638]: time="2025-10-27T23:46:35.307343593Z" level=info msg="Daemon has completed initialization" Oct 27 23:46:35.307581 dockerd[1638]: time="2025-10-27T23:46:35.307406233Z" level=info msg="API listen on /run/docker.sock" Oct 27 23:46:35.307607 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 27 23:46:35.315831 sshd[1636]: Connection closed by 10.0.0.1 port 36994 Oct 27 23:46:35.316575 sshd-session[1633]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:35.325890 systemd[1]: sshd@6-10.0.0.129:22-10.0.0.1:36994.service: Deactivated successfully. Oct 27 23:46:35.329195 systemd[1]: session-7.scope: Deactivated successfully. Oct 27 23:46:35.329936 systemd-logind[1397]: Session 7 logged out. Waiting for processes to exit. Oct 27 23:46:35.332212 systemd[1]: Started sshd@7-10.0.0.129:22-10.0.0.1:37020.service - OpenSSH per-connection server daemon (10.0.0.1:37020). Oct 27 23:46:35.332896 systemd-logind[1397]: Removed session 7. Oct 27 23:46:35.395139 sshd[1850]: Accepted publickey for core from 10.0.0.1 port 37020 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:35.396708 sshd-session[1850]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:35.401470 systemd-logind[1397]: New session 8 of user core. Oct 27 23:46:35.409068 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 27 23:46:36.548670 systemd[1]: var-lib-docker-overlay2-68392368ca610d922352bac54106509373fb5678f62472d1a02e2673f808861d\x2dinit-merged.mount: Deactivated successfully. Oct 27 23:46:36.605189 containerd[1437]: time="2025-10-27T23:46:36.605117353Z" level=info msg="connecting to shim 405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55" address="unix:///run/containerd/s/809a1dbb1487debbb0585c1f3bfbca90a7d0c772c4f88fdb4dc9cda01bdf94d5" namespace=moby protocol=ttrpc version=3 Oct 27 23:46:36.635372 systemd[1]: Started docker-405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55.scope - libcontainer container 405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55. Oct 27 23:46:36.648132 systemd-resolved[1300]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 27 23:46:36.667527 kernel: docker0: port 1(veth974e1c9) entered blocking state Oct 27 23:46:36.667612 kernel: docker0: port 1(veth974e1c9) entered disabled state Oct 27 23:46:36.668353 kernel: veth974e1c9: entered allmulticast mode Oct 27 23:46:36.669101 kernel: veth974e1c9: entered promiscuous mode Oct 27 23:46:36.670183 systemd-networkd[1364]: veth974e1c9: Link UP Oct 27 23:46:36.679945 kernel: eth0: renamed from veth1420590 Oct 27 23:46:36.681634 kernel: docker0: port 1(veth974e1c9) entered blocking state Oct 27 23:46:36.681685 kernel: docker0: port 1(veth974e1c9) entered forwarding state Oct 27 23:46:36.681777 systemd-networkd[1364]: veth974e1c9: Gained carrier Oct 27 23:46:36.682039 systemd-networkd[1364]: docker0: Gained carrier Oct 27 23:46:36.707917 sshd[1854]: Connection closed by 10.0.0.1 port 37020 Oct 27 23:46:36.708295 sshd-session[1850]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:36.715764 systemd[1]: sshd@7-10.0.0.129:22-10.0.0.1:37020.service: Deactivated successfully. Oct 27 23:46:36.717158 systemd[1]: session-8.scope: Deactivated successfully. Oct 27 23:46:36.719095 systemd-logind[1397]: Session 8 logged out. Waiting for processes to exit. Oct 27 23:46:36.721147 systemd[1]: Started sshd@8-10.0.0.129:22-10.0.0.1:37024.service - OpenSSH per-connection server daemon (10.0.0.1:37024). Oct 27 23:46:36.722688 systemd-logind[1397]: Removed session 8. Oct 27 23:46:36.782005 sshd[1927]: Accepted publickey for core from 10.0.0.1 port 37024 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:36.783165 sshd-session[1927]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:36.787970 systemd-logind[1397]: New session 9 of user core. Oct 27 23:46:36.795076 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 27 23:46:36.848476 sshd[1930]: Connection closed by 10.0.0.1 port 37024 Oct 27 23:46:36.849032 sshd-session[1927]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:36.860883 systemd[1]: sshd@8-10.0.0.129:22-10.0.0.1:37024.service: Deactivated successfully. Oct 27 23:46:36.862466 systemd[1]: session-9.scope: Deactivated successfully. Oct 27 23:46:36.863130 systemd-logind[1397]: Session 9 logged out. Waiting for processes to exit. Oct 27 23:46:36.865945 systemd[1]: Started sshd@9-10.0.0.129:22-10.0.0.1:37038.service - OpenSSH per-connection server daemon (10.0.0.1:37038). Oct 27 23:46:36.866579 systemd-logind[1397]: Removed session 9. Oct 27 23:46:36.937171 sshd[1936]: Accepted publickey for core from 10.0.0.1 port 37038 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:36.938575 sshd-session[1936]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:36.943036 systemd-logind[1397]: New session 10 of user core. Oct 27 23:46:36.955093 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 27 23:46:37.005442 sudo[1940]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1437 Oct 27 23:46:37.005705 sudo[1940]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 27 23:46:37.009332 sudo[1940]: pam_unix(sudo:session): session closed for user root Oct 27 23:46:37.009645 containerd[1437]: time="2025-10-27T23:46:37.009502073Z" level=info msg="Stop CRI service" Oct 27 23:46:37.010524 dockerd[1638]: time="2025-10-27T23:46:37.010411713Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Oct 27 23:46:37.010524 dockerd[1638]: time="2025-10-27T23:46:37.010479993Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Oct 27 23:46:37.011106 dockerd[1638]: time="2025-10-27T23:46:37.011003513Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Oct 27 23:46:37.011106 dockerd[1638]: time="2025-10-27T23:46:37.011035753Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Oct 27 23:46:37.011233 sshd[1939]: Connection closed by 10.0.0.1 port 37038 Oct 27 23:46:37.012533 sshd-session[1936]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:37.023321 systemd[1]: containerd.service: Deactivated successfully. Oct 27 23:46:37.023827 systemd[1]: containerd.service: Unit process 1880 (containerd-shim) remains running after unit stopped. Oct 27 23:46:37.024165 systemd[1]: containerd.service: Consumed 284ms CPU time, 120.1M memory peak. Oct 27 23:46:37.024697 systemd[1]: sshd@9-10.0.0.129:22-10.0.0.1:37038.service: Deactivated successfully. Oct 27 23:46:37.026641 systemd[1]: session-10.scope: Deactivated successfully. Oct 27 23:46:37.029117 systemd-logind[1397]: Session 10 logged out. Waiting for processes to exit. Oct 27 23:46:37.033174 systemd[1]: Started sshd@10-10.0.0.129:22-10.0.0.1:37040.service - OpenSSH per-connection server daemon (10.0.0.1:37040). Oct 27 23:46:37.033883 systemd-logind[1397]: Removed session 10. Oct 27 23:46:37.092301 sshd[1947]: Accepted publickey for core from 10.0.0.1 port 37040 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:37.093868 sshd-session[1947]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:37.097684 systemd-logind[1397]: New session 11 of user core. Oct 27 23:46:37.108085 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 27 23:46:37.161833 sshd[1950]: Connection closed by 10.0.0.1 port 37040 Oct 27 23:46:37.162130 sshd-session[1947]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:37.165210 systemd[1]: sshd@10-10.0.0.129:22-10.0.0.1:37040.service: Deactivated successfully. Oct 27 23:46:37.167302 systemd[1]: session-11.scope: Deactivated successfully. Oct 27 23:46:37.168073 systemd-logind[1397]: Session 11 logged out. Waiting for processes to exit. Oct 27 23:46:37.169170 systemd-logind[1397]: Removed session 11. Oct 27 23:46:38.466071 systemd-networkd[1364]: docker0: Gained IPv6LL Oct 27 23:46:38.530205 systemd-networkd[1364]: veth974e1c9: Gained IPv6LL Oct 27 23:46:42.087014 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Oct 27 23:46:42.087445 systemd[1]: Stopping docker.service - Docker Application Container Engine... Oct 27 23:46:42.087604 dockerd[1638]: time="2025-10-27T23:46:42.087460873Z" level=info msg="Processing signal 'terminated'" Oct 27 23:46:43.186445 systemd[1]: Started sshd@11-10.0.0.129:22-10.0.0.1:53174.service - OpenSSH per-connection server daemon (10.0.0.1:53174). Oct 27 23:46:43.242323 sshd[1956]: Accepted publickey for core from 10.0.0.1 port 53174 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:43.243546 sshd-session[1956]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:43.247980 systemd-logind[1397]: New session 12 of user core. Oct 27 23:46:43.261151 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 27 23:46:43.315811 sshd[1959]: Connection closed by 10.0.0.1 port 53174 Oct 27 23:46:43.316143 sshd-session[1956]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:43.319592 systemd[1]: sshd@11-10.0.0.129:22-10.0.0.1:53174.service: Deactivated successfully. Oct 27 23:46:43.321197 systemd[1]: session-12.scope: Deactivated successfully. Oct 27 23:46:43.322952 systemd-logind[1397]: Session 12 logged out. Waiting for processes to exit. Oct 27 23:46:43.324693 systemd-logind[1397]: Removed session 12. Oct 27 23:46:49.333146 systemd[1]: Started sshd@12-10.0.0.129:22-10.0.0.1:38368.service - OpenSSH per-connection server daemon (10.0.0.1:38368). Oct 27 23:46:49.386230 sshd[1965]: Accepted publickey for core from 10.0.0.1 port 38368 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:49.387536 sshd-session[1965]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:49.391794 systemd-logind[1397]: New session 13 of user core. Oct 27 23:46:49.398098 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 27 23:46:49.454995 sshd[1968]: Connection closed by 10.0.0.1 port 38368 Oct 27 23:46:49.455319 sshd-session[1965]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:49.458729 systemd[1]: sshd@12-10.0.0.129:22-10.0.0.1:38368.service: Deactivated successfully. Oct 27 23:46:49.461491 systemd[1]: session-13.scope: Deactivated successfully. Oct 27 23:46:49.463688 systemd-logind[1397]: Session 13 logged out. Waiting for processes to exit. Oct 27 23:46:49.468558 systemd-logind[1397]: Removed session 13. Oct 27 23:46:55.470020 systemd[1]: Started sshd@13-10.0.0.129:22-10.0.0.1:38560.service - OpenSSH per-connection server daemon (10.0.0.1:38560). Oct 27 23:46:55.518565 sshd[1974]: Accepted publickey for core from 10.0.0.1 port 38560 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:46:55.519895 sshd-session[1974]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:46:55.524309 systemd-logind[1397]: New session 14 of user core. Oct 27 23:46:55.534099 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 27 23:46:55.588225 sshd[1977]: Connection closed by 10.0.0.1 port 38560 Oct 27 23:46:55.588566 sshd-session[1974]: pam_unix(sshd:session): session closed for user core Oct 27 23:46:55.591870 systemd[1]: sshd@13-10.0.0.129:22-10.0.0.1:38560.service: Deactivated successfully. Oct 27 23:46:55.593661 systemd[1]: session-14.scope: Deactivated successfully. Oct 27 23:46:55.595810 systemd-logind[1397]: Session 14 logged out. Waiting for processes to exit. Oct 27 23:46:55.596853 systemd-logind[1397]: Removed session 14. Oct 27 23:46:57.088519 dockerd[1638]: time="2025-10-27T23:46:57.088452193Z" level=error msg="Force shutdown daemon" Oct 27 23:46:57.088933 dockerd[1638]: time="2025-10-27T23:46:57.088575113Z" level=info msg="Daemon shutdown complete" Oct 27 23:46:57.091579 systemd[1]: docker.service: Deactivated successfully. Oct 27 23:46:57.091896 systemd[1]: Stopped docker.service - Docker Application Container Engine. Oct 27 23:46:57.091995 systemd[1]: docker.service: Consumed 974ms CPU time, 123.4M memory peak. Oct 27 23:46:57.092441 systemd[1]: docker.socket: Deactivated successfully. Oct 27 23:46:57.092733 systemd[1]: Closed docker.socket - Docker Socket for the API. Oct 27 23:46:57.092794 systemd[1]: Stopping docker.socket - Docker Socket for the API... Oct 27 23:46:57.094373 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 27 23:46:57.094567 systemd[1]: containerd.service: Found left-over process 1880 (containerd-shim) in control group while starting unit. Ignoring. Oct 27 23:46:57.094570 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 27 23:46:57.100416 systemd[1]: Starting containerd.service - containerd container runtime... Oct 27 23:46:57.101381 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 27 23:46:57.112395 systemd[1]: containerd.service: Found left-over process 1880 (containerd-shim) in control group while starting unit. Ignoring. Oct 27 23:46:57.114640 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 27 23:46:57.119831 (ntainerd)[1988]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 27 23:46:57.134486 containerd[1988]: time="2025-10-27T23:46:57Z" 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 Oct 27 23:46:57.135114 containerd[1988]: time="2025-10-27T23:46:57.135071353Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 27 23:46:57.142491 containerd[1988]: time="2025-10-27T23:46:57.142431273Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.36µs" Oct 27 23:46:57.142491 containerd[1988]: time="2025-10-27T23:46:57.142472873Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 27 23:46:57.142491 containerd[1988]: time="2025-10-27T23:46:57.142497353Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 27 23:46:57.142636 containerd[1988]: time="2025-10-27T23:46:57.142538633Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 27 23:46:57.142636 containerd[1988]: time="2025-10-27T23:46:57.142552713Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 27 23:46:57.142768 containerd[1988]: time="2025-10-27T23:46:57.142745313Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:46:57.142794 containerd[1988]: time="2025-10-27T23:46:57.142779833Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:46:57.142815 containerd[1988]: time="2025-10-27T23:46:57.142792153Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 27 23:46:57.143085 containerd[1988]: time="2025-10-27T23:46:57.143045353Z" 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 Oct 27 23:46:57.143085 containerd[1988]: time="2025-10-27T23:46:57.143068513Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:46:57.143085 containerd[1988]: time="2025-10-27T23:46:57.143080633Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:46:57.143157 containerd[1988]: time="2025-10-27T23:46:57.143089273Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 27 23:46:57.143157 containerd[1988]: time="2025-10-27T23:46:57.143114553Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 27 23:46:57.143317 containerd[1988]: time="2025-10-27T23:46:57.143298553Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 27 23:46:57.143341 containerd[1988]: time="2025-10-27T23:46:57.143326713Z" 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 Oct 27 23:46:57.143341 containerd[1988]: time="2025-10-27T23:46:57.143336393Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 27 23:46:57.143391 containerd[1988]: time="2025-10-27T23:46:57.143368153Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 27 23:46:57.144129 containerd[1988]: time="2025-10-27T23:46:57.143879033Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 27 23:46:57.144129 containerd[1988]: time="2025-10-27T23:46:57.143938513Z" level=info msg="metadata content store policy set" policy=shared Oct 27 23:46:57.144129 containerd[1988]: time="2025-10-27T23:46:57.144088553Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 27 23:46:57.144129 containerd[1988]: time="2025-10-27T23:46:57.144125593Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 27 23:46:57.144272 containerd[1988]: time="2025-10-27T23:46:57.144145553Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 27 23:46:57.144272 containerd[1988]: time="2025-10-27T23:46:57.144161913Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 27 23:46:57.144272 containerd[1988]: time="2025-10-27T23:46:57.144179153Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 27 23:46:57.144272 containerd[1988]: time="2025-10-27T23:46:57.144198193Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 27 23:46:57.144272 containerd[1988]: time="2025-10-27T23:46:57.144221313Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 27 23:46:57.144272 containerd[1988]: time="2025-10-27T23:46:57.144238913Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 27 23:46:57.144272 containerd[1988]: time="2025-10-27T23:46:57.144261313Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 27 23:46:57.144466 containerd[1988]: time="2025-10-27T23:46:57.144286673Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 27 23:46:57.144466 containerd[1988]: time="2025-10-27T23:46:57.144302153Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 27 23:46:57.144466 containerd[1988]: time="2025-10-27T23:46:57.144319153Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 27 23:46:57.144635 containerd[1988]: time="2025-10-27T23:46:57.144578393Z" level=info msg="connecting to shim 405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55" address="unix:///run/containerd/s/809a1dbb1487debbb0585c1f3bfbca90a7d0c772c4f88fdb4dc9cda01bdf94d5" namespace=moby protocol=ttrpc version=3 Oct 27 23:46:57.152025 containerd[1988]: time="2025-10-27T23:46:57.151985633Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 27 23:46:57.152124 containerd[1988]: time="2025-10-27T23:46:57.152038353Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 27 23:46:57.152124 containerd[1988]: time="2025-10-27T23:46:57.152068073Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 27 23:46:57.152124 containerd[1988]: time="2025-10-27T23:46:57.152081313Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 27 23:46:57.152124 containerd[1988]: time="2025-10-27T23:46:57.152097433Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 27 23:46:57.152124 containerd[1988]: time="2025-10-27T23:46:57.152109073Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 27 23:46:57.152124 containerd[1988]: time="2025-10-27T23:46:57.152123913Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 27 23:46:57.152257 containerd[1988]: time="2025-10-27T23:46:57.152137033Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 27 23:46:57.152257 containerd[1988]: time="2025-10-27T23:46:57.152150193Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 27 23:46:57.152257 containerd[1988]: time="2025-10-27T23:46:57.152161793Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 27 23:46:57.152257 containerd[1988]: time="2025-10-27T23:46:57.152173233Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 27 23:46:57.152257 containerd[1988]: time="2025-10-27T23:46:57.152238753Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 27 23:46:57.152257 containerd[1988]: time="2025-10-27T23:46:57.152253473Z" level=info msg="Start snapshots syncer" Oct 27 23:46:57.152370 containerd[1988]: time="2025-10-27T23:46:57.152284393Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 27 23:46:57.152525 containerd[1988]: time="2025-10-27T23:46:57.152490193Z" 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\"}" Oct 27 23:46:57.152612 containerd[1988]: time="2025-10-27T23:46:57.152551273Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 27 23:46:57.152647 containerd[1988]: time="2025-10-27T23:46:57.152628353Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 27 23:46:57.152693 containerd[1988]: time="2025-10-27T23:46:57.152681993Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 27 23:46:57.152759 containerd[1988]: time="2025-10-27T23:46:57.152703393Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 27 23:46:57.152759 containerd[1988]: time="2025-10-27T23:46:57.152715273Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 27 23:46:57.152759 containerd[1988]: time="2025-10-27T23:46:57.152729593Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 27 23:46:57.152759 containerd[1988]: time="2025-10-27T23:46:57.152753753Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 27 23:46:57.152845 containerd[1988]: time="2025-10-27T23:46:57.152765673Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 27 23:46:57.152845 containerd[1988]: time="2025-10-27T23:46:57.152778113Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 27 23:46:57.152845 containerd[1988]: time="2025-10-27T23:46:57.152807153Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 27 23:46:57.152845 containerd[1988]: time="2025-10-27T23:46:57.152818033Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 27 23:46:57.152845 containerd[1988]: time="2025-10-27T23:46:57.152836953Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 27 23:46:57.152973 containerd[1988]: time="2025-10-27T23:46:57.152875873Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 27 23:46:57.152973 containerd[1988]: time="2025-10-27T23:46:57.152891393Z" 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 Oct 27 23:46:57.152973 containerd[1988]: time="2025-10-27T23:46:57.152900993Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:46:57.152973 containerd[1988]: time="2025-10-27T23:46:57.152930433Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:46:57.152973 containerd[1988]: time="2025-10-27T23:46:57.152940633Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 27 23:46:57.152973 containerd[1988]: time="2025-10-27T23:46:57.152950233Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 27 23:46:57.152973 containerd[1988]: time="2025-10-27T23:46:57.152964513Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 27 23:46:57.153093 containerd[1988]: time="2025-10-27T23:46:57.152981953Z" level=info msg="runtime interface created" Oct 27 23:46:57.153093 containerd[1988]: time="2025-10-27T23:46:57.152987873Z" level=info msg="created NRI interface" Oct 27 23:46:57.153093 containerd[1988]: time="2025-10-27T23:46:57.152995873Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 27 23:46:57.153093 containerd[1988]: time="2025-10-27T23:46:57.153007553Z" level=info msg="Connect containerd service" Oct 27 23:46:57.153093 containerd[1988]: time="2025-10-27T23:46:57.153037753Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 27 23:46:57.153732 containerd[1988]: time="2025-10-27T23:46:57.153699193Z" 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" Oct 27 23:46:57.163005 containerd[1988]: time="2025-10-27T23:46:57.162949313Z" level=info msg="Start subscribing containerd event" Oct 27 23:46:57.163090 containerd[1988]: time="2025-10-27T23:46:57.163015553Z" level=info msg="Start recovering state" Oct 27 23:46:57.163090 containerd[1988]: time="2025-10-27T23:46:57.163086913Z" level=info msg="Start event monitor" Oct 27 23:46:57.163148 containerd[1988]: time="2025-10-27T23:46:57.163098113Z" level=info msg="Start cni network conf syncer for default" Oct 27 23:46:57.163148 containerd[1988]: time="2025-10-27T23:46:57.163106393Z" level=info msg="Start streaming server" Oct 27 23:46:57.163148 containerd[1988]: time="2025-10-27T23:46:57.163116313Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 27 23:46:57.163148 containerd[1988]: time="2025-10-27T23:46:57.163123313Z" level=info msg="runtime interface starting up..." Oct 27 23:46:57.163148 containerd[1988]: time="2025-10-27T23:46:57.163128833Z" level=info msg="starting plugins..." Oct 27 23:46:57.163148 containerd[1988]: time="2025-10-27T23:46:57.163137353Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 27 23:46:57.163468 containerd[1988]: time="2025-10-27T23:46:57.163433473Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 27 23:46:57.163504 containerd[1988]: time="2025-10-27T23:46:57.163479913Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 27 23:46:57.163566 containerd[1988]: time="2025-10-27T23:46:57.163554513Z" level=info msg="containerd successfully booted in 0.029312s" Oct 27 23:46:57.164047 systemd[1]: Started containerd.service - containerd container runtime. Oct 27 23:46:57.166680 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 27 23:46:57.179512 (dockerd)[2016]: 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 Oct 27 23:46:57.204364 dockerd[2016]: time="2025-10-27T23:46:57.204307353Z" level=info msg="Starting up" Oct 27 23:46:57.205608 dockerd[2016]: time="2025-10-27T23:46:57.205118993Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 27 23:46:57.215078 dockerd[2016]: time="2025-10-27T23:46:57.215040153Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 27 23:46:57.239784 dockerd[2016]: time="2025-10-27T23:46:57.239744033Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Oct 27 23:46:57.249041 dockerd[2016]: time="2025-10-27T23:46:57.248996833Z" level=info msg="Loading containers: start." Oct 27 23:47:01.604034 systemd[1]: Started sshd@14-10.0.0.129:22-10.0.0.1:36844.service - OpenSSH per-connection server daemon (10.0.0.1:36844). Oct 27 23:47:01.650867 sshd[2046]: Accepted publickey for core from 10.0.0.1 port 36844 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:01.652186 sshd-session[2046]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:01.656726 systemd-logind[1397]: New session 15 of user core. Oct 27 23:47:01.666123 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 27 23:47:01.720241 sshd[2049]: Connection closed by 10.0.0.1 port 36844 Oct 27 23:47:01.720616 sshd-session[2046]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:01.743019 systemd[1]: sshd@14-10.0.0.129:22-10.0.0.1:36844.service: Deactivated successfully. Oct 27 23:47:01.744562 systemd[1]: session-15.scope: Deactivated successfully. Oct 27 23:47:01.745556 systemd-logind[1397]: Session 15 logged out. Waiting for processes to exit. Oct 27 23:47:01.747606 systemd[1]: Started sshd@15-10.0.0.129:22-10.0.0.1:36854.service - OpenSSH per-connection server daemon (10.0.0.1:36854). Oct 27 23:47:01.748867 systemd-logind[1397]: Removed session 15. Oct 27 23:47:01.816045 sshd[2055]: Accepted publickey for core from 10.0.0.1 port 36854 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:01.817356 sshd-session[2055]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:01.821463 systemd-logind[1397]: New session 16 of user core. Oct 27 23:47:01.833083 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 27 23:47:01.887864 sshd[2058]: Connection closed by 10.0.0.1 port 36854 Oct 27 23:47:01.887856 sshd-session[2055]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:01.903979 systemd[1]: sshd@15-10.0.0.129:22-10.0.0.1:36854.service: Deactivated successfully. Oct 27 23:47:01.907340 systemd[1]: session-16.scope: Deactivated successfully. Oct 27 23:47:01.910514 systemd-logind[1397]: Session 16 logged out. Waiting for processes to exit. Oct 27 23:47:01.911660 systemd[1]: Started sshd@16-10.0.0.129:22-10.0.0.1:36870.service - OpenSSH per-connection server daemon (10.0.0.1:36870). Oct 27 23:47:01.912532 systemd-logind[1397]: Removed session 16. Oct 27 23:47:01.970165 sshd[2064]: Accepted publickey for core from 10.0.0.1 port 36870 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:01.971585 sshd-session[2064]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:01.975388 systemd-logind[1397]: New session 17 of user core. Oct 27 23:47:01.990107 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 27 23:47:07.274849 dockerd[2016]: time="2025-10-27T23:47:07.274784320Z" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55 Oct 27 23:47:07.287078 systemd[1]: docker-405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55.scope: Deactivated successfully. Oct 27 23:47:07.300838 dockerd[2016]: time="2025-10-27T23:47:07.300772749Z" level=info msg="ignoring event" container=405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 27 23:47:07.301320 containerd[1988]: time="2025-10-27T23:47:07.301286118Z" level=info msg="shim disconnected" id=405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55 namespace=moby Oct 27 23:47:07.301613 containerd[1988]: time="2025-10-27T23:47:07.301320998Z" level=warning msg="cleaning up after shim disconnected" id=405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55 namespace=moby Oct 27 23:47:07.301613 containerd[1988]: time="2025-10-27T23:47:07.301353159Z" level=info msg="cleaning up dead shim" namespace=moby Oct 27 23:47:07.323409 systemd[1]: var-lib-docker-overlay2-68392368ca610d922352bac54106509373fb5678f62472d1a02e2673f808861d-merged.mount: Deactivated successfully. Oct 27 23:47:07.834298 systemd[1]: run-docker-netns-2565c7f9fbae.mount: Deactivated successfully. Oct 27 23:47:07.834992 systemd-networkd[1364]: veth974e1c9: Link DOWN Oct 27 23:47:07.835004 systemd-networkd[1364]: veth974e1c9: Lost carrier Oct 27 23:47:07.835954 kernel: docker0: port 1(veth974e1c9) entered disabled state Oct 27 23:47:07.836982 kernel: veth974e1c9 (unregistering): left allmulticast mode Oct 27 23:47:07.837016 kernel: veth974e1c9 (unregistering): left promiscuous mode Oct 27 23:47:07.838329 kernel: docker0: port 1(veth974e1c9) entered disabled state Oct 27 23:47:07.839770 systemd-networkd[1364]: docker0: Lost carrier Oct 27 23:47:07.842863 dockerd[2016]: time="2025-10-27T23:47:07.842315377Z" level=info msg="Removing stale sandbox 2565c7f9fbaef5918c3ac8647edad031eb369b47c4b230bdf0606a316868ab11 (405792c5c33c615ff24429ca1673a79bab3726129b83becc450ce1bbacbf6a55)" Oct 27 23:47:07.845609 dockerd[2016]: time="2025-10-27T23:47:07.845554430Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Oct 27 23:47:07.845655 dockerd[2016]: time="2025-10-27T23:47:07.845608031Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 0b384ed67da789b656a6b9175749d5ae5a0eea0ff5e4de3646a216eee5557c54 0f5c3f7db10c7b3003b8f88bceedf498ab77bf01027c1cfc1d8dba70e2b4de80], retrying...." Oct 27 23:47:07.848780 systemd[1]: run-docker-netns-2565c7f9fbae.mount: Deactivated successfully. Oct 27 23:47:08.016504 dockerd[2016]: time="2025-10-27T23:47:08.016452878Z" level=warning msg="error locating sandbox id 2565c7f9fbaef5918c3ac8647edad031eb369b47c4b230bdf0606a316868ab11: sandbox 2565c7f9fbaef5918c3ac8647edad031eb369b47c4b230bdf0606a316868ab11 not found" Oct 27 23:47:08.016635 dockerd[2016]: time="2025-10-27T23:47:08.016568759Z" level=info msg="Loading containers: done." Oct 27 23:47:08.029355 dockerd[2016]: time="2025-10-27T23:47:08.029299357Z" 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 Oct 27 23:47:08.029496 dockerd[2016]: time="2025-10-27T23:47:08.029391038Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 27 23:47:08.029496 dockerd[2016]: time="2025-10-27T23:47:08.029425759Z" level=info msg="Initializing buildkit" Oct 27 23:47:08.041152 dockerd[2016]: time="2025-10-27T23:47:08.041089419Z" level=info msg="Completed buildkit initialization" Oct 27 23:47:08.047783 dockerd[2016]: time="2025-10-27T23:47:08.047733922Z" level=info msg="Daemon has completed initialization" Oct 27 23:47:08.047879 dockerd[2016]: time="2025-10-27T23:47:08.047809603Z" level=info msg="API listen on /run/docker.sock" Oct 27 23:47:08.047971 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 27 23:47:08.054670 sshd[2067]: Connection closed by 10.0.0.1 port 36870 Oct 27 23:47:08.055140 sshd-session[2064]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:08.066226 systemd[1]: sshd@16-10.0.0.129:22-10.0.0.1:36870.service: Deactivated successfully. Oct 27 23:47:08.069353 systemd[1]: session-17.scope: Deactivated successfully. Oct 27 23:47:08.070125 systemd-logind[1397]: Session 17 logged out. Waiting for processes to exit. Oct 27 23:47:08.072188 systemd[1]: Started sshd@17-10.0.0.129:22-10.0.0.1:37006.service - OpenSSH per-connection server daemon (10.0.0.1:37006). Oct 27 23:47:08.072720 systemd-logind[1397]: Removed session 17. Oct 27 23:47:08.126851 sshd[2319]: Accepted publickey for core from 10.0.0.1 port 37006 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:08.128305 sshd-session[2319]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:08.132346 systemd-logind[1397]: New session 18 of user core. Oct 27 23:47:08.146081 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 27 23:47:08.350115 containerd[1988]: time="2025-10-27T23:47:08.349115950Z" level=info msg="connecting to shim 77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514" address="unix:///run/containerd/s/571717555c2b9d1642ba5c67eef2119c5e1a732c39c92e8c6d7f3d6721e54bce" namespace=moby protocol=ttrpc version=3 Oct 27 23:47:08.372316 systemd[1]: Started docker-77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514.scope - libcontainer container 77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514. Oct 27 23:47:08.392209 systemd-resolved[1300]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 27 23:47:08.412927 kernel: docker0: port 1(veth5d3d5ed) entered blocking state Oct 27 23:47:08.413032 kernel: docker0: port 1(veth5d3d5ed) entered disabled state Oct 27 23:47:08.413050 kernel: veth5d3d5ed: entered allmulticast mode Oct 27 23:47:08.413070 kernel: veth5d3d5ed: entered promiscuous mode Oct 27 23:47:08.413621 systemd-networkd[1364]: veth5d3d5ed: Link UP Oct 27 23:47:08.419938 kernel: eth0: renamed from veth5e56993 Oct 27 23:47:08.422007 kernel: docker0: port 1(veth5d3d5ed) entered blocking state Oct 27 23:47:08.422296 kernel: docker0: port 1(veth5d3d5ed) entered forwarding state Oct 27 23:47:08.422086 systemd-networkd[1364]: veth5d3d5ed: Gained carrier Oct 27 23:47:08.422655 systemd-networkd[1364]: docker0: Gained carrier Oct 27 23:47:08.441848 sshd[2322]: Connection closed by 10.0.0.1 port 37006 Oct 27 23:47:08.442352 sshd-session[2319]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:08.450786 systemd[1]: sshd@17-10.0.0.129:22-10.0.0.1:37006.service: Deactivated successfully. Oct 27 23:47:08.453035 systemd[1]: session-18.scope: Deactivated successfully. Oct 27 23:47:08.453690 systemd-logind[1397]: Session 18 logged out. Waiting for processes to exit. Oct 27 23:47:08.455527 systemd[1]: Started sshd@18-10.0.0.129:22-10.0.0.1:37022.service - OpenSSH per-connection server daemon (10.0.0.1:37022). Oct 27 23:47:08.456316 systemd-logind[1397]: Removed session 18. Oct 27 23:47:08.510314 sshd[2388]: Accepted publickey for core from 10.0.0.1 port 37022 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:08.511514 sshd-session[2388]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:08.515532 systemd-logind[1397]: New session 19 of user core. Oct 27 23:47:08.523070 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 27 23:47:08.575394 sshd[2391]: Connection closed by 10.0.0.1 port 37022 Oct 27 23:47:08.575708 sshd-session[2388]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:08.597849 systemd[1]: sshd@18-10.0.0.129:22-10.0.0.1:37022.service: Deactivated successfully. Oct 27 23:47:08.599429 systemd[1]: session-19.scope: Deactivated successfully. Oct 27 23:47:08.600927 systemd-logind[1397]: Session 19 logged out. Waiting for processes to exit. Oct 27 23:47:08.602491 systemd[1]: Started sshd@19-10.0.0.129:22-10.0.0.1:37034.service - OpenSSH per-connection server daemon (10.0.0.1:37034). Oct 27 23:47:08.603715 systemd-logind[1397]: Removed session 19. Oct 27 23:47:08.666845 sshd[2397]: Accepted publickey for core from 10.0.0.1 port 37034 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:08.668066 sshd-session[2397]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:08.672252 systemd-logind[1397]: New session 20 of user core. Oct 27 23:47:08.682055 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 27 23:47:08.733556 sudo[2401]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1988 Oct 27 23:47:08.733854 sudo[2401]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 27 23:47:08.737055 sudo[2401]: pam_unix(sudo:session): session closed for user root Oct 27 23:47:08.739198 sshd[2400]: Connection closed by 10.0.0.1 port 37034 Oct 27 23:47:08.739475 sshd-session[2397]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:08.740705 dockerd[2016]: time="2025-10-27T23:47:08.740548373Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Oct 27 23:47:08.740705 dockerd[2016]: time="2025-10-27T23:47:08.740583894Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Oct 27 23:47:08.741216 dockerd[2016]: time="2025-10-27T23:47:08.741177903Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Oct 27 23:47:08.741216 dockerd[2016]: time="2025-10-27T23:47:08.741208743Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Oct 27 23:47:08.747678 systemd[1]: containerd.service: Deactivated successfully. Oct 27 23:47:08.747772 systemd[1]: containerd.service: Unit process 2340 (containerd-shim) remains running after unit stopped. Oct 27 23:47:08.748139 systemd[1]: containerd.service: Consumed 178ms CPU time, 121.8M memory peak. Oct 27 23:47:08.748582 systemd[1]: sshd@19-10.0.0.129:22-10.0.0.1:37034.service: Deactivated successfully. Oct 27 23:47:08.751267 systemd[1]: session-20.scope: Deactivated successfully. Oct 27 23:47:08.752026 systemd-logind[1397]: Session 20 logged out. Waiting for processes to exit. Oct 27 23:47:08.754848 systemd[1]: Started sshd@20-10.0.0.129:22-10.0.0.1:37042.service - OpenSSH per-connection server daemon (10.0.0.1:37042). Oct 27 23:47:08.755407 systemd-logind[1397]: Removed session 20. Oct 27 23:47:08.807021 sshd[2408]: Accepted publickey for core from 10.0.0.1 port 37042 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:08.808287 sshd-session[2408]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:08.812511 systemd-logind[1397]: New session 21 of user core. Oct 27 23:47:08.820076 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 27 23:47:08.872793 sshd[2411]: Connection closed by 10.0.0.1 port 37042 Oct 27 23:47:08.872673 sshd-session[2408]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:08.875879 systemd[1]: sshd@20-10.0.0.129:22-10.0.0.1:37042.service: Deactivated successfully. Oct 27 23:47:08.878800 systemd[1]: session-21.scope: Deactivated successfully. Oct 27 23:47:08.879639 systemd-logind[1397]: Session 21 logged out. Waiting for processes to exit. Oct 27 23:47:08.881642 systemd-logind[1397]: Removed session 21. Oct 27 23:47:09.954107 systemd-networkd[1364]: veth5d3d5ed: Gained IPv6LL Oct 27 23:47:10.957109 update_engine[1398]: I20251027 23:47:10.957037 1398 update_attempter.cc:509] Updating boot flags... Oct 27 23:47:13.837097 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Oct 27 23:47:13.837429 systemd[1]: Stopping docker.service - Docker Application Container Engine... Oct 27 23:47:13.837552 dockerd[2016]: time="2025-10-27T23:47:13.837452086Z" level=info msg="Processing signal 'terminated'" Oct 27 23:47:14.883718 systemd[1]: Started sshd@21-10.0.0.129:22-10.0.0.1:54326.service - OpenSSH per-connection server daemon (10.0.0.1:54326). Oct 27 23:47:14.944882 sshd[2428]: Accepted publickey for core from 10.0.0.1 port 54326 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:14.946303 sshd-session[2428]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:14.950794 systemd-logind[1397]: New session 22 of user core. Oct 27 23:47:14.970091 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 27 23:47:15.024710 sshd[2431]: Connection closed by 10.0.0.1 port 54326 Oct 27 23:47:15.025031 sshd-session[2428]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:15.028274 systemd[1]: sshd@21-10.0.0.129:22-10.0.0.1:54326.service: Deactivated successfully. Oct 27 23:47:15.029775 systemd[1]: session-22.scope: Deactivated successfully. Oct 27 23:47:15.032658 systemd-logind[1397]: Session 22 logged out. Waiting for processes to exit. Oct 27 23:47:15.034542 systemd-logind[1397]: Removed session 22. Oct 27 23:47:15.838939 dockerd[2016]: time="2025-10-27T23:47:15.838685814Z" level=error msg="Error sending stop (signal 15) to container" container=77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514 error="Cannot kill container 77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Oct 27 23:47:15.838939 dockerd[2016]: time="2025-10-27T23:47:15.838764055Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514 Oct 27 23:47:21.037726 systemd[1]: Started sshd@22-10.0.0.129:22-10.0.0.1:52400.service - OpenSSH per-connection server daemon (10.0.0.1:52400). Oct 27 23:47:21.085212 sshd[2437]: Accepted publickey for core from 10.0.0.1 port 52400 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:21.086437 sshd-session[2437]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:21.091176 systemd-logind[1397]: New session 23 of user core. Oct 27 23:47:21.099093 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 27 23:47:21.152403 sshd[2440]: Connection closed by 10.0.0.1 port 52400 Oct 27 23:47:21.152867 sshd-session[2437]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:21.156799 systemd[1]: sshd@22-10.0.0.129:22-10.0.0.1:52400.service: Deactivated successfully. Oct 27 23:47:21.158299 systemd[1]: session-23.scope: Deactivated successfully. Oct 27 23:47:21.158996 systemd-logind[1397]: Session 23 logged out. Waiting for processes to exit. Oct 27 23:47:21.159904 systemd-logind[1397]: Removed session 23. Oct 27 23:47:25.840860 dockerd[2016]: time="2025-10-27T23:47:25.840782933Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514 error="context deadline exceeded" Oct 27 23:47:25.841809 systemd[1]: docker-77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514.scope: Deactivated successfully. Oct 27 23:47:27.178809 systemd[1]: Started sshd@23-10.0.0.129:22-10.0.0.1:52538.service - OpenSSH per-connection server daemon (10.0.0.1:52538). Oct 27 23:47:27.225147 sshd[2446]: Accepted publickey for core from 10.0.0.1 port 52538 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:27.226489 sshd-session[2446]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:27.231417 systemd-logind[1397]: New session 24 of user core. Oct 27 23:47:27.239065 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 27 23:47:27.293583 sshd[2449]: Connection closed by 10.0.0.1 port 52538 Oct 27 23:47:27.294078 sshd-session[2446]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:27.297411 systemd[1]: sshd@23-10.0.0.129:22-10.0.0.1:52538.service: Deactivated successfully. Oct 27 23:47:27.300614 systemd[1]: session-24.scope: Deactivated successfully. Oct 27 23:47:27.301413 systemd-logind[1397]: Session 24 logged out. Waiting for processes to exit. Oct 27 23:47:27.304885 systemd-logind[1397]: Removed session 24. Oct 27 23:47:28.838113 dockerd[2016]: time="2025-10-27T23:47:28.838028736Z" level=error msg="Force shutdown daemon" Oct 27 23:47:28.838465 dockerd[2016]: time="2025-10-27T23:47:28.838169137Z" level=info msg="Daemon shutdown complete" Oct 27 23:47:28.841725 systemd[1]: docker.service: Deactivated successfully. Oct 27 23:47:28.842057 systemd[1]: Stopped docker.service - Docker Application Container Engine. Oct 27 23:47:28.842530 systemd[1]: docker.socket: Deactivated successfully. Oct 27 23:47:28.842809 systemd[1]: Closed docker.socket - Docker Socket for the API. Oct 27 23:47:28.842851 systemd[1]: Stopping docker.socket - Docker Socket for the API... Oct 27 23:47:28.844158 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 27 23:47:28.844318 systemd[1]: containerd.service: Found left-over process 2340 (containerd-shim) in control group while starting unit. Ignoring. Oct 27 23:47:28.844327 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 27 23:47:28.851333 systemd[1]: Starting containerd.service - containerd container runtime... Oct 27 23:47:28.852856 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 27 23:47:28.862543 systemd[1]: containerd.service: Found left-over process 2340 (containerd-shim) in control group while starting unit. Ignoring. Oct 27 23:47:28.862557 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 27 23:47:28.866737 (ntainerd)[2460]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 27 23:47:28.882288 containerd[2460]: time="2025-10-27T23:47:28Z" 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 Oct 27 23:47:28.882796 containerd[2460]: time="2025-10-27T23:47:28.882732127Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 27 23:47:28.892319 containerd[2460]: time="2025-10-27T23:47:28.892266527Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.24µs" Oct 27 23:47:28.892319 containerd[2460]: time="2025-10-27T23:47:28.892304247Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 27 23:47:28.892319 containerd[2460]: time="2025-10-27T23:47:28.892322168Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 27 23:47:28.892460 containerd[2460]: time="2025-10-27T23:47:28.892356728Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 27 23:47:28.892460 containerd[2460]: time="2025-10-27T23:47:28.892368288Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 27 23:47:28.892630 containerd[2460]: time="2025-10-27T23:47:28.892594169Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:47:28.892630 containerd[2460]: time="2025-10-27T23:47:28.892626689Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:47:28.892689 containerd[2460]: time="2025-10-27T23:47:28.892637009Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 27 23:47:28.892895 containerd[2460]: time="2025-10-27T23:47:28.892857370Z" 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 Oct 27 23:47:28.892895 containerd[2460]: time="2025-10-27T23:47:28.892878730Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:47:28.892895 containerd[2460]: time="2025-10-27T23:47:28.892890810Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:47:28.892978 containerd[2460]: time="2025-10-27T23:47:28.892899490Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 27 23:47:28.892978 containerd[2460]: time="2025-10-27T23:47:28.892940250Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 27 23:47:28.893038 containerd[2460]: time="2025-10-27T23:47:28.893022971Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 27 23:47:28.893068 containerd[2460]: time="2025-10-27T23:47:28.893053491Z" 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 Oct 27 23:47:28.893104 containerd[2460]: time="2025-10-27T23:47:28.893072131Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 27 23:47:28.893125 containerd[2460]: time="2025-10-27T23:47:28.893116811Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 27 23:47:28.894067 containerd[2460]: time="2025-10-27T23:47:28.894019895Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 27 23:47:28.894107 containerd[2460]: time="2025-10-27T23:47:28.894086055Z" level=info msg="metadata content store policy set" policy=shared Oct 27 23:47:28.894296 containerd[2460]: time="2025-10-27T23:47:28.894265816Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 27 23:47:28.894327 containerd[2460]: time="2025-10-27T23:47:28.894307896Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 27 23:47:28.894349 containerd[2460]: time="2025-10-27T23:47:28.894329856Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 27 23:47:28.894372 containerd[2460]: time="2025-10-27T23:47:28.894346776Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 27 23:47:28.894372 containerd[2460]: time="2025-10-27T23:47:28.894363856Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 27 23:47:28.894415 containerd[2460]: time="2025-10-27T23:47:28.894378216Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 27 23:47:28.894505 containerd[2460]: time="2025-10-27T23:47:28.894392776Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 27 23:47:28.894505 containerd[2460]: time="2025-10-27T23:47:28.894479977Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 27 23:47:28.894505 containerd[2460]: time="2025-10-27T23:47:28.894495457Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 27 23:47:28.894586 containerd[2460]: time="2025-10-27T23:47:28.894509777Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 27 23:47:28.894586 containerd[2460]: time="2025-10-27T23:47:28.894522697Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 27 23:47:28.894586 containerd[2460]: time="2025-10-27T23:47:28.894536937Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 27 23:47:28.894799 containerd[2460]: time="2025-10-27T23:47:28.894774538Z" level=info msg="connecting to shim 77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514" address="unix:///run/containerd/s/571717555c2b9d1642ba5c67eef2119c5e1a732c39c92e8c6d7f3d6721e54bce" namespace=moby protocol=ttrpc version=3 Oct 27 23:47:28.902068 containerd[2460]: time="2025-10-27T23:47:28.901984329Z" level=info msg="cleaning leaked shim process" id=77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514 namespace=moby Oct 27 23:47:28.910945 containerd[2460]: time="2025-10-27T23:47:28.910826166Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Oct 27 23:47:28.911132 containerd[2460]: time="2025-10-27T23:47:28.911108088Z" level=info msg="shim disconnected" id=77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514 namespace=moby Oct 27 23:47:28.911836 containerd[2460]: time="2025-10-27T23:47:28.911716970Z" level=warning msg="cleaning up after shim disconnected" id=77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514 namespace=moby Oct 27 23:47:28.911836 containerd[2460]: time="2025-10-27T23:47:28.911773130Z" level=info msg="cleaning up dead shim" namespace=moby Oct 27 23:47:28.920871 containerd[2460]: time="2025-10-27T23:47:28.920835209Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 27 23:47:28.921012 containerd[2460]: time="2025-10-27T23:47:28.920994730Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 27 23:47:28.921072 containerd[2460]: time="2025-10-27T23:47:28.921059690Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 27 23:47:28.921143 containerd[2460]: time="2025-10-27T23:47:28.921129450Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 27 23:47:28.921200 containerd[2460]: time="2025-10-27T23:47:28.921187171Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 27 23:47:28.921255 containerd[2460]: time="2025-10-27T23:47:28.921242531Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 27 23:47:28.921309 containerd[2460]: time="2025-10-27T23:47:28.921296491Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 27 23:47:28.921365 containerd[2460]: time="2025-10-27T23:47:28.921353491Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 27 23:47:28.921425 containerd[2460]: time="2025-10-27T23:47:28.921411731Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 27 23:47:28.921483 containerd[2460]: time="2025-10-27T23:47:28.921470052Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 27 23:47:28.921537 containerd[2460]: time="2025-10-27T23:47:28.921525212Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 27 23:47:28.921643 containerd[2460]: time="2025-10-27T23:47:28.921628772Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 27 23:47:28.921713 containerd[2460]: time="2025-10-27T23:47:28.921701853Z" level=info msg="Start snapshots syncer" Oct 27 23:47:28.921790 containerd[2460]: time="2025-10-27T23:47:28.921776293Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 27 23:47:28.922087 containerd[2460]: time="2025-10-27T23:47:28.922050014Z" 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\"}" Oct 27 23:47:28.922249 containerd[2460]: time="2025-10-27T23:47:28.922227935Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 27 23:47:28.922401 containerd[2460]: time="2025-10-27T23:47:28.922385936Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 27 23:47:28.922516 containerd[2460]: time="2025-10-27T23:47:28.922502056Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 27 23:47:28.922576 containerd[2460]: time="2025-10-27T23:47:28.922564936Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 27 23:47:28.922629 containerd[2460]: time="2025-10-27T23:47:28.922617177Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 27 23:47:28.922691 containerd[2460]: time="2025-10-27T23:47:28.922665937Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 27 23:47:28.922758 containerd[2460]: time="2025-10-27T23:47:28.922744857Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 27 23:47:28.922808 containerd[2460]: time="2025-10-27T23:47:28.922796417Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 27 23:47:28.922859 containerd[2460]: time="2025-10-27T23:47:28.922845658Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 27 23:47:28.922942 containerd[2460]: time="2025-10-27T23:47:28.922926898Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 27 23:47:28.922997 containerd[2460]: time="2025-10-27T23:47:28.922984058Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 27 23:47:28.923047 containerd[2460]: time="2025-10-27T23:47:28.923035218Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 27 23:47:28.923151 containerd[2460]: time="2025-10-27T23:47:28.923137899Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 27 23:47:28.923471 containerd[2460]: time="2025-10-27T23:47:28.923451780Z" 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 Oct 27 23:47:28.923537 containerd[2460]: time="2025-10-27T23:47:28.923524180Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:47:28.923586 containerd[2460]: time="2025-10-27T23:47:28.923573461Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:47:28.923635 containerd[2460]: time="2025-10-27T23:47:28.923622981Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 27 23:47:28.923701 containerd[2460]: time="2025-10-27T23:47:28.923685941Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 27 23:47:28.923761 containerd[2460]: time="2025-10-27T23:47:28.923747581Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 27 23:47:28.923821 containerd[2460]: time="2025-10-27T23:47:28.923809142Z" level=info msg="runtime interface created" Oct 27 23:47:28.923867 containerd[2460]: time="2025-10-27T23:47:28.923856782Z" level=info msg="created NRI interface" Oct 27 23:47:28.923931 containerd[2460]: time="2025-10-27T23:47:28.923903822Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 27 23:47:28.923980 containerd[2460]: time="2025-10-27T23:47:28.923969342Z" level=info msg="Connect containerd service" Oct 27 23:47:28.924050 containerd[2460]: time="2025-10-27T23:47:28.924036743Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 27 23:47:28.924630 containerd[2460]: time="2025-10-27T23:47:28.924607425Z" 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" Oct 27 23:47:28.933289 containerd[2460]: time="2025-10-27T23:47:28.933233382Z" level=info msg="Start subscribing containerd event" Oct 27 23:47:28.933347 containerd[2460]: time="2025-10-27T23:47:28.933308182Z" level=info msg="Start recovering state" Oct 27 23:47:28.933407 containerd[2460]: time="2025-10-27T23:47:28.933387262Z" level=info msg="Start event monitor" Oct 27 23:47:28.933407 containerd[2460]: time="2025-10-27T23:47:28.933404223Z" level=info msg="Start cni network conf syncer for default" Oct 27 23:47:28.933469 containerd[2460]: time="2025-10-27T23:47:28.933412623Z" level=info msg="Start streaming server" Oct 27 23:47:28.933469 containerd[2460]: time="2025-10-27T23:47:28.933421623Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 27 23:47:28.933469 containerd[2460]: time="2025-10-27T23:47:28.933428223Z" level=info msg="runtime interface starting up..." Oct 27 23:47:28.933469 containerd[2460]: time="2025-10-27T23:47:28.933434263Z" level=info msg="starting plugins..." Oct 27 23:47:28.933469 containerd[2460]: time="2025-10-27T23:47:28.933442943Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 27 23:47:28.933635 containerd[2460]: time="2025-10-27T23:47:28.933615983Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 27 23:47:28.933752 containerd[2460]: time="2025-10-27T23:47:28.933737664Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 27 23:47:28.933863 containerd[2460]: time="2025-10-27T23:47:28.933850064Z" level=info msg="containerd successfully booted in 0.051748s" Oct 27 23:47:28.933946 systemd[1]: Started containerd.service - containerd container runtime. Oct 27 23:47:28.935292 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 27 23:47:28.953236 (dockerd)[2508]: 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 Oct 27 23:47:28.979849 dockerd[2508]: time="2025-10-27T23:47:28.979803580Z" level=info msg="Starting up" Oct 27 23:47:28.980332 dockerd[2508]: time="2025-10-27T23:47:28.980315342Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 27 23:47:28.990222 dockerd[2508]: time="2025-10-27T23:47:28.990133664Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 27 23:47:29.007404 dockerd[2508]: time="2025-10-27T23:47:29.007353576Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Oct 27 23:47:29.015042 dockerd[2508]: time="2025-10-27T23:47:29.014996286Z" level=info msg="Loading containers: start." Oct 27 23:47:29.031185 systemd[1]: var-lib-docker-overlay2-9f22add784297da8eaa44ee65a43b8e5fac3e58f71a919e37b58b99cdc5f1c6c-merged.mount: Deactivated successfully. Oct 27 23:47:29.372002 kernel: docker0: port 1(veth5d3d5ed) entered disabled state Oct 27 23:47:29.372244 systemd-networkd[1364]: veth5d3d5ed: Link DOWN Oct 27 23:47:29.372250 systemd-networkd[1364]: veth5d3d5ed: Lost carrier Oct 27 23:47:29.374279 kernel: veth5d3d5ed (unregistering): left allmulticast mode Oct 27 23:47:29.374328 kernel: veth5d3d5ed (unregistering): left promiscuous mode Oct 27 23:47:29.374344 kernel: docker0: port 1(veth5d3d5ed) entered disabled state Oct 27 23:47:29.375852 systemd-networkd[1364]: docker0: Lost carrier Oct 27 23:47:29.376653 dockerd[2508]: time="2025-10-27T23:47:29.376611251Z" level=info msg="Removing stale sandbox fde9bee902d0c6b3cc38471e9465df2347e961d72222025d2ff006fbdb0ea7bf (77832cb516e6c699cafca397b99d6ce3bf23c98843d313fd37d64602a9e75514)" Oct 27 23:47:29.380132 dockerd[2508]: time="2025-10-27T23:47:29.380101745Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Oct 27 23:47:29.380208 dockerd[2508]: time="2025-10-27T23:47:29.380148545Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 17b2fb95cee9d339e96573fb138e49f3640b58fb61b211a923db97d57361725d 492c8a81203cd91f1779d9cec09b7ed69611022f9ec06a9ba7a424b2a273471a], retrying...." Oct 27 23:47:29.546831 dockerd[2508]: time="2025-10-27T23:47:29.546780410Z" level=warning msg="error locating sandbox id fde9bee902d0c6b3cc38471e9465df2347e961d72222025d2ff006fbdb0ea7bf: sandbox fde9bee902d0c6b3cc38471e9465df2347e961d72222025d2ff006fbdb0ea7bf not found" Oct 27 23:47:29.546831 dockerd[2508]: time="2025-10-27T23:47:29.546824091Z" level=warning msg="error locating sandbox id 2565c7f9fbaef5918c3ac8647edad031eb369b47c4b230bdf0606a316868ab11: sandbox 2565c7f9fbaef5918c3ac8647edad031eb369b47c4b230bdf0606a316868ab11 not found" Oct 27 23:47:29.546982 dockerd[2508]: time="2025-10-27T23:47:29.546931811Z" level=info msg="Loading containers: done." Oct 27 23:47:29.557141 dockerd[2508]: time="2025-10-27T23:47:29.557085812Z" 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 Oct 27 23:47:29.557288 dockerd[2508]: time="2025-10-27T23:47:29.557160892Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 27 23:47:29.557288 dockerd[2508]: time="2025-10-27T23:47:29.557194132Z" level=info msg="Initializing buildkit" Oct 27 23:47:29.568851 dockerd[2508]: time="2025-10-27T23:47:29.568818899Z" level=info msg="Completed buildkit initialization" Oct 27 23:47:29.573595 dockerd[2508]: time="2025-10-27T23:47:29.573563677Z" level=info msg="Daemon has completed initialization" Oct 27 23:47:29.573733 dockerd[2508]: time="2025-10-27T23:47:29.573696718Z" level=info msg="API listen on /run/docker.sock" Oct 27 23:47:29.573801 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 27 23:47:29.997088 systemd[1]: run-docker-netns-fde9bee902d0.mount: Deactivated successfully. Oct 27 23:47:33.307714 systemd[1]: Started sshd@24-10.0.0.129:22-10.0.0.1:35558.service - OpenSSH per-connection server daemon (10.0.0.1:35558). Oct 27 23:47:33.371126 sshd[2743]: Accepted publickey for core from 10.0.0.1 port 35558 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:33.372432 sshd-session[2743]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:33.376114 systemd-logind[1397]: New session 25 of user core. Oct 27 23:47:33.388064 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 27 23:47:33.440503 sshd[2746]: Connection closed by 10.0.0.1 port 35558 Oct 27 23:47:33.440826 sshd-session[2743]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:33.454775 systemd[1]: sshd@24-10.0.0.129:22-10.0.0.1:35558.service: Deactivated successfully. Oct 27 23:47:33.457358 systemd[1]: session-25.scope: Deactivated successfully. Oct 27 23:47:33.458583 systemd-logind[1397]: Session 25 logged out. Waiting for processes to exit. Oct 27 23:47:33.461113 systemd[1]: Started sshd@25-10.0.0.129:22-10.0.0.1:35564.service - OpenSSH per-connection server daemon (10.0.0.1:35564). Oct 27 23:47:33.462817 systemd-logind[1397]: Removed session 25. Oct 27 23:47:33.520564 sshd[2752]: Accepted publickey for core from 10.0.0.1 port 35564 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:33.521901 sshd-session[2752]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:33.526973 systemd-logind[1397]: New session 26 of user core. Oct 27 23:47:33.543130 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 27 23:47:33.596339 sshd[2755]: Connection closed by 10.0.0.1 port 35564 Oct 27 23:47:33.596794 sshd-session[2752]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:33.609854 systemd[1]: sshd@25-10.0.0.129:22-10.0.0.1:35564.service: Deactivated successfully. Oct 27 23:47:33.611248 systemd[1]: session-26.scope: Deactivated successfully. Oct 27 23:47:33.611971 systemd-logind[1397]: Session 26 logged out. Waiting for processes to exit. Oct 27 23:47:33.615017 systemd[1]: Started sshd@26-10.0.0.129:22-10.0.0.1:35570.service - OpenSSH per-connection server daemon (10.0.0.1:35570). Oct 27 23:47:33.615934 systemd-logind[1397]: Removed session 26. Oct 27 23:47:33.674936 sshd[2761]: Accepted publickey for core from 10.0.0.1 port 35570 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:33.676060 sshd-session[2761]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:33.680043 systemd-logind[1397]: New session 27 of user core. Oct 27 23:47:33.691146 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 27 23:47:33.749726 sshd[2764]: Connection closed by 10.0.0.1 port 35570 Oct 27 23:47:33.750138 sshd-session[2761]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:33.766847 systemd[1]: sshd@26-10.0.0.129:22-10.0.0.1:35570.service: Deactivated successfully. Oct 27 23:47:33.770133 systemd[1]: session-27.scope: Deactivated successfully. Oct 27 23:47:33.770760 systemd-logind[1397]: Session 27 logged out. Waiting for processes to exit. Oct 27 23:47:33.772615 systemd[1]: Started sshd@27-10.0.0.129:22-10.0.0.1:35582.service - OpenSSH per-connection server daemon (10.0.0.1:35582). Oct 27 23:47:33.773500 systemd-logind[1397]: Removed session 27. Oct 27 23:47:33.840354 sshd[2777]: Accepted publickey for core from 10.0.0.1 port 35582 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:47:33.841569 sshd-session[2777]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:47:33.845975 systemd-logind[1397]: New session 28 of user core. Oct 27 23:47:33.856076 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 27 23:47:33.959185 containerd[2460]: time="2025-10-27T23:47:33.959143752Z" level=info msg="connecting to shim 3857803d7f0acf12c014ca570ff2b7a101d70d2159c4947d84a9f0122a10a25a" address="unix:///run/containerd/s/d544a52a0c0fee1d5a9fb448bff7d19f39f9c34f9799b593ecdbdadb83efb1b8" namespace=moby protocol=ttrpc version=3 Oct 27 23:47:33.992162 systemd[1]: Started docker-3857803d7f0acf12c014ca570ff2b7a101d70d2159c4947d84a9f0122a10a25a.scope - libcontainer container 3857803d7f0acf12c014ca570ff2b7a101d70d2159c4947d84a9f0122a10a25a. Oct 27 23:47:34.003425 systemd-resolved[1300]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 27 23:47:34.022332 kernel: docker0: port 1(veth8791d26) entered blocking state Oct 27 23:47:34.022425 kernel: docker0: port 1(veth8791d26) entered disabled state Oct 27 23:47:34.022442 kernel: veth8791d26: entered allmulticast mode Oct 27 23:47:34.022457 kernel: veth8791d26: entered promiscuous mode Oct 27 23:47:34.023161 systemd-networkd[1364]: veth8791d26: Link UP Oct 27 23:47:34.029936 kernel: eth0: renamed from veth20382a1 Oct 27 23:47:34.032190 kernel: docker0: port 1(veth8791d26) entered blocking state Oct 27 23:47:34.032373 kernel: docker0: port 1(veth8791d26) entered forwarding state Oct 27 23:47:34.032195 systemd-networkd[1364]: veth8791d26: Gained carrier Oct 27 23:47:34.032389 systemd-networkd[1364]: docker0: Gained carrier Oct 27 23:47:34.050004 sshd[2780]: Connection closed by 10.0.0.1 port 35582 Oct 27 23:47:34.050315 sshd-session[2777]: pam_unix(sshd:session): session closed for user core Oct 27 23:47:34.053462 systemd[1]: sshd@27-10.0.0.129:22-10.0.0.1:35582.service: Deactivated successfully. Oct 27 23:47:34.055086 systemd[1]: session-28.scope: Deactivated successfully. Oct 27 23:47:34.056601 systemd-logind[1397]: Session 28 logged out. Waiting for processes to exit. Oct 27 23:47:34.058033 systemd-logind[1397]: Removed session 28. Oct 27 23:47:35.234155 systemd-networkd[1364]: veth8791d26: Gained IPv6LL