Jul 15 11:04:10.729499 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 15 11:04:10.729519 kernel: Linux version 5.15.188-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Tue Jul 15 10:06:30 -00 2025 Jul 15 11:04:10.729527 kernel: efi: EFI v2.70 by EDK II Jul 15 11:04:10.729533 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Jul 15 11:04:10.729538 kernel: random: crng init done Jul 15 11:04:10.729544 kernel: ACPI: Early table checksum verification disabled Jul 15 11:04:10.729551 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Jul 15 11:04:10.729557 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 15 11:04:10.729563 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729568 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729574 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729579 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729585 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729590 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729598 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729604 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729610 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 11:04:10.729616 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 15 11:04:10.729622 kernel: NUMA: Failed to initialise from firmware Jul 15 11:04:10.729628 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 15 11:04:10.729634 kernel: NUMA: NODE_DATA [mem 0xdcb09900-0xdcb0efff] Jul 15 11:04:10.729639 kernel: Zone ranges: Jul 15 11:04:10.729645 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 15 11:04:10.729652 kernel: DMA32 empty Jul 15 11:04:10.729658 kernel: Normal empty Jul 15 11:04:10.729664 kernel: Movable zone start for each node Jul 15 11:04:10.729669 kernel: Early memory node ranges Jul 15 11:04:10.729675 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Jul 15 11:04:10.729681 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Jul 15 11:04:10.729687 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Jul 15 11:04:10.729693 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Jul 15 11:04:10.729698 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Jul 15 11:04:10.729704 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Jul 15 11:04:10.729710 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Jul 15 11:04:10.729716 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 15 11:04:10.729723 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 15 11:04:10.729729 kernel: psci: probing for conduit method from ACPI. Jul 15 11:04:10.729735 kernel: psci: PSCIv1.1 detected in firmware. Jul 15 11:04:10.729741 kernel: psci: Using standard PSCI v0.2 function IDs Jul 15 11:04:10.729747 kernel: psci: Trusted OS migration not required Jul 15 11:04:10.729755 kernel: psci: SMC Calling Convention v1.1 Jul 15 11:04:10.731412 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 15 11:04:10.731436 kernel: ACPI: SRAT not present Jul 15 11:04:10.731443 kernel: percpu: Embedded 30 pages/cpu s82968 r8192 d31720 u122880 Jul 15 11:04:10.731450 kernel: pcpu-alloc: s82968 r8192 d31720 u122880 alloc=30*4096 Jul 15 11:04:10.731457 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 15 11:04:10.731463 kernel: Detected PIPT I-cache on CPU0 Jul 15 11:04:10.731469 kernel: CPU features: detected: GIC system register CPU interface Jul 15 11:04:10.731475 kernel: CPU features: detected: Hardware dirty bit management Jul 15 11:04:10.731481 kernel: CPU features: detected: Spectre-v4 Jul 15 11:04:10.731487 kernel: CPU features: detected: Spectre-BHB Jul 15 11:04:10.731495 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 15 11:04:10.731501 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 15 11:04:10.731507 kernel: CPU features: detected: ARM erratum 1418040 Jul 15 11:04:10.731513 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 15 11:04:10.731519 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Jul 15 11:04:10.731525 kernel: Policy zone: DMA Jul 15 11:04:10.731533 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=66cb9a8d6ebbbd62ba3e197b019773f14f902d0ee05716ff2fc41a726e431e67 Jul 15 11:04:10.731540 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 15 11:04:10.731546 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 15 11:04:10.731552 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 15 11:04:10.731559 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 15 11:04:10.731566 kernel: Memory: 2457332K/2572288K available (9792K kernel code, 2094K rwdata, 7588K rodata, 36416K init, 777K bss, 114956K reserved, 0K cma-reserved) Jul 15 11:04:10.731573 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 15 11:04:10.731579 kernel: trace event string verifier disabled Jul 15 11:04:10.731585 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 15 11:04:10.731591 kernel: rcu: RCU event tracing is enabled. Jul 15 11:04:10.731597 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 15 11:04:10.731603 kernel: Trampoline variant of Tasks RCU enabled. Jul 15 11:04:10.731610 kernel: Tracing variant of Tasks RCU enabled. Jul 15 11:04:10.731616 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 15 11:04:10.731622 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 15 11:04:10.731628 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 15 11:04:10.731635 kernel: GICv3: 256 SPIs implemented Jul 15 11:04:10.731641 kernel: GICv3: 0 Extended SPIs implemented Jul 15 11:04:10.731647 kernel: GICv3: Distributor has no Range Selector support Jul 15 11:04:10.731653 kernel: Root IRQ handler: gic_handle_irq Jul 15 11:04:10.731659 kernel: GICv3: 16 PPIs implemented Jul 15 11:04:10.731665 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 15 11:04:10.731671 kernel: ACPI: SRAT not present Jul 15 11:04:10.731677 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 15 11:04:10.731683 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Jul 15 11:04:10.731689 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Jul 15 11:04:10.731695 kernel: GICv3: using LPI property table @0x00000000400d0000 Jul 15 11:04:10.731701 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Jul 15 11:04:10.731709 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 11:04:10.731715 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 15 11:04:10.731721 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 15 11:04:10.731727 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 15 11:04:10.731733 kernel: arm-pv: using stolen time PV Jul 15 11:04:10.731740 kernel: Console: colour dummy device 80x25 Jul 15 11:04:10.731746 kernel: ACPI: Core revision 20210730 Jul 15 11:04:10.731752 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 15 11:04:10.731759 kernel: pid_max: default: 32768 minimum: 301 Jul 15 11:04:10.731765 kernel: LSM: Security Framework initializing Jul 15 11:04:10.731772 kernel: SELinux: Initializing. Jul 15 11:04:10.731779 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 15 11:04:10.731785 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 15 11:04:10.731791 kernel: rcu: Hierarchical SRCU implementation. Jul 15 11:04:10.731798 kernel: Platform MSI: ITS@0x8080000 domain created Jul 15 11:04:10.731804 kernel: PCI/MSI: ITS@0x8080000 domain created Jul 15 11:04:10.731810 kernel: Remapping and enabling EFI services. Jul 15 11:04:10.731816 kernel: smp: Bringing up secondary CPUs ... Jul 15 11:04:10.731822 kernel: Detected PIPT I-cache on CPU1 Jul 15 11:04:10.731856 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 15 11:04:10.731863 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Jul 15 11:04:10.731870 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 11:04:10.731876 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 15 11:04:10.731882 kernel: Detected PIPT I-cache on CPU2 Jul 15 11:04:10.731889 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 15 11:04:10.731895 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Jul 15 11:04:10.731901 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 11:04:10.731907 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 15 11:04:10.731914 kernel: Detected PIPT I-cache on CPU3 Jul 15 11:04:10.731921 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 15 11:04:10.731928 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Jul 15 11:04:10.731934 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 11:04:10.731940 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 15 11:04:10.731951 kernel: smp: Brought up 1 node, 4 CPUs Jul 15 11:04:10.731959 kernel: SMP: Total of 4 processors activated. Jul 15 11:04:10.731966 kernel: CPU features: detected: 32-bit EL0 Support Jul 15 11:04:10.731972 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 15 11:04:10.731980 kernel: CPU features: detected: Common not Private translations Jul 15 11:04:10.731986 kernel: CPU features: detected: CRC32 instructions Jul 15 11:04:10.731993 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 15 11:04:10.731999 kernel: CPU features: detected: LSE atomic instructions Jul 15 11:04:10.732007 kernel: CPU features: detected: Privileged Access Never Jul 15 11:04:10.732014 kernel: CPU features: detected: RAS Extension Support Jul 15 11:04:10.732020 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 15 11:04:10.732027 kernel: CPU: All CPU(s) started at EL1 Jul 15 11:04:10.732033 kernel: alternatives: patching kernel code Jul 15 11:04:10.732041 kernel: devtmpfs: initialized Jul 15 11:04:10.732047 kernel: KASLR enabled Jul 15 11:04:10.732054 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 15 11:04:10.732061 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 15 11:04:10.732067 kernel: pinctrl core: initialized pinctrl subsystem Jul 15 11:04:10.732074 kernel: SMBIOS 3.0.0 present. Jul 15 11:04:10.732080 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Jul 15 11:04:10.732087 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 15 11:04:10.732093 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 15 11:04:10.732102 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 15 11:04:10.732108 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 15 11:04:10.732115 kernel: audit: initializing netlink subsys (disabled) Jul 15 11:04:10.732122 kernel: audit: type=2000 audit(0.033:1): state=initialized audit_enabled=0 res=1 Jul 15 11:04:10.732128 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 15 11:04:10.732134 kernel: cpuidle: using governor menu Jul 15 11:04:10.732141 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 15 11:04:10.732148 kernel: ASID allocator initialised with 32768 entries Jul 15 11:04:10.732154 kernel: ACPI: bus type PCI registered Jul 15 11:04:10.732162 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 15 11:04:10.732168 kernel: Serial: AMBA PL011 UART driver Jul 15 11:04:10.732175 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Jul 15 11:04:10.732182 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Jul 15 11:04:10.732189 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Jul 15 11:04:10.732195 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Jul 15 11:04:10.732202 kernel: cryptd: max_cpu_qlen set to 1000 Jul 15 11:04:10.732209 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 15 11:04:10.732215 kernel: ACPI: Added _OSI(Module Device) Jul 15 11:04:10.732223 kernel: ACPI: Added _OSI(Processor Device) Jul 15 11:04:10.732230 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 15 11:04:10.732236 kernel: ACPI: Added _OSI(Linux-Dell-Video) Jul 15 11:04:10.732243 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Jul 15 11:04:10.732249 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Jul 15 11:04:10.732256 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 15 11:04:10.732263 kernel: ACPI: Interpreter enabled Jul 15 11:04:10.732269 kernel: ACPI: Using GIC for interrupt routing Jul 15 11:04:10.732276 kernel: ACPI: MCFG table detected, 1 entries Jul 15 11:04:10.732284 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 15 11:04:10.732290 kernel: printk: console [ttyAMA0] enabled Jul 15 11:04:10.732297 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 15 11:04:10.732439 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 15 11:04:10.732502 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 15 11:04:10.732559 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 15 11:04:10.732617 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 15 11:04:10.732677 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 15 11:04:10.732686 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 15 11:04:10.732693 kernel: PCI host bridge to bus 0000:00 Jul 15 11:04:10.732759 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 15 11:04:10.732812 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 15 11:04:10.732887 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 15 11:04:10.732940 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 15 11:04:10.733022 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Jul 15 11:04:10.733092 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Jul 15 11:04:10.733151 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Jul 15 11:04:10.733232 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Jul 15 11:04:10.733296 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Jul 15 11:04:10.733354 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Jul 15 11:04:10.733412 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Jul 15 11:04:10.733473 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Jul 15 11:04:10.733525 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 15 11:04:10.733577 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 15 11:04:10.733629 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 15 11:04:10.733638 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 15 11:04:10.733645 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 15 11:04:10.733652 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 15 11:04:10.733660 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 15 11:04:10.733667 kernel: iommu: Default domain type: Translated Jul 15 11:04:10.733674 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 15 11:04:10.733680 kernel: vgaarb: loaded Jul 15 11:04:10.733687 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 15 11:04:10.733693 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 15 11:04:10.733700 kernel: PTP clock support registered Jul 15 11:04:10.733706 kernel: Registered efivars operations Jul 15 11:04:10.733713 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 15 11:04:10.733720 kernel: VFS: Disk quotas dquot_6.6.0 Jul 15 11:04:10.733728 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 15 11:04:10.733734 kernel: pnp: PnP ACPI init Jul 15 11:04:10.733804 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 15 11:04:10.733813 kernel: pnp: PnP ACPI: found 1 devices Jul 15 11:04:10.733820 kernel: NET: Registered PF_INET protocol family Jul 15 11:04:10.733827 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 15 11:04:10.733874 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 15 11:04:10.733881 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 15 11:04:10.733891 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 15 11:04:10.733897 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Jul 15 11:04:10.733905 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 15 11:04:10.733911 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 15 11:04:10.733918 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 15 11:04:10.733924 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 15 11:04:10.733931 kernel: PCI: CLS 0 bytes, default 64 Jul 15 11:04:10.733938 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Jul 15 11:04:10.733944 kernel: kvm [1]: HYP mode not available Jul 15 11:04:10.733952 kernel: Initialise system trusted keyrings Jul 15 11:04:10.733959 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 15 11:04:10.733965 kernel: Key type asymmetric registered Jul 15 11:04:10.733978 kernel: Asymmetric key parser 'x509' registered Jul 15 11:04:10.733986 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 15 11:04:10.733992 kernel: io scheduler mq-deadline registered Jul 15 11:04:10.733999 kernel: io scheduler kyber registered Jul 15 11:04:10.734005 kernel: io scheduler bfq registered Jul 15 11:04:10.734012 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 15 11:04:10.734020 kernel: ACPI: button: Power Button [PWRB] Jul 15 11:04:10.734027 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 15 11:04:10.734098 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 15 11:04:10.734107 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 15 11:04:10.734114 kernel: thunder_xcv, ver 1.0 Jul 15 11:04:10.734120 kernel: thunder_bgx, ver 1.0 Jul 15 11:04:10.734127 kernel: nicpf, ver 1.0 Jul 15 11:04:10.734133 kernel: nicvf, ver 1.0 Jul 15 11:04:10.734197 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 15 11:04:10.734253 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-15T11:04:10 UTC (1752577450) Jul 15 11:04:10.734262 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 15 11:04:10.734268 kernel: NET: Registered PF_INET6 protocol family Jul 15 11:04:10.734275 kernel: Segment Routing with IPv6 Jul 15 11:04:10.734282 kernel: In-situ OAM (IOAM) with IPv6 Jul 15 11:04:10.734288 kernel: NET: Registered PF_PACKET protocol family Jul 15 11:04:10.734295 kernel: Key type dns_resolver registered Jul 15 11:04:10.734301 kernel: registered taskstats version 1 Jul 15 11:04:10.734309 kernel: Loading compiled-in X.509 certificates Jul 15 11:04:10.734316 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.188-flatcar: 1835a6fea2ba29f82433ea6fde09cb345fc75fe9' Jul 15 11:04:10.734323 kernel: Key type .fscrypt registered Jul 15 11:04:10.734329 kernel: Key type fscrypt-provisioning registered Jul 15 11:04:10.734336 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 15 11:04:10.734342 kernel: ima: Allocated hash algorithm: sha1 Jul 15 11:04:10.734349 kernel: ima: No architecture policies found Jul 15 11:04:10.734355 kernel: clk: Disabling unused clocks Jul 15 11:04:10.734362 kernel: Freeing unused kernel memory: 36416K Jul 15 11:04:10.734369 kernel: Run /init as init process Jul 15 11:04:10.734376 kernel: with arguments: Jul 15 11:04:10.734382 kernel: /init Jul 15 11:04:10.734388 kernel: with environment: Jul 15 11:04:10.734395 kernel: HOME=/ Jul 15 11:04:10.734401 kernel: TERM=linux Jul 15 11:04:10.734407 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 15 11:04:10.734416 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Jul 15 11:04:10.734426 systemd[1]: Detected virtualization kvm. Jul 15 11:04:10.734433 systemd[1]: Detected architecture arm64. Jul 15 11:04:10.734440 systemd[1]: Running in initrd. Jul 15 11:04:10.734447 systemd[1]: No hostname configured, using default hostname. Jul 15 11:04:10.734454 systemd[1]: Hostname set to . Jul 15 11:04:10.734461 systemd[1]: Initializing machine ID from VM UUID. Jul 15 11:04:10.734468 systemd[1]: Queued start job for default target initrd.target. Jul 15 11:04:10.734475 systemd[1]: Started systemd-ask-password-console.path. Jul 15 11:04:10.734483 systemd[1]: Reached target cryptsetup.target. Jul 15 11:04:10.734490 systemd[1]: Reached target paths.target. Jul 15 11:04:10.734497 systemd[1]: Reached target slices.target. Jul 15 11:04:10.734504 systemd[1]: Reached target swap.target. Jul 15 11:04:10.734511 systemd[1]: Reached target timers.target. Jul 15 11:04:10.734518 systemd[1]: Listening on iscsid.socket. Jul 15 11:04:10.734525 systemd[1]: Listening on iscsiuio.socket. Jul 15 11:04:10.734534 systemd[1]: Listening on systemd-journald-audit.socket. Jul 15 11:04:10.734541 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 15 11:04:10.734548 systemd[1]: Listening on systemd-journald.socket. Jul 15 11:04:10.734555 systemd[1]: Listening on systemd-networkd.socket. Jul 15 11:04:10.734562 systemd[1]: Listening on systemd-udevd-control.socket. Jul 15 11:04:10.734569 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 15 11:04:10.734576 systemd[1]: Reached target sockets.target. Jul 15 11:04:10.734583 systemd[1]: Starting kmod-static-nodes.service... Jul 15 11:04:10.734590 systemd[1]: Finished network-cleanup.service. Jul 15 11:04:10.734599 systemd[1]: Starting systemd-fsck-usr.service... Jul 15 11:04:10.734606 systemd[1]: Starting systemd-journald.service... Jul 15 11:04:10.734613 systemd[1]: Starting systemd-modules-load.service... Jul 15 11:04:10.734620 systemd[1]: Starting systemd-resolved.service... Jul 15 11:04:10.734626 systemd[1]: Starting systemd-vconsole-setup.service... Jul 15 11:04:10.734633 systemd[1]: Finished kmod-static-nodes.service. Jul 15 11:04:10.734640 systemd[1]: Finished systemd-fsck-usr.service. Jul 15 11:04:10.734647 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 15 11:04:10.734654 systemd[1]: Finished systemd-vconsole-setup.service. Jul 15 11:04:10.734662 systemd[1]: Starting dracut-cmdline-ask.service... Jul 15 11:04:10.734669 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 15 11:04:10.734677 kernel: audit: type=1130 audit(1752577450.731:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.734687 systemd-journald[289]: Journal started Jul 15 11:04:10.734726 systemd-journald[289]: Runtime Journal (/run/log/journal/f12e6760a54b4ce0840805c05e989ce7) is 6.0M, max 48.7M, 42.6M free. Jul 15 11:04:10.731000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.728336 systemd-modules-load[290]: Inserted module 'overlay' Jul 15 11:04:10.736034 systemd[1]: Started systemd-journald.service. Jul 15 11:04:10.735000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.738856 kernel: audit: type=1130 audit(1752577450.735:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.747736 systemd[1]: Finished dracut-cmdline-ask.service. Jul 15 11:04:10.747000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.750769 systemd-resolved[291]: Positive Trust Anchors: Jul 15 11:04:10.751989 kernel: audit: type=1130 audit(1752577450.747:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.750786 systemd-resolved[291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 15 11:04:10.750814 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Jul 15 11:04:10.759318 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 15 11:04:10.750922 systemd[1]: Starting dracut-cmdline.service... Jul 15 11:04:10.755181 systemd-resolved[291]: Defaulting to hostname 'linux'. Jul 15 11:04:10.758000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.755972 systemd[1]: Started systemd-resolved.service. Jul 15 11:04:10.764123 kernel: audit: type=1130 audit(1752577450.758:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.764140 kernel: Bridge firewalling registered Jul 15 11:04:10.759943 systemd[1]: Reached target nss-lookup.target. Jul 15 11:04:10.764143 systemd-modules-load[290]: Inserted module 'br_netfilter' Jul 15 11:04:10.768894 dracut-cmdline[308]: dracut-dracut-053 Jul 15 11:04:10.771668 dracut-cmdline[308]: Using kernel command line parameters: rd.driver.pre=btrfs 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=66cb9a8d6ebbbd62ba3e197b019773f14f902d0ee05716ff2fc41a726e431e67 Jul 15 11:04:10.777878 kernel: SCSI subsystem initialized Jul 15 11:04:10.785235 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 15 11:04:10.785265 kernel: device-mapper: uevent: version 1.0.3 Jul 15 11:04:10.786039 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Jul 15 11:04:10.788939 systemd-modules-load[290]: Inserted module 'dm_multipath' Jul 15 11:04:10.789000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.789914 systemd[1]: Finished systemd-modules-load.service. Jul 15 11:04:10.793739 kernel: audit: type=1130 audit(1752577450.789:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.791410 systemd[1]: Starting systemd-sysctl.service... Jul 15 11:04:10.800372 systemd[1]: Finished systemd-sysctl.service. Jul 15 11:04:10.800000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.803871 kernel: audit: type=1130 audit(1752577450.800:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.843859 kernel: Loading iSCSI transport class v2.0-870. Jul 15 11:04:10.857864 kernel: iscsi: registered transport (tcp) Jul 15 11:04:10.874861 kernel: iscsi: registered transport (qla4xxx) Jul 15 11:04:10.874883 kernel: QLogic iSCSI HBA Driver Jul 15 11:04:10.909814 systemd[1]: Finished dracut-cmdline.service. Jul 15 11:04:10.909000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.911297 systemd[1]: Starting dracut-pre-udev.service... Jul 15 11:04:10.913457 kernel: audit: type=1130 audit(1752577450.909:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:10.957800 kernel: raid6: neonx8 gen() 13710 MB/s Jul 15 11:04:10.973880 kernel: raid6: neonx8 xor() 10751 MB/s Jul 15 11:04:10.990878 kernel: raid6: neonx4 gen() 13521 MB/s Jul 15 11:04:11.007874 kernel: raid6: neonx4 xor() 11143 MB/s Jul 15 11:04:11.024873 kernel: raid6: neonx2 gen() 12953 MB/s Jul 15 11:04:11.041873 kernel: raid6: neonx2 xor() 10264 MB/s Jul 15 11:04:11.058865 kernel: raid6: neonx1 gen() 10574 MB/s Jul 15 11:04:11.075869 kernel: raid6: neonx1 xor() 8807 MB/s Jul 15 11:04:11.092878 kernel: raid6: int64x8 gen() 6269 MB/s Jul 15 11:04:11.109875 kernel: raid6: int64x8 xor() 3540 MB/s Jul 15 11:04:11.126869 kernel: raid6: int64x4 gen() 7212 MB/s Jul 15 11:04:11.143871 kernel: raid6: int64x4 xor() 3854 MB/s Jul 15 11:04:11.160866 kernel: raid6: int64x2 gen() 6150 MB/s Jul 15 11:04:11.177918 kernel: raid6: int64x2 xor() 3320 MB/s Jul 15 11:04:11.194874 kernel: raid6: int64x1 gen() 5043 MB/s Jul 15 11:04:11.212158 kernel: raid6: int64x1 xor() 2644 MB/s Jul 15 11:04:11.212188 kernel: raid6: using algorithm neonx8 gen() 13710 MB/s Jul 15 11:04:11.212197 kernel: raid6: .... xor() 10751 MB/s, rmw enabled Jul 15 11:04:11.212206 kernel: raid6: using neon recovery algorithm Jul 15 11:04:11.224202 kernel: xor: measuring software checksum speed Jul 15 11:04:11.224241 kernel: 8regs : 17152 MB/sec Jul 15 11:04:11.224251 kernel: 32regs : 20717 MB/sec Jul 15 11:04:11.225132 kernel: arm64_neon : 27757 MB/sec Jul 15 11:04:11.225147 kernel: xor: using function: arm64_neon (27757 MB/sec) Jul 15 11:04:11.281873 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Jul 15 11:04:11.291991 systemd[1]: Finished dracut-pre-udev.service. Jul 15 11:04:11.291000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:11.294000 audit: BPF prog-id=7 op=LOAD Jul 15 11:04:11.294000 audit: BPF prog-id=8 op=LOAD Jul 15 11:04:11.295856 kernel: audit: type=1130 audit(1752577451.291:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:11.295877 kernel: audit: type=1334 audit(1752577451.294:10): prog-id=7 op=LOAD Jul 15 11:04:11.300501 systemd[1]: Starting systemd-udevd.service... Jul 15 11:04:11.317069 systemd-udevd[492]: Using default interface naming scheme 'v252'. Jul 15 11:04:11.331685 systemd[1]: Started systemd-udevd.service. Jul 15 11:04:11.332000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:11.333190 systemd[1]: Starting dracut-pre-trigger.service... Jul 15 11:04:11.344849 dracut-pre-trigger[512]: rd.md=0: removing MD RAID activation Jul 15 11:04:11.373573 systemd[1]: Finished dracut-pre-trigger.service. Jul 15 11:04:11.374000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:11.375053 systemd[1]: Starting systemd-udev-trigger.service... Jul 15 11:04:11.410461 systemd[1]: Finished systemd-udev-trigger.service. Jul 15 11:04:11.411000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:11.442406 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 15 11:04:11.447094 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 15 11:04:11.447110 kernel: GPT:9289727 != 19775487 Jul 15 11:04:11.447118 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 15 11:04:11.447134 kernel: GPT:9289727 != 19775487 Jul 15 11:04:11.447147 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 15 11:04:11.447155 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 15 11:04:11.456879 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by (udev-worker) (544) Jul 15 11:04:11.461362 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Jul 15 11:04:11.464737 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Jul 15 11:04:11.472152 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Jul 15 11:04:11.472912 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Jul 15 11:04:11.477020 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 15 11:04:11.479466 systemd[1]: Starting disk-uuid.service... Jul 15 11:04:11.486135 disk-uuid[568]: Primary Header is updated. Jul 15 11:04:11.486135 disk-uuid[568]: Secondary Entries is updated. Jul 15 11:04:11.486135 disk-uuid[568]: Secondary Header is updated. Jul 15 11:04:11.489859 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 15 11:04:12.510813 disk-uuid[569]: The operation has completed successfully. Jul 15 11:04:12.511678 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 15 11:04:12.542573 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 15 11:04:12.543000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.543000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.542668 systemd[1]: Finished disk-uuid.service. Jul 15 11:04:12.544231 systemd[1]: Starting verity-setup.service... Jul 15 11:04:12.558856 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jul 15 11:04:12.577518 systemd[1]: Found device dev-mapper-usr.device. Jul 15 11:04:12.579645 systemd[1]: Mounting sysusr-usr.mount... Jul 15 11:04:12.581309 systemd[1]: Finished verity-setup.service. Jul 15 11:04:12.581000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.630883 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Jul 15 11:04:12.631357 systemd[1]: Mounted sysusr-usr.mount. Jul 15 11:04:12.632059 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Jul 15 11:04:12.632773 systemd[1]: Starting ignition-setup.service... Jul 15 11:04:12.633951 systemd[1]: Starting parse-ip-for-networkd.service... Jul 15 11:04:12.647915 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 15 11:04:12.647957 kernel: BTRFS info (device vda6): using free space tree Jul 15 11:04:12.647968 kernel: BTRFS info (device vda6): has skinny extents Jul 15 11:04:12.656511 systemd[1]: mnt-oem.mount: Deactivated successfully. Jul 15 11:04:12.664131 systemd[1]: Finished ignition-setup.service. Jul 15 11:04:12.663000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.665565 systemd[1]: Starting ignition-fetch-offline.service... Jul 15 11:04:12.722088 systemd[1]: Finished parse-ip-for-networkd.service. Jul 15 11:04:12.721000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.724000 audit: BPF prog-id=9 op=LOAD Jul 15 11:04:12.725649 systemd[1]: Starting systemd-networkd.service... Jul 15 11:04:12.753272 systemd-networkd[745]: lo: Link UP Jul 15 11:04:12.753282 systemd-networkd[745]: lo: Gained carrier Jul 15 11:04:12.753667 systemd-networkd[745]: Enumeration completed Jul 15 11:04:12.753871 systemd-networkd[745]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 15 11:04:12.754009 systemd[1]: Started systemd-networkd.service. Jul 15 11:04:12.754962 systemd-networkd[745]: eth0: Link UP Jul 15 11:04:12.754000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.754965 systemd-networkd[745]: eth0: Gained carrier Jul 15 11:04:12.755001 systemd[1]: Reached target network.target. Jul 15 11:04:12.756670 systemd[1]: Starting iscsiuio.service... Jul 15 11:04:12.765484 systemd[1]: Started iscsiuio.service. Jul 15 11:04:12.766000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.767228 systemd[1]: Starting iscsid.service... Jul 15 11:04:12.772165 iscsid[750]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Jul 15 11:04:12.772165 iscsid[750]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Jul 15 11:04:12.772165 iscsid[750]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Jul 15 11:04:12.772165 iscsid[750]: If using hardware iscsi like qla4xxx this message can be ignored. Jul 15 11:04:12.772165 iscsid[750]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Jul 15 11:04:12.772165 iscsid[750]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Jul 15 11:04:12.777000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.771918 systemd-networkd[745]: eth0: DHCPv4 address 10.0.0.27/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 15 11:04:12.774761 systemd[1]: Started iscsid.service. Jul 15 11:04:12.778891 systemd[1]: Starting dracut-initqueue.service... Jul 15 11:04:12.793359 systemd[1]: Finished dracut-initqueue.service. Jul 15 11:04:12.793000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.794501 systemd[1]: Reached target remote-fs-pre.target. Jul 15 11:04:12.795706 systemd[1]: Reached target remote-cryptsetup.target. Jul 15 11:04:12.797122 systemd[1]: Reached target remote-fs.target. Jul 15 11:04:12.797296 ignition[666]: Ignition 2.14.0 Jul 15 11:04:12.797303 ignition[666]: Stage: fetch-offline Jul 15 11:04:12.799592 systemd[1]: Starting dracut-pre-mount.service... Jul 15 11:04:12.797339 ignition[666]: no configs at "/usr/lib/ignition/base.d" Jul 15 11:04:12.797347 ignition[666]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 11:04:12.797479 ignition[666]: parsed url from cmdline: "" Jul 15 11:04:12.797482 ignition[666]: no config URL provided Jul 15 11:04:12.797487 ignition[666]: reading system config file "/usr/lib/ignition/user.ign" Jul 15 11:04:12.797493 ignition[666]: no config at "/usr/lib/ignition/user.ign" Jul 15 11:04:12.797511 ignition[666]: op(1): [started] loading QEMU firmware config module Jul 15 11:04:12.797515 ignition[666]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 15 11:04:12.803754 ignition[666]: op(1): [finished] loading QEMU firmware config module Jul 15 11:04:12.812053 systemd[1]: Finished dracut-pre-mount.service. Jul 15 11:04:12.811000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.815947 ignition[666]: parsing config with SHA512: bbdbd6064a7156ba9ab4a7f86dcb01529d20974b94109440aba5918c35b63900ad8f9dbaaa7f095c009c1949bec90360b351a434ebf8b93c573da38dd6da957b Jul 15 11:04:12.820226 unknown[666]: fetched base config from "system" Jul 15 11:04:12.820237 unknown[666]: fetched user config from "qemu" Jul 15 11:04:12.822524 ignition[666]: fetch-offline: fetch-offline passed Jul 15 11:04:12.822611 ignition[666]: Ignition finished successfully Jul 15 11:04:12.823359 systemd[1]: Finished ignition-fetch-offline.service. Jul 15 11:04:12.823000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.824580 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 15 11:04:12.825403 systemd[1]: Starting ignition-kargs.service... Jul 15 11:04:12.834166 ignition[767]: Ignition 2.14.0 Jul 15 11:04:12.834181 ignition[767]: Stage: kargs Jul 15 11:04:12.834275 ignition[767]: no configs at "/usr/lib/ignition/base.d" Jul 15 11:04:12.834285 ignition[767]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 11:04:12.836947 systemd[1]: Finished ignition-kargs.service. Jul 15 11:04:12.838000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.835353 ignition[767]: kargs: kargs passed Jul 15 11:04:12.835397 ignition[767]: Ignition finished successfully Jul 15 11:04:12.839173 systemd[1]: Starting ignition-disks.service... Jul 15 11:04:12.846075 ignition[773]: Ignition 2.14.0 Jul 15 11:04:12.846091 ignition[773]: Stage: disks Jul 15 11:04:12.847691 systemd[1]: Finished ignition-disks.service. Jul 15 11:04:12.848000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.846188 ignition[773]: no configs at "/usr/lib/ignition/base.d" Jul 15 11:04:12.848793 systemd[1]: Reached target initrd-root-device.target. Jul 15 11:04:12.846198 ignition[773]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 11:04:12.849859 systemd[1]: Reached target local-fs-pre.target. Jul 15 11:04:12.846906 ignition[773]: disks: disks passed Jul 15 11:04:12.851071 systemd[1]: Reached target local-fs.target. Jul 15 11:04:12.846949 ignition[773]: Ignition finished successfully Jul 15 11:04:12.852203 systemd[1]: Reached target sysinit.target. Jul 15 11:04:12.853467 systemd[1]: Reached target basic.target. Jul 15 11:04:12.855335 systemd[1]: Starting systemd-fsck-root.service... Jul 15 11:04:12.866155 systemd-fsck[781]: ROOT: clean, 619/553520 files, 56022/553472 blocks Jul 15 11:04:12.870082 systemd[1]: Finished systemd-fsck-root.service. Jul 15 11:04:12.870000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.871742 systemd[1]: Mounting sysroot.mount... Jul 15 11:04:12.877852 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Jul 15 11:04:12.878389 systemd[1]: Mounted sysroot.mount. Jul 15 11:04:12.879011 systemd[1]: Reached target initrd-root-fs.target. Jul 15 11:04:12.880925 systemd[1]: Mounting sysroot-usr.mount... Jul 15 11:04:12.881628 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Jul 15 11:04:12.881664 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 15 11:04:12.881687 systemd[1]: Reached target ignition-diskful.target. Jul 15 11:04:12.883702 systemd[1]: Mounted sysroot-usr.mount. Jul 15 11:04:12.885637 systemd[1]: Starting initrd-setup-root.service... Jul 15 11:04:12.889846 initrd-setup-root[791]: cut: /sysroot/etc/passwd: No such file or directory Jul 15 11:04:12.894078 initrd-setup-root[799]: cut: /sysroot/etc/group: No such file or directory Jul 15 11:04:12.898271 initrd-setup-root[807]: cut: /sysroot/etc/shadow: No such file or directory Jul 15 11:04:12.901990 initrd-setup-root[815]: cut: /sysroot/etc/gshadow: No such file or directory Jul 15 11:04:12.934097 systemd[1]: Finished initrd-setup-root.service. Jul 15 11:04:12.933000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.935542 systemd[1]: Starting ignition-mount.service... Jul 15 11:04:12.936730 systemd[1]: Starting sysroot-boot.service... Jul 15 11:04:12.941746 bash[832]: umount: /sysroot/usr/share/oem: not mounted. Jul 15 11:04:12.950429 ignition[833]: INFO : Ignition 2.14.0 Jul 15 11:04:12.950429 ignition[833]: INFO : Stage: mount Jul 15 11:04:12.952198 ignition[833]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 11:04:12.952198 ignition[833]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 11:04:12.952198 ignition[833]: INFO : mount: mount passed Jul 15 11:04:12.952198 ignition[833]: INFO : Ignition finished successfully Jul 15 11:04:12.954000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:12.954047 systemd[1]: Finished ignition-mount.service. Jul 15 11:04:12.965387 systemd[1]: Finished sysroot-boot.service. Jul 15 11:04:12.965000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:13.588284 systemd[1]: Mounting sysroot-usr-share-oem.mount... Jul 15 11:04:13.593853 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (842) Jul 15 11:04:13.595193 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 15 11:04:13.595206 kernel: BTRFS info (device vda6): using free space tree Jul 15 11:04:13.595216 kernel: BTRFS info (device vda6): has skinny extents Jul 15 11:04:13.598398 systemd[1]: Mounted sysroot-usr-share-oem.mount. Jul 15 11:04:13.599665 systemd[1]: Starting ignition-files.service... Jul 15 11:04:13.612954 ignition[862]: INFO : Ignition 2.14.0 Jul 15 11:04:13.612954 ignition[862]: INFO : Stage: files Jul 15 11:04:13.614270 ignition[862]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 11:04:13.614270 ignition[862]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 11:04:13.614270 ignition[862]: DEBUG : files: compiled without relabeling support, skipping Jul 15 11:04:13.616880 ignition[862]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 15 11:04:13.617971 ignition[862]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 15 11:04:13.619238 ignition[862]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 15 11:04:13.620202 ignition[862]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 15 11:04:13.620202 ignition[862]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 15 11:04:13.619816 unknown[862]: wrote ssh authorized keys file for user: core Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 15 11:04:13.623119 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-arm64.raw: attempt #1 Jul 15 11:04:13.988016 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Jul 15 11:04:14.311305 systemd-networkd[745]: eth0: Gained IPv6LL Jul 15 11:04:14.369709 ignition[862]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 15 11:04:14.369709 ignition[862]: INFO : files: op(8): [started] processing unit "containerd.service" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(8): [finished] processing unit "containerd.service" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(a): [started] processing unit "coreos-metadata.service" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(a): op(b): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(a): op(b): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(a): [finished] processing unit "coreos-metadata.service" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(c): [started] setting preset to disabled for "coreos-metadata.service" Jul 15 11:04:14.372532 ignition[862]: INFO : files: op(c): op(d): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 15 11:04:14.406262 ignition[862]: INFO : files: op(c): op(d): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 15 11:04:14.408331 ignition[862]: INFO : files: op(c): [finished] setting preset to disabled for "coreos-metadata.service" Jul 15 11:04:14.408331 ignition[862]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 15 11:04:14.408331 ignition[862]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 15 11:04:14.408331 ignition[862]: INFO : files: files passed Jul 15 11:04:14.408331 ignition[862]: INFO : Ignition finished successfully Jul 15 11:04:14.409000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.408499 systemd[1]: Finished ignition-files.service. Jul 15 11:04:14.410854 systemd[1]: Starting initrd-setup-root-after-ignition.service... Jul 15 11:04:14.412826 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Jul 15 11:04:14.413466 systemd[1]: Starting ignition-quench.service... Jul 15 11:04:14.416000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.416000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.418318 initrd-setup-root-after-ignition[887]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Jul 15 11:04:14.418000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.416108 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 15 11:04:14.421143 initrd-setup-root-after-ignition[889]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 15 11:04:14.416182 systemd[1]: Finished ignition-quench.service. Jul 15 11:04:14.418322 systemd[1]: Finished initrd-setup-root-after-ignition.service. Jul 15 11:04:14.419080 systemd[1]: Reached target ignition-complete.target. Jul 15 11:04:14.421107 systemd[1]: Starting initrd-parse-etc.service... Jul 15 11:04:14.433077 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 15 11:04:14.433161 systemd[1]: Finished initrd-parse-etc.service. Jul 15 11:04:14.433000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.433000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.434574 systemd[1]: Reached target initrd-fs.target. Jul 15 11:04:14.435462 systemd[1]: Reached target initrd.target. Jul 15 11:04:14.436417 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Jul 15 11:04:14.437129 systemd[1]: Starting dracut-pre-pivot.service... Jul 15 11:04:14.446933 systemd[1]: Finished dracut-pre-pivot.service. Jul 15 11:04:14.446000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.448248 systemd[1]: Starting initrd-cleanup.service... Jul 15 11:04:14.455867 systemd[1]: Stopped target nss-lookup.target. Jul 15 11:04:14.456518 systemd[1]: Stopped target remote-cryptsetup.target. Jul 15 11:04:14.457559 systemd[1]: Stopped target timers.target. Jul 15 11:04:14.458567 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 15 11:04:14.458000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.458669 systemd[1]: Stopped dracut-pre-pivot.service. Jul 15 11:04:14.459667 systemd[1]: Stopped target initrd.target. Jul 15 11:04:14.460773 systemd[1]: Stopped target basic.target. Jul 15 11:04:14.461797 systemd[1]: Stopped target ignition-complete.target. Jul 15 11:04:14.462832 systemd[1]: Stopped target ignition-diskful.target. Jul 15 11:04:14.463794 systemd[1]: Stopped target initrd-root-device.target. Jul 15 11:04:14.464862 systemd[1]: Stopped target remote-fs.target. Jul 15 11:04:14.465875 systemd[1]: Stopped target remote-fs-pre.target. Jul 15 11:04:14.466989 systemd[1]: Stopped target sysinit.target. Jul 15 11:04:14.467988 systemd[1]: Stopped target local-fs.target. Jul 15 11:04:14.468979 systemd[1]: Stopped target local-fs-pre.target. Jul 15 11:04:14.469910 systemd[1]: Stopped target swap.target. Jul 15 11:04:14.470000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.470786 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 15 11:04:14.470912 systemd[1]: Stopped dracut-pre-mount.service. Jul 15 11:04:14.473000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.471876 systemd[1]: Stopped target cryptsetup.target. Jul 15 11:04:14.474000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.472741 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 15 11:04:14.472853 systemd[1]: Stopped dracut-initqueue.service. Jul 15 11:04:14.473969 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 15 11:04:14.474061 systemd[1]: Stopped ignition-fetch-offline.service. Jul 15 11:04:14.475000 systemd[1]: Stopped target paths.target. Jul 15 11:04:14.475898 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 15 11:04:14.479896 systemd[1]: Stopped systemd-ask-password-console.path. Jul 15 11:04:14.480681 systemd[1]: Stopped target slices.target. Jul 15 11:04:14.481804 systemd[1]: Stopped target sockets.target. Jul 15 11:04:14.482794 systemd[1]: iscsid.socket: Deactivated successfully. Jul 15 11:04:14.482881 systemd[1]: Closed iscsid.socket. Jul 15 11:04:14.483729 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 15 11:04:14.483792 systemd[1]: Closed iscsiuio.socket. Jul 15 11:04:14.486000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.485103 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 15 11:04:14.487000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.485202 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Jul 15 11:04:14.486295 systemd[1]: ignition-files.service: Deactivated successfully. Jul 15 11:04:14.486384 systemd[1]: Stopped ignition-files.service. Jul 15 11:04:14.490000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.488076 systemd[1]: Stopping ignition-mount.service... Jul 15 11:04:14.489018 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 15 11:04:14.489135 systemd[1]: Stopped kmod-static-nodes.service. Jul 15 11:04:14.491000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.490914 systemd[1]: Stopping sysroot-boot.service... Jul 15 11:04:14.493000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.494876 ignition[902]: INFO : Ignition 2.14.0 Jul 15 11:04:14.494876 ignition[902]: INFO : Stage: umount Jul 15 11:04:14.494876 ignition[902]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 11:04:14.494876 ignition[902]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 11:04:14.497000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.491676 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 15 11:04:14.498000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.498000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.499456 ignition[902]: INFO : umount: umount passed Jul 15 11:04:14.499456 ignition[902]: INFO : Ignition finished successfully Jul 15 11:04:14.501000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.491798 systemd[1]: Stopped systemd-udev-trigger.service. Jul 15 11:04:14.501000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.492932 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 15 11:04:14.493026 systemd[1]: Stopped dracut-pre-trigger.service. Jul 15 11:04:14.496273 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 15 11:04:14.496366 systemd[1]: Stopped ignition-mount.service. Jul 15 11:04:14.505000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.497614 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 15 11:04:14.497680 systemd[1]: Finished initrd-cleanup.service. Jul 15 11:04:14.499334 systemd[1]: Stopped target network.target. Jul 15 11:04:14.499931 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 15 11:04:14.499977 systemd[1]: Stopped ignition-disks.service. Jul 15 11:04:14.501181 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 15 11:04:14.515000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.501216 systemd[1]: Stopped ignition-kargs.service. Jul 15 11:04:14.502333 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 15 11:04:14.502366 systemd[1]: Stopped ignition-setup.service. Jul 15 11:04:14.506056 systemd[1]: Stopping systemd-networkd.service... Jul 15 11:04:14.507004 systemd[1]: Stopping systemd-resolved.service... Jul 15 11:04:14.509328 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 15 11:04:14.520000 audit: BPF prog-id=6 op=UNLOAD Jul 15 11:04:14.514115 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 15 11:04:14.514220 systemd[1]: Stopped systemd-resolved.service. Jul 15 11:04:14.520903 systemd-networkd[745]: eth0: DHCPv6 lease lost Jul 15 11:04:14.523000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.522159 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 15 11:04:14.524000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.522262 systemd[1]: Stopped systemd-networkd.service. Jul 15 11:04:14.526000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.526000 audit: BPF prog-id=9 op=UNLOAD Jul 15 11:04:14.523454 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 15 11:04:14.523532 systemd[1]: Stopped sysroot-boot.service. Jul 15 11:04:14.524410 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 15 11:04:14.529000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.524437 systemd[1]: Closed systemd-networkd.socket. Jul 15 11:04:14.529000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.525159 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 15 11:04:14.530000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.525201 systemd[1]: Stopped initrd-setup-root.service. Jul 15 11:04:14.527217 systemd[1]: Stopping network-cleanup.service... Jul 15 11:04:14.528186 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 15 11:04:14.528233 systemd[1]: Stopped parse-ip-for-networkd.service. Jul 15 11:04:14.529393 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 15 11:04:14.537000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.529427 systemd[1]: Stopped systemd-sysctl.service. Jul 15 11:04:14.531058 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 15 11:04:14.531094 systemd[1]: Stopped systemd-modules-load.service. Jul 15 11:04:14.542000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.531849 systemd[1]: Stopping systemd-udevd.service... Jul 15 11:04:14.543000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.536276 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 15 11:04:14.543000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.537535 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 15 11:04:14.537669 systemd[1]: Stopped systemd-udevd.service. Jul 15 11:04:14.547000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.538898 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 15 11:04:14.548000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.538935 systemd[1]: Closed systemd-udevd-control.socket. Jul 15 11:04:14.540001 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 15 11:04:14.540031 systemd[1]: Closed systemd-udevd-kernel.socket. Jul 15 11:04:14.551000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.551000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:14.541082 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 15 11:04:14.541119 systemd[1]: Stopped dracut-pre-udev.service. Jul 15 11:04:14.542372 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 15 11:04:14.542405 systemd[1]: Stopped dracut-cmdline.service. Jul 15 11:04:14.543474 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 15 11:04:14.543510 systemd[1]: Stopped dracut-cmdline-ask.service. Jul 15 11:04:14.545283 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Jul 15 11:04:14.546025 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 15 11:04:14.546111 systemd[1]: Stopped systemd-vconsole-setup.service. Jul 15 11:04:14.547639 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 15 11:04:14.547730 systemd[1]: Stopped network-cleanup.service. Jul 15 11:04:14.550449 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 15 11:04:14.550518 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Jul 15 11:04:14.551372 systemd[1]: Reached target initrd-switch-root.target. Jul 15 11:04:14.561000 audit: BPF prog-id=5 op=UNLOAD Jul 15 11:04:14.561000 audit: BPF prog-id=4 op=UNLOAD Jul 15 11:04:14.561000 audit: BPF prog-id=3 op=UNLOAD Jul 15 11:04:14.553117 systemd[1]: Starting initrd-switch-root.service... Jul 15 11:04:14.562000 audit: BPF prog-id=8 op=UNLOAD Jul 15 11:04:14.562000 audit: BPF prog-id=7 op=UNLOAD Jul 15 11:04:14.558824 systemd[1]: Switching root. Jul 15 11:04:14.571360 iscsid[750]: iscsid shutting down. Jul 15 11:04:14.571939 systemd-journald[289]: Received SIGTERM from PID 1 (systemd). Jul 15 11:04:14.571973 systemd-journald[289]: Journal stopped Jul 15 11:04:16.688182 kernel: SELinux: Class mctp_socket not defined in policy. Jul 15 11:04:16.688244 kernel: SELinux: Class anon_inode not defined in policy. Jul 15 11:04:16.688259 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 15 11:04:16.688269 kernel: SELinux: policy capability network_peer_controls=1 Jul 15 11:04:16.688279 kernel: SELinux: policy capability open_perms=1 Jul 15 11:04:16.688288 kernel: SELinux: policy capability extended_socket_class=1 Jul 15 11:04:16.688298 kernel: SELinux: policy capability always_check_network=0 Jul 15 11:04:16.688307 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 15 11:04:16.688321 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 15 11:04:16.688331 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 15 11:04:16.688340 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 15 11:04:16.688352 systemd[1]: Successfully loaded SELinux policy in 36.416ms. Jul 15 11:04:16.688375 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 8.468ms. Jul 15 11:04:16.688391 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Jul 15 11:04:16.688402 systemd[1]: Detected virtualization kvm. Jul 15 11:04:16.688413 systemd[1]: Detected architecture arm64. Jul 15 11:04:16.688423 systemd[1]: Detected first boot. Jul 15 11:04:16.688434 systemd[1]: Initializing machine ID from VM UUID. Jul 15 11:04:16.688445 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 15 11:04:16.688455 systemd[1]: Populated /etc with preset unit settings. Jul 15 11:04:16.688467 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 15 11:04:16.688479 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 15 11:04:16.688490 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 15 11:04:16.688501 systemd[1]: Queued start job for default target multi-user.target. Jul 15 11:04:16.688511 systemd[1]: Unnecessary job was removed for dev-vda6.device. Jul 15 11:04:16.688521 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 15 11:04:16.688532 systemd[1]: Created slice system-addon\x2drun.slice. Jul 15 11:04:16.688542 systemd[1]: Created slice system-getty.slice. Jul 15 11:04:16.688552 systemd[1]: Created slice system-modprobe.slice. Jul 15 11:04:16.688566 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 15 11:04:16.688576 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 15 11:04:16.688586 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 15 11:04:16.688596 systemd[1]: Created slice user.slice. Jul 15 11:04:16.688606 systemd[1]: Started systemd-ask-password-console.path. Jul 15 11:04:16.688616 systemd[1]: Started systemd-ask-password-wall.path. Jul 15 11:04:16.688627 systemd[1]: Set up automount boot.automount. Jul 15 11:04:16.688637 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 15 11:04:16.688652 systemd[1]: Reached target integritysetup.target. Jul 15 11:04:16.688662 systemd[1]: Reached target remote-cryptsetup.target. Jul 15 11:04:16.688673 systemd[1]: Reached target remote-fs.target. Jul 15 11:04:16.688685 systemd[1]: Reached target slices.target. Jul 15 11:04:16.688698 systemd[1]: Reached target swap.target. Jul 15 11:04:16.688708 systemd[1]: Reached target torcx.target. Jul 15 11:04:16.688718 systemd[1]: Reached target veritysetup.target. Jul 15 11:04:16.688728 systemd[1]: Listening on systemd-coredump.socket. Jul 15 11:04:16.688738 systemd[1]: Listening on systemd-initctl.socket. Jul 15 11:04:16.688748 kernel: kauditd_printk_skb: 77 callbacks suppressed Jul 15 11:04:16.688758 kernel: audit: type=1400 audit(1752577456.582:81): avc: denied { audit_read } for pid=1 comm="systemd" capability=37 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Jul 15 11:04:16.688768 systemd[1]: Listening on systemd-journald-audit.socket. Jul 15 11:04:16.688779 kernel: audit: type=1335 audit(1752577456.583:82): pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Jul 15 11:04:16.688789 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 15 11:04:16.688800 systemd[1]: Listening on systemd-journald.socket. Jul 15 11:04:16.688818 systemd[1]: Listening on systemd-networkd.socket. Jul 15 11:04:16.688831 systemd[1]: Listening on systemd-udevd-control.socket. Jul 15 11:04:16.688850 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 15 11:04:16.688861 systemd[1]: Listening on systemd-userdbd.socket. Jul 15 11:04:16.688871 systemd[1]: Mounting dev-hugepages.mount... Jul 15 11:04:16.688881 systemd[1]: Mounting dev-mqueue.mount... Jul 15 11:04:16.688893 systemd[1]: Mounting media.mount... Jul 15 11:04:16.688902 systemd[1]: Mounting sys-kernel-debug.mount... Jul 15 11:04:16.688913 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 15 11:04:16.688923 systemd[1]: Mounting tmp.mount... Jul 15 11:04:16.688934 systemd[1]: Starting flatcar-tmpfiles.service... Jul 15 11:04:16.688945 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 15 11:04:16.688955 systemd[1]: Starting kmod-static-nodes.service... Jul 15 11:04:16.688965 systemd[1]: Starting modprobe@configfs.service... Jul 15 11:04:16.688975 systemd[1]: Starting modprobe@dm_mod.service... Jul 15 11:04:16.688986 systemd[1]: Starting modprobe@drm.service... Jul 15 11:04:16.688997 systemd[1]: Starting modprobe@efi_pstore.service... Jul 15 11:04:16.689007 systemd[1]: Starting modprobe@fuse.service... Jul 15 11:04:16.689017 systemd[1]: Starting modprobe@loop.service... Jul 15 11:04:16.689028 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 15 11:04:16.689039 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Jul 15 11:04:16.689049 systemd[1]: (This warning is only shown for the first unit using IP firewalling.) Jul 15 11:04:16.689059 systemd[1]: Starting systemd-journald.service... Jul 15 11:04:16.689069 systemd[1]: Starting systemd-modules-load.service... Jul 15 11:04:16.689081 systemd[1]: Starting systemd-network-generator.service... Jul 15 11:04:16.689091 systemd[1]: Starting systemd-remount-fs.service... Jul 15 11:04:16.689101 systemd[1]: Starting systemd-udev-trigger.service... Jul 15 11:04:16.689111 systemd[1]: Mounted dev-hugepages.mount. Jul 15 11:04:16.689121 systemd[1]: Mounted dev-mqueue.mount. Jul 15 11:04:16.689131 systemd[1]: Mounted media.mount. Jul 15 11:04:16.689141 systemd[1]: Mounted sys-kernel-debug.mount. Jul 15 11:04:16.689152 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 15 11:04:16.689162 systemd[1]: Mounted tmp.mount. Jul 15 11:04:16.689173 kernel: fuse: init (API version 7.34) Jul 15 11:04:16.689182 systemd[1]: Finished kmod-static-nodes.service. Jul 15 11:04:16.689193 kernel: audit: type=1130 audit(1752577456.673:83): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.689202 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 15 11:04:16.689212 kernel: audit: type=1305 audit(1752577456.676:84): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 15 11:04:16.689222 systemd[1]: Finished modprobe@configfs.service. Jul 15 11:04:16.689233 kernel: audit: type=1300 audit(1752577456.676:84): arch=c00000b7 syscall=211 success=yes exit=60 a0=5 a1=ffffd02e3e00 a2=4000 a3=1 items=0 ppid=1 pid=1028 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 15 11:04:16.689243 kernel: audit: type=1327 audit(1752577456.676:84): proctitle="/usr/lib/systemd/systemd-journald" Jul 15 11:04:16.689254 kernel: loop: module loaded Jul 15 11:04:16.689264 kernel: audit: type=1130 audit(1752577456.684:85): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.689274 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 11:04:16.689284 kernel: audit: type=1131 audit(1752577456.684:86): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.689294 systemd[1]: Finished modprobe@dm_mod.service. Jul 15 11:04:16.689307 systemd-journald[1028]: Journal started Jul 15 11:04:16.689351 systemd-journald[1028]: Runtime Journal (/run/log/journal/f12e6760a54b4ce0840805c05e989ce7) is 6.0M, max 48.7M, 42.6M free. Jul 15 11:04:16.583000 audit[1]: EVENT_LISTENER pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Jul 15 11:04:16.673000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.676000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 15 11:04:16.676000 audit[1028]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=5 a1=ffffd02e3e00 a2=4000 a3=1 items=0 ppid=1 pid=1028 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 15 11:04:16.676000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 15 11:04:16.684000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.684000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.694423 kernel: audit: type=1130 audit(1752577456.689:87): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.694480 systemd[1]: Started systemd-journald.service. Jul 15 11:04:16.694501 kernel: audit: type=1131 audit(1752577456.689:88): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.689000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.689000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.694000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.696453 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 15 11:04:16.696672 systemd[1]: Finished modprobe@drm.service. Jul 15 11:04:16.696000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.696000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.697589 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 11:04:16.697801 systemd[1]: Finished modprobe@efi_pstore.service. Jul 15 11:04:16.697000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.697000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.698709 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 15 11:04:16.698939 systemd[1]: Finished modprobe@fuse.service. Jul 15 11:04:16.698000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.698000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.699748 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 11:04:16.700532 systemd[1]: Finished modprobe@loop.service. Jul 15 11:04:16.700000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.700000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.701624 systemd[1]: Finished systemd-modules-load.service. Jul 15 11:04:16.701000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.702000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.702907 systemd[1]: Finished systemd-network-generator.service. Jul 15 11:04:16.703000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.704043 systemd[1]: Finished systemd-remount-fs.service. Jul 15 11:04:16.705036 systemd[1]: Reached target network-pre.target. Jul 15 11:04:16.706951 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 15 11:04:16.709115 systemd[1]: Mounting sys-kernel-config.mount... Jul 15 11:04:16.709762 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 15 11:04:16.711719 systemd[1]: Starting systemd-hwdb-update.service... Jul 15 11:04:16.713789 systemd[1]: Starting systemd-journal-flush.service... Jul 15 11:04:16.714844 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 11:04:16.716080 systemd[1]: Starting systemd-random-seed.service... Jul 15 11:04:16.723195 systemd-journald[1028]: Time spent on flushing to /var/log/journal/f12e6760a54b4ce0840805c05e989ce7 is 20.902ms for 901 entries. Jul 15 11:04:16.723195 systemd-journald[1028]: System Journal (/var/log/journal/f12e6760a54b4ce0840805c05e989ce7) is 8.0M, max 195.6M, 187.6M free. Jul 15 11:04:16.758153 systemd-journald[1028]: Received client request to flush runtime journal. Jul 15 11:04:16.734000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.735000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.747000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.749000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.716834 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 15 11:04:16.718247 systemd[1]: Starting systemd-sysctl.service... Jul 15 11:04:16.721806 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 15 11:04:16.722746 systemd[1]: Mounted sys-kernel-config.mount. Jul 15 11:04:16.759185 udevadm[1085]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 15 11:04:16.734783 systemd[1]: Finished flatcar-tmpfiles.service. Jul 15 11:04:16.735780 systemd[1]: Finished systemd-udev-trigger.service. Jul 15 11:04:16.737697 systemd[1]: Starting systemd-sysusers.service... Jul 15 11:04:16.739791 systemd[1]: Starting systemd-udev-settle.service... Jul 15 11:04:16.747344 systemd[1]: Finished systemd-random-seed.service. Jul 15 11:04:16.748367 systemd[1]: Reached target first-boot-complete.target. Jul 15 11:04:16.750041 systemd[1]: Finished systemd-sysctl.service. Jul 15 11:04:16.759671 systemd[1]: Finished systemd-journal-flush.service. Jul 15 11:04:16.759000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.767386 systemd[1]: Finished systemd-sysusers.service. Jul 15 11:04:16.767000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:16.769308 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 15 11:04:16.790914 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 15 11:04:16.790000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.089372 systemd[1]: Finished systemd-hwdb-update.service. Jul 15 11:04:17.089000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.091281 systemd[1]: Starting systemd-udevd.service... Jul 15 11:04:17.113911 systemd-udevd[1096]: Using default interface naming scheme 'v252'. Jul 15 11:04:17.128000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.128596 systemd[1]: Started systemd-udevd.service. Jul 15 11:04:17.130747 systemd[1]: Starting systemd-networkd.service... Jul 15 11:04:17.155327 systemd[1]: Starting systemd-userdbd.service... Jul 15 11:04:17.161429 systemd[1]: Found device dev-ttyAMA0.device. Jul 15 11:04:17.173717 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 15 11:04:17.197000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.197217 systemd[1]: Started systemd-userdbd.service. Jul 15 11:04:17.238000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.238669 systemd[1]: Finished systemd-udev-settle.service. Jul 15 11:04:17.240656 systemd[1]: Starting lvm2-activation-early.service... Jul 15 11:04:17.257372 lvm[1130]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 15 11:04:17.260320 systemd-networkd[1104]: lo: Link UP Jul 15 11:04:17.260330 systemd-networkd[1104]: lo: Gained carrier Jul 15 11:04:17.260693 systemd-networkd[1104]: Enumeration completed Jul 15 11:04:17.260804 systemd-networkd[1104]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 15 11:04:17.260851 systemd[1]: Started systemd-networkd.service. Jul 15 11:04:17.260000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.262600 systemd-networkd[1104]: eth0: Link UP Jul 15 11:04:17.262609 systemd-networkd[1104]: eth0: Gained carrier Jul 15 11:04:17.285976 systemd-networkd[1104]: eth0: DHCPv4 address 10.0.0.27/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 15 11:04:17.294759 systemd[1]: Finished lvm2-activation-early.service. Jul 15 11:04:17.294000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.295543 systemd[1]: Reached target cryptsetup.target. Jul 15 11:04:17.297339 systemd[1]: Starting lvm2-activation.service... Jul 15 11:04:17.300924 lvm[1132]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 15 11:04:17.334641 systemd[1]: Finished lvm2-activation.service. Jul 15 11:04:17.334000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.335385 systemd[1]: Reached target local-fs-pre.target. Jul 15 11:04:17.336022 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 15 11:04:17.336050 systemd[1]: Reached target local-fs.target. Jul 15 11:04:17.336599 systemd[1]: Reached target machines.target. Jul 15 11:04:17.338251 systemd[1]: Starting ldconfig.service... Jul 15 11:04:17.339123 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.339193 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 15 11:04:17.340457 systemd[1]: Starting systemd-boot-update.service... Jul 15 11:04:17.342497 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 15 11:04:17.344470 systemd[1]: Starting systemd-machine-id-commit.service... Jul 15 11:04:17.346928 systemd[1]: Starting systemd-sysext.service... Jul 15 11:04:17.348254 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1135 (bootctl) Jul 15 11:04:17.350237 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 15 11:04:17.351686 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 15 11:04:17.351000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.357958 systemd[1]: Unmounting usr-share-oem.mount... Jul 15 11:04:17.363241 systemd[1]: usr-share-oem.mount: Deactivated successfully. Jul 15 11:04:17.363476 systemd[1]: Unmounted usr-share-oem.mount. Jul 15 11:04:17.376858 kernel: loop0: detected capacity change from 0 to 203944 Jul 15 11:04:17.422788 systemd[1]: Finished systemd-machine-id-commit.service. Jul 15 11:04:17.422000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.428872 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 15 11:04:17.429992 systemd-fsck[1144]: fsck.fat 4.2 (2021-01-31) Jul 15 11:04:17.429992 systemd-fsck[1144]: /dev/vda1: 236 files, 117310/258078 clusters Jul 15 11:04:17.434181 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 15 11:04:17.434000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.448854 kernel: loop1: detected capacity change from 0 to 203944 Jul 15 11:04:17.456291 (sd-sysext)[1154]: Using extensions 'kubernetes'. Jul 15 11:04:17.457207 (sd-sysext)[1154]: Merged extensions into '/usr'. Jul 15 11:04:17.475671 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.477041 systemd[1]: Starting modprobe@dm_mod.service... Jul 15 11:04:17.478884 systemd[1]: Starting modprobe@efi_pstore.service... Jul 15 11:04:17.480696 systemd[1]: Starting modprobe@loop.service... Jul 15 11:04:17.481531 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.481704 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 15 11:04:17.482482 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 11:04:17.482628 systemd[1]: Finished modprobe@dm_mod.service. Jul 15 11:04:17.482000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.482000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.483828 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 11:04:17.484012 systemd[1]: Finished modprobe@efi_pstore.service. Jul 15 11:04:17.484000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.484000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.485305 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 11:04:17.485448 systemd[1]: Finished modprobe@loop.service. Jul 15 11:04:17.485000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.485000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.486627 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 11:04:17.486780 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.521506 ldconfig[1134]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 15 11:04:17.525580 systemd[1]: Finished ldconfig.service. Jul 15 11:04:17.525000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.656286 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 15 11:04:17.658187 systemd[1]: Mounting boot.mount... Jul 15 11:04:17.659934 systemd[1]: Mounting usr-share-oem.mount... Jul 15 11:04:17.666417 systemd[1]: Mounted boot.mount. Jul 15 11:04:17.670897 systemd[1]: Mounted usr-share-oem.mount. Jul 15 11:04:17.673000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.673368 systemd[1]: Finished systemd-sysext.service. Jul 15 11:04:17.675032 systemd[1]: Finished systemd-boot-update.service. Jul 15 11:04:17.674000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.676707 systemd[1]: Starting ensure-sysext.service... Jul 15 11:04:17.678231 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 15 11:04:17.682425 systemd[1]: Reloading. Jul 15 11:04:17.686775 systemd-tmpfiles[1172]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 15 11:04:17.687791 systemd-tmpfiles[1172]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 15 11:04:17.689066 systemd-tmpfiles[1172]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 15 11:04:17.720581 /usr/lib/systemd/system-generators/torcx-generator[1192]: time="2025-07-15T11:04:17Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.100 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.100 /var/lib/torcx/store]" Jul 15 11:04:17.720991 /usr/lib/systemd/system-generators/torcx-generator[1192]: time="2025-07-15T11:04:17Z" level=info msg="torcx already run" Jul 15 11:04:17.782571 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 15 11:04:17.782593 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 15 11:04:17.797594 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 15 11:04:17.842578 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 15 11:04:17.843000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.846302 systemd[1]: Starting audit-rules.service... Jul 15 11:04:17.848047 systemd[1]: Starting clean-ca-certificates.service... Jul 15 11:04:17.849742 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 15 11:04:17.852173 systemd[1]: Starting systemd-resolved.service... Jul 15 11:04:17.854379 systemd[1]: Starting systemd-timesyncd.service... Jul 15 11:04:17.856070 systemd[1]: Starting systemd-update-utmp.service... Jul 15 11:04:17.857000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.857883 systemd[1]: Finished clean-ca-certificates.service. Jul 15 11:04:17.858000 audit[1248]: SYSTEM_BOOT pid=1248 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.865582 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.866718 systemd[1]: Starting modprobe@dm_mod.service... Jul 15 11:04:17.868455 systemd[1]: Starting modprobe@efi_pstore.service... Jul 15 11:04:17.870373 systemd[1]: Starting modprobe@loop.service... Jul 15 11:04:17.870990 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.871110 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 15 11:04:17.871209 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 15 11:04:17.872132 systemd[1]: Finished systemd-update-utmp.service. Jul 15 11:04:17.872000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.873225 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 11:04:17.873362 systemd[1]: Finished modprobe@dm_mod.service. Jul 15 11:04:17.873000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.873000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.874428 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 11:04:17.874562 systemd[1]: Finished modprobe@loop.service. Jul 15 11:04:17.874000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.874000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.876273 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 11:04:17.876424 systemd[1]: Finished modprobe@efi_pstore.service. Jul 15 11:04:17.876000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.876000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.877466 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 11:04:17.877549 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.879230 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.880333 systemd[1]: Starting modprobe@dm_mod.service... Jul 15 11:04:17.882040 systemd[1]: Starting modprobe@efi_pstore.service... Jul 15 11:04:17.883702 systemd[1]: Starting modprobe@loop.service... Jul 15 11:04:17.884304 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.884426 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 15 11:04:17.884539 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 15 11:04:17.885469 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 15 11:04:17.885000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.886722 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 11:04:17.886880 systemd[1]: Finished modprobe@efi_pstore.service. Jul 15 11:04:17.886000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.886000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.887000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.887000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.887895 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 11:04:17.888024 systemd[1]: Finished modprobe@loop.service. Jul 15 11:04:17.888978 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 11:04:17.890244 systemd[1]: Starting systemd-update-done.service... Jul 15 11:04:17.893000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.893000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.893495 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 11:04:17.893655 systemd[1]: Finished modprobe@dm_mod.service. Jul 15 11:04:17.894762 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.895911 systemd[1]: Starting modprobe@drm.service... Jul 15 11:04:17.899223 systemd[1]: Starting modprobe@efi_pstore.service... Jul 15 11:04:17.901349 systemd[1]: Starting modprobe@loop.service... Jul 15 11:04:17.903751 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.903910 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 15 11:04:17.905150 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 15 11:04:17.905876 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 15 11:04:17.907018 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 15 11:04:17.907000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.907000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.907167 systemd[1]: Finished modprobe@drm.service. Jul 15 11:04:17.908203 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 11:04:17.908336 systemd[1]: Finished modprobe@efi_pstore.service. Jul 15 11:04:17.908000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.908000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.909000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.909515 systemd[1]: Finished systemd-update-done.service. Jul 15 11:04:17.910530 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 11:04:17.910669 systemd[1]: Finished modprobe@loop.service. Jul 15 11:04:17.910000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.910000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.912586 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 11:04:17.912683 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.916600 systemd[1]: Finished ensure-sysext.service. Jul 15 11:04:17.916000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ensure-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 15 11:04:17.928000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Jul 15 11:04:17.928000 audit[1288]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff6d289c0 a2=420 a3=0 items=0 ppid=1238 pid=1288 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 15 11:04:17.928000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 15 11:04:17.929536 augenrules[1288]: No rules Jul 15 11:04:17.930432 systemd[1]: Finished audit-rules.service. Jul 15 11:04:17.937155 systemd[1]: Started systemd-timesyncd.service. Jul 15 11:04:17.937832 systemd[1]: Reached target time-set.target. Jul 15 11:04:17.939495 systemd-timesyncd[1244]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 15 11:04:17.939621 systemd-timesyncd[1244]: Initial clock synchronization to Tue 2025-07-15 11:04:17.558173 UTC. Jul 15 11:04:17.942455 systemd-resolved[1242]: Positive Trust Anchors: Jul 15 11:04:17.942466 systemd-resolved[1242]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 15 11:04:17.942493 systemd-resolved[1242]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Jul 15 11:04:17.959606 systemd-resolved[1242]: Defaulting to hostname 'linux'. Jul 15 11:04:17.961037 systemd[1]: Started systemd-resolved.service. Jul 15 11:04:17.961682 systemd[1]: Reached target network.target. Jul 15 11:04:17.962268 systemd[1]: Reached target nss-lookup.target. Jul 15 11:04:17.962820 systemd[1]: Reached target sysinit.target. Jul 15 11:04:17.963425 systemd[1]: Started motdgen.path. Jul 15 11:04:17.963963 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 15 11:04:17.964888 systemd[1]: Started logrotate.timer. Jul 15 11:04:17.965491 systemd[1]: Started mdadm.timer. Jul 15 11:04:17.966036 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 15 11:04:17.966617 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 15 11:04:17.966649 systemd[1]: Reached target paths.target. Jul 15 11:04:17.967195 systemd[1]: Reached target timers.target. Jul 15 11:04:17.968073 systemd[1]: Listening on dbus.socket. Jul 15 11:04:17.969798 systemd[1]: Starting docker.socket... Jul 15 11:04:17.971418 systemd[1]: Listening on sshd.socket. Jul 15 11:04:17.972127 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 15 11:04:17.972448 systemd[1]: Listening on docker.socket. Jul 15 11:04:17.973049 systemd[1]: Reached target sockets.target. Jul 15 11:04:17.973637 systemd[1]: Reached target basic.target. Jul 15 11:04:17.974370 systemd[1]: System is tainted: cgroupsv1 Jul 15 11:04:17.974421 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.974440 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 15 11:04:17.975481 systemd[1]: Starting containerd.service... Jul 15 11:04:17.977164 systemd[1]: Starting dbus.service... Jul 15 11:04:17.978757 systemd[1]: Starting enable-oem-cloudinit.service... Jul 15 11:04:17.980715 systemd[1]: Starting extend-filesystems.service... Jul 15 11:04:17.981461 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 15 11:04:17.982716 systemd[1]: Starting motdgen.service... Jul 15 11:04:17.984540 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 15 11:04:17.986978 systemd[1]: Starting sshd-keygen.service... Jul 15 11:04:17.987694 jq[1300]: false Jul 15 11:04:17.989518 systemd[1]: Starting systemd-logind.service... Jul 15 11:04:17.990213 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 15 11:04:17.991022 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 15 11:04:17.992352 systemd[1]: Starting update-engine.service... Jul 15 11:04:17.998799 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 15 11:04:18.001609 jq[1319]: true Jul 15 11:04:18.000902 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 15 11:04:18.001125 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 15 11:04:18.001385 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 15 11:04:18.001565 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 15 11:04:18.009944 extend-filesystems[1301]: Found loop1 Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda1 Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda2 Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda3 Jul 15 11:04:18.009944 extend-filesystems[1301]: Found usr Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda4 Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda6 Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda7 Jul 15 11:04:18.009944 extend-filesystems[1301]: Found vda9 Jul 15 11:04:18.009944 extend-filesystems[1301]: Checking size of /dev/vda9 Jul 15 11:04:18.023119 jq[1323]: true Jul 15 11:04:18.021329 systemd[1]: motdgen.service: Deactivated successfully. Jul 15 11:04:18.021576 systemd[1]: Finished motdgen.service. Jul 15 11:04:18.026036 extend-filesystems[1301]: Resized partition /dev/vda9 Jul 15 11:04:18.030260 extend-filesystems[1341]: resize2fs 1.46.5 (30-Dec-2021) Jul 15 11:04:18.030402 dbus-daemon[1299]: [system] SELinux support is enabled Jul 15 11:04:18.030574 systemd[1]: Started dbus.service. Jul 15 11:04:18.034609 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 15 11:04:18.033592 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Jul 15 11:04:18.033617 systemd[1]: Reached target system-config.target. Jul 15 11:04:18.034566 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 15 11:04:18.034585 systemd[1]: Reached target user-config.target. Jul 15 11:04:18.072220 update_engine[1313]: I0715 11:04:18.072031 1313 main.cc:92] Flatcar Update Engine starting Jul 15 11:04:18.072812 systemd-logind[1308]: Watching system buttons on /dev/input/event0 (Power Button) Jul 15 11:04:18.073315 systemd-logind[1308]: New seat seat0. Jul 15 11:04:18.076677 systemd[1]: Started systemd-logind.service. Jul 15 11:04:18.078853 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 15 11:04:18.079251 systemd[1]: Started update-engine.service. Jul 15 11:04:18.093777 update_engine[1313]: I0715 11:04:18.079279 1313 update_check_scheduler.cc:74] Next update check in 11m7s Jul 15 11:04:18.081516 systemd[1]: Started locksmithd.service. Jul 15 11:04:18.094157 extend-filesystems[1341]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 15 11:04:18.094157 extend-filesystems[1341]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 15 11:04:18.094157 extend-filesystems[1341]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 15 11:04:18.097990 extend-filesystems[1301]: Resized filesystem in /dev/vda9 Jul 15 11:04:18.097273 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 15 11:04:18.099575 env[1324]: time="2025-07-15T11:04:18.095509659Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 15 11:04:18.099752 bash[1353]: Updated "/home/core/.ssh/authorized_keys" Jul 15 11:04:18.097498 systemd[1]: Finished extend-filesystems.service. Jul 15 11:04:18.099000 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 15 11:04:18.115539 env[1324]: time="2025-07-15T11:04:18.115498090Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 15 11:04:18.115762 env[1324]: time="2025-07-15T11:04:18.115743795Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 15 11:04:18.117032 env[1324]: time="2025-07-15T11:04:18.117000662Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.188-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 15 11:04:18.117111 env[1324]: time="2025-07-15T11:04:18.117096011Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 15 11:04:18.117394 env[1324]: time="2025-07-15T11:04:18.117369906Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Jul 15 11:04:18.117477 env[1324]: time="2025-07-15T11:04:18.117462093Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 15 11:04:18.117540 env[1324]: time="2025-07-15T11:04:18.117525976Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 15 11:04:18.117588 env[1324]: time="2025-07-15T11:04:18.117576984Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 15 11:04:18.117710 env[1324]: time="2025-07-15T11:04:18.117693360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 15 11:04:18.118077 env[1324]: time="2025-07-15T11:04:18.118055099Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 15 11:04:18.118293 env[1324]: time="2025-07-15T11:04:18.118271815Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jul 15 11:04:18.118372 env[1324]: time="2025-07-15T11:04:18.118356612Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 15 11:04:18.118484 env[1324]: time="2025-07-15T11:04:18.118465750Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 15 11:04:18.118549 env[1324]: time="2025-07-15T11:04:18.118536529Z" level=info msg="metadata content store policy set" policy=shared Jul 15 11:04:18.121326 env[1324]: time="2025-07-15T11:04:18.121304120Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 15 11:04:18.121420 env[1324]: time="2025-07-15T11:04:18.121403621Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 15 11:04:18.121477 env[1324]: time="2025-07-15T11:04:18.121464381Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 15 11:04:18.121573 env[1324]: time="2025-07-15T11:04:18.121556872Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.121642 env[1324]: time="2025-07-15T11:04:18.121628336Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.121698 env[1324]: time="2025-07-15T11:04:18.121685782Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.121748 env[1324]: time="2025-07-15T11:04:18.121736789Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.122158 env[1324]: time="2025-07-15T11:04:18.122132546Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.122236 env[1324]: time="2025-07-15T11:04:18.122221800Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.122306 env[1324]: time="2025-07-15T11:04:18.122291131Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.122362 env[1324]: time="2025-07-15T11:04:18.122348728Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.122416 env[1324]: time="2025-07-15T11:04:18.122403317Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 15 11:04:18.122563 env[1324]: time="2025-07-15T11:04:18.122543997Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 15 11:04:18.122699 env[1324]: time="2025-07-15T11:04:18.122682049Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 15 11:04:18.123067 env[1324]: time="2025-07-15T11:04:18.123049007Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 15 11:04:18.123164 env[1324]: time="2025-07-15T11:04:18.123149346Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123223 env[1324]: time="2025-07-15T11:04:18.123209877Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 15 11:04:18.123372 env[1324]: time="2025-07-15T11:04:18.123358062Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123427 env[1324]: time="2025-07-15T11:04:18.123415127Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123478 env[1324]: time="2025-07-15T11:04:18.123466744Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123531 env[1324]: time="2025-07-15T11:04:18.123518627Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123588 env[1324]: time="2025-07-15T11:04:18.123576340Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123645 env[1324]: time="2025-07-15T11:04:18.123634014Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123713 env[1324]: time="2025-07-15T11:04:18.123699878Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123764 env[1324]: time="2025-07-15T11:04:18.123753247Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.123846 env[1324]: time="2025-07-15T11:04:18.123823225Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 15 11:04:18.124015 env[1324]: time="2025-07-15T11:04:18.123998381Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.124082 env[1324]: time="2025-07-15T11:04:18.124069540Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.124137 env[1324]: time="2025-07-15T11:04:18.124124738Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.124190 env[1324]: time="2025-07-15T11:04:18.124177879Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 15 11:04:18.124247 env[1324]: time="2025-07-15T11:04:18.124232391Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Jul 15 11:04:18.124300 env[1324]: time="2025-07-15T11:04:18.124287513Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 15 11:04:18.124369 env[1324]: time="2025-07-15T11:04:18.124355167Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 15 11:04:18.124443 env[1324]: time="2025-07-15T11:04:18.124429298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 15 11:04:18.124713 env[1324]: time="2025-07-15T11:04:18.124662089Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} 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}" Jul 15 11:04:18.125289 env[1324]: time="2025-07-15T11:04:18.125062531Z" level=info msg="Connect containerd service" Jul 15 11:04:18.125289 env[1324]: time="2025-07-15T11:04:18.125114606Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 15 11:04:18.125919 env[1324]: time="2025-07-15T11:04:18.125893662Z" 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" Jul 15 11:04:18.126267 env[1324]: time="2025-07-15T11:04:18.126209574Z" level=info msg="Start subscribing containerd event" Jul 15 11:04:18.126312 env[1324]: time="2025-07-15T11:04:18.126270524Z" level=info msg="Start recovering state" Jul 15 11:04:18.126335 env[1324]: time="2025-07-15T11:04:18.126326255Z" level=info msg="Start event monitor" Jul 15 11:04:18.126355 env[1324]: time="2025-07-15T11:04:18.126344388Z" level=info msg="Start snapshots syncer" Jul 15 11:04:18.126374 env[1324]: time="2025-07-15T11:04:18.126354331Z" level=info msg="Start cni network conf syncer for default" Jul 15 11:04:18.126374 env[1324]: time="2025-07-15T11:04:18.126361911Z" level=info msg="Start streaming server" Jul 15 11:04:18.126483 env[1324]: time="2025-07-15T11:04:18.126466517Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 15 11:04:18.126585 env[1324]: time="2025-07-15T11:04:18.126571732Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 15 11:04:18.126685 env[1324]: time="2025-07-15T11:04:18.126672566Z" level=info msg="containerd successfully booted in 0.035640s" Jul 15 11:04:18.126759 systemd[1]: Started containerd.service. Jul 15 11:04:18.129743 locksmithd[1355]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 15 11:04:18.663046 systemd-networkd[1104]: eth0: Gained IPv6LL Jul 15 11:04:18.664696 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 15 11:04:18.665652 systemd[1]: Reached target network-online.target. Jul 15 11:04:18.667809 systemd[1]: Starting kubelet.service... Jul 15 11:04:19.007016 sshd_keygen[1325]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 15 11:04:19.024015 systemd[1]: Finished sshd-keygen.service. Jul 15 11:04:19.026140 systemd[1]: Starting issuegen.service... Jul 15 11:04:19.030883 systemd[1]: issuegen.service: Deactivated successfully. Jul 15 11:04:19.031086 systemd[1]: Finished issuegen.service. Jul 15 11:04:19.033086 systemd[1]: Starting systemd-user-sessions.service... Jul 15 11:04:19.040380 systemd[1]: Finished systemd-user-sessions.service. Jul 15 11:04:19.042554 systemd[1]: Started getty@tty1.service. Jul 15 11:04:19.044613 systemd[1]: Started serial-getty@ttyAMA0.service. Jul 15 11:04:19.045505 systemd[1]: Reached target getty.target. Jul 15 11:04:19.227198 systemd[1]: Started kubelet.service. Jul 15 11:04:19.228282 systemd[1]: Reached target multi-user.target. Jul 15 11:04:19.230959 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 15 11:04:19.236575 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 15 11:04:19.236788 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 15 11:04:19.237932 systemd[1]: Startup finished in 4.637s (kernel) + 4.609s (userspace) = 9.247s. Jul 15 11:04:19.656376 kubelet[1392]: E0715 11:04:19.656335 1392 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 15 11:04:19.658184 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 15 11:04:19.658319 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 15 11:04:22.868449 systemd[1]: Created slice system-sshd.slice. Jul 15 11:04:22.869545 systemd[1]: Started sshd@0-10.0.0.27:22-10.0.0.1:57846.service. Jul 15 11:04:22.914620 sshd[1402]: Accepted publickey for core from 10.0.0.1 port 57846 ssh2: RSA SHA256:j1wDR6gweCSngZuaE8kL1fszhDF+Tuwb03sE4/bYQBA Jul 15 11:04:22.917031 sshd[1402]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 15 11:04:22.929305 systemd-logind[1308]: New session 1 of user core. Jul 15 11:04:22.930131 systemd[1]: Created slice user-500.slice. Jul 15 11:04:22.931083 systemd[1]: Starting user-runtime-dir@500.service... Jul 15 11:04:22.939659 systemd[1]: Finished user-runtime-dir@500.service. Jul 15 11:04:22.940989 systemd[1]: Starting user@500.service... Jul 15 11:04:22.944014 (systemd)[1407]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 15 11:04:23.003805 systemd[1407]: Queued start job for default target default.target. Jul 15 11:04:23.004046 systemd[1407]: Reached target paths.target. Jul 15 11:04:23.004061 systemd[1407]: Reached target sockets.target. Jul 15 11:04:23.004071 systemd[1407]: Reached target timers.target. Jul 15 11:04:23.004080 systemd[1407]: Reached target basic.target. Jul 15 11:04:23.004118 systemd[1407]: Reached target default.target. Jul 15 11:04:23.004139 systemd[1407]: Startup finished in 54ms. Jul 15 11:04:23.004349 systemd[1]: Started user@500.service. Jul 15 11:04:23.006133 systemd[1]: Started session-1.scope. Jul 15 11:04:23.055967 systemd[1]: Started sshd@1-10.0.0.27:22-10.0.0.1:57856.service. Jul 15 11:04:23.095335 sshd[1416]: Accepted publickey for core from 10.0.0.1 port 57856 ssh2: RSA SHA256:j1wDR6gweCSngZuaE8kL1fszhDF+Tuwb03sE4/bYQBA Jul 15 11:04:23.096559 sshd[1416]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 15 11:04:23.100893 systemd[1]: Started session-2.scope. Jul 15 11:04:23.101617 systemd-logind[1308]: New session 2 of user core. Jul 15 11:04:23.157206 sshd[1416]: pam_unix(sshd:session): session closed for user core Jul 15 11:04:23.159648 systemd[1]: Started sshd@2-10.0.0.27:22-10.0.0.1:57870.service. Jul 15 11:04:23.160972 systemd[1]: sshd@1-10.0.0.27:22-10.0.0.1:57856.service: Deactivated successfully. Jul 15 11:04:23.162044 systemd[1]: session-2.scope: Deactivated successfully. Jul 15 11:04:23.162059 systemd-logind[1308]: Session 2 logged out. Waiting for processes to exit. Jul 15 11:04:23.163142 systemd-logind[1308]: Removed session 2. Jul 15 11:04:23.199389 sshd[1421]: Accepted publickey for core from 10.0.0.1 port 57870 ssh2: RSA SHA256:j1wDR6gweCSngZuaE8kL1fszhDF+Tuwb03sE4/bYQBA Jul 15 11:04:23.200871 sshd[1421]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 15 11:04:23.204169 systemd-logind[1308]: New session 3 of user core. Jul 15 11:04:23.205004 systemd[1]: Started session-3.scope. Jul 15 11:04:23.253947 sshd[1421]: pam_unix(sshd:session): session closed for user core Jul 15 11:04:23.256252 systemd[1]: Started sshd@3-10.0.0.27:22-10.0.0.1:57882.service. Jul 15 11:04:23.256747 systemd[1]: sshd@2-10.0.0.27:22-10.0.0.1:57870.service: Deactivated successfully. Jul 15 11:04:23.257770 systemd-logind[1308]: Session 3 logged out. Waiting for processes to exit. Jul 15 11:04:23.257816 systemd[1]: session-3.scope: Deactivated successfully. Jul 15 11:04:23.258583 systemd-logind[1308]: Removed session 3. Jul 15 11:04:23.299221 sshd[1428]: Accepted publickey for core from 10.0.0.1 port 57882 ssh2: RSA SHA256:j1wDR6gweCSngZuaE8kL1fszhDF+Tuwb03sE4/bYQBA Jul 15 11:04:23.300434 sshd[1428]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 15 11:04:23.303710 systemd-logind[1308]: New session 4 of user core. Jul 15 11:04:23.304522 systemd[1]: Started session-4.scope. Jul 15 11:04:23.356805 sshd[1428]: pam_unix(sshd:session): session closed for user core Jul 15 11:04:23.358930 systemd[1]: Started sshd@4-10.0.0.27:22-10.0.0.1:57888.service. Jul 15 11:04:23.359902 systemd[1]: sshd@3-10.0.0.27:22-10.0.0.1:57882.service: Deactivated successfully. Jul 15 11:04:23.360981 systemd-logind[1308]: Session 4 logged out. Waiting for processes to exit. Jul 15 11:04:23.361206 systemd[1]: session-4.scope: Deactivated successfully. Jul 15 11:04:23.362123 systemd-logind[1308]: Removed session 4. Jul 15 11:04:23.399039 sshd[1435]: Accepted publickey for core from 10.0.0.1 port 57888 ssh2: RSA SHA256:j1wDR6gweCSngZuaE8kL1fszhDF+Tuwb03sE4/bYQBA Jul 15 11:04:23.400231 sshd[1435]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 15 11:04:23.403744 systemd-logind[1308]: New session 5 of user core. Jul 15 11:04:23.405649 systemd[1]: Started session-5.scope. Jul 15 11:04:23.462772 sudo[1441]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 15 11:04:23.463016 sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 15 11:04:23.475508 systemd[1]: Starting coreos-metadata.service... Jul 15 11:04:23.481970 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 15 11:04:23.482200 systemd[1]: Finished coreos-metadata.service. Jul 15 11:04:23.936201 systemd[1]: Stopped kubelet.service. Jul 15 11:04:23.938235 systemd[1]: Starting kubelet.service... Jul 15 11:04:23.960624 systemd[1]: Reloading. Jul 15 11:04:24.010421 /usr/lib/systemd/system-generators/torcx-generator[1504]: time="2025-07-15T11:04:24Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.100 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.100 /var/lib/torcx/store]" Jul 15 11:04:24.010450 /usr/lib/systemd/system-generators/torcx-generator[1504]: time="2025-07-15T11:04:24Z" level=info msg="torcx already run" Jul 15 11:04:24.084429 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 15 11:04:24.084558 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 15 11:04:24.099710 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 15 11:04:24.158152 systemd[1]: Started kubelet.service. Jul 15 11:04:24.160741 systemd[1]: Stopping kubelet.service... Jul 15 11:04:24.161129 systemd[1]: kubelet.service: Deactivated successfully. Jul 15 11:04:24.161343 systemd[1]: Stopped kubelet.service. Jul 15 11:04:24.162882 systemd[1]: Starting kubelet.service... Jul 15 11:04:24.251908 systemd[1]: Started kubelet.service. Jul 15 11:04:24.290680 kubelet[1564]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Jul 15 11:04:24.291017 kubelet[1564]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Jul 15 11:04:24.291062 kubelet[1564]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Jul 15 11:04:24.291181 kubelet[1564]: I0715 11:04:24.291154 1564 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jul 15 11:04:25.066141 kubelet[1564]: I0715 11:04:25.066102 1564 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Jul 15 11:04:25.066141 kubelet[1564]: I0715 11:04:25.066134 1564 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 15 11:04:25.067362 kubelet[1564]: I0715 11:04:25.067326 1564 server.go:934] "Client rotation is on, will bootstrap in background" Jul 15 11:04:25.106014 kubelet[1564]: I0715 11:04:25.105983 1564 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 15 11:04:25.119150 kubelet[1564]: E0715 11:04:25.119114 1564 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Jul 15 11:04:25.119150 kubelet[1564]: I0715 11:04:25.119150 1564 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Jul 15 11:04:25.123394 kubelet[1564]: I0715 11:04:25.123364 1564 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 15 11:04:25.124416 kubelet[1564]: I0715 11:04:25.124380 1564 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Jul 15 11:04:25.124542 kubelet[1564]: I0715 11:04:25.124513 1564 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 15 11:04:25.124699 kubelet[1564]: I0715 11:04:25.124546 1564 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.27","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":1} Jul 15 11:04:25.124791 kubelet[1564]: I0715 11:04:25.124782 1564 topology_manager.go:138] "Creating topology manager with none policy" Jul 15 11:04:25.124827 kubelet[1564]: I0715 11:04:25.124793 1564 container_manager_linux.go:300] "Creating device plugin manager" Jul 15 11:04:25.125036 kubelet[1564]: I0715 11:04:25.125025 1564 state_mem.go:36] "Initialized new in-memory state store" Jul 15 11:04:25.128905 kubelet[1564]: I0715 11:04:25.128872 1564 kubelet.go:408] "Attempting to sync node with API server" Jul 15 11:04:25.128905 kubelet[1564]: I0715 11:04:25.128907 1564 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 15 11:04:25.128977 kubelet[1564]: I0715 11:04:25.128928 1564 kubelet.go:314] "Adding apiserver pod source" Jul 15 11:04:25.128977 kubelet[1564]: I0715 11:04:25.128940 1564 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 15 11:04:25.129030 kubelet[1564]: E0715 11:04:25.129006 1564 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:25.129091 kubelet[1564]: E0715 11:04:25.129036 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:25.142703 kubelet[1564]: I0715 11:04:25.142681 1564 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Jul 15 11:04:25.143569 kubelet[1564]: I0715 11:04:25.143552 1564 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 15 11:04:25.143748 kubelet[1564]: W0715 11:04:25.143736 1564 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 15 11:04:25.150798 kubelet[1564]: I0715 11:04:25.150774 1564 server.go:1274] "Started kubelet" Jul 15 11:04:25.152317 kubelet[1564]: I0715 11:04:25.152150 1564 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 15 11:04:25.152506 kubelet[1564]: I0715 11:04:25.152467 1564 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 15 11:04:25.152541 kubelet[1564]: I0715 11:04:25.152528 1564 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Jul 15 11:04:25.153758 kubelet[1564]: I0715 11:04:25.153725 1564 server.go:449] "Adding debug handlers to kubelet server" Jul 15 11:04:25.154036 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Jul 15 11:04:25.154188 kubelet[1564]: I0715 11:04:25.154146 1564 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 15 11:04:25.157781 kubelet[1564]: I0715 11:04:25.157747 1564 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jul 15 11:04:25.159235 kubelet[1564]: I0715 11:04:25.159207 1564 volume_manager.go:289] "Starting Kubelet Volume Manager" Jul 15 11:04:25.159424 kubelet[1564]: E0715 11:04:25.159400 1564 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.27\" not found" Jul 15 11:04:25.159855 kubelet[1564]: I0715 11:04:25.159634 1564 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Jul 15 11:04:25.160703 kubelet[1564]: I0715 11:04:25.160673 1564 factory.go:221] Registration of the systemd container factory successfully Jul 15 11:04:25.160862 kubelet[1564]: I0715 11:04:25.160798 1564 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Jul 15 11:04:25.161177 kubelet[1564]: I0715 11:04:25.161151 1564 reconciler.go:26] "Reconciler: start to sync state" Jul 15 11:04:25.163675 kubelet[1564]: E0715 11:04:25.163606 1564 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 15 11:04:25.164137 kubelet[1564]: I0715 11:04:25.164121 1564 factory.go:221] Registration of the containerd container factory successfully Jul 15 11:04:25.167307 kubelet[1564]: E0715 11:04:25.167275 1564 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.27\" not found" node="10.0.0.27" Jul 15 11:04:25.178156 kubelet[1564]: I0715 11:04:25.178140 1564 cpu_manager.go:214] "Starting CPU manager" policy="none" Jul 15 11:04:25.178431 kubelet[1564]: I0715 11:04:25.178413 1564 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jul 15 11:04:25.178499 kubelet[1564]: I0715 11:04:25.178489 1564 state_mem.go:36] "Initialized new in-memory state store" Jul 15 11:04:25.260485 kubelet[1564]: E0715 11:04:25.260446 1564 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.27\" not found" Jul 15 11:04:25.269149 kubelet[1564]: I0715 11:04:25.269127 1564 policy_none.go:49] "None policy: Start" Jul 15 11:04:25.269905 kubelet[1564]: I0715 11:04:25.269889 1564 memory_manager.go:170] "Starting memorymanager" policy="None" Jul 15 11:04:25.270019 kubelet[1564]: I0715 11:04:25.270011 1564 state_mem.go:35] "Initializing new in-memory state store" Jul 15 11:04:25.274457 kubelet[1564]: I0715 11:04:25.274435 1564 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 15 11:04:25.274681 kubelet[1564]: I0715 11:04:25.274666 1564 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 15 11:04:25.274767 kubelet[1564]: I0715 11:04:25.274737 1564 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 15 11:04:25.275195 kubelet[1564]: I0715 11:04:25.275177 1564 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 15 11:04:25.276419 kubelet[1564]: E0715 11:04:25.276396 1564 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.27\" not found" Jul 15 11:04:25.308181 kubelet[1564]: I0715 11:04:25.308141 1564 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 15 11:04:25.309221 kubelet[1564]: I0715 11:04:25.309193 1564 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 15 11:04:25.309221 kubelet[1564]: I0715 11:04:25.309222 1564 status_manager.go:217] "Starting to sync pod status with apiserver" Jul 15 11:04:25.309309 kubelet[1564]: I0715 11:04:25.309240 1564 kubelet.go:2321] "Starting kubelet main sync loop" Jul 15 11:04:25.309309 kubelet[1564]: E0715 11:04:25.309283 1564 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Jul 15 11:04:25.376759 kubelet[1564]: I0715 11:04:25.376656 1564 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.27" Jul 15 11:04:25.385169 kubelet[1564]: I0715 11:04:25.385137 1564 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.27" Jul 15 11:04:25.391441 kubelet[1564]: I0715 11:04:25.391400 1564 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 15 11:04:25.391761 env[1324]: time="2025-07-15T11:04:25.391720802Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 15 11:04:25.392068 kubelet[1564]: I0715 11:04:25.391893 1564 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 15 11:04:25.772488 sudo[1441]: pam_unix(sudo:session): session closed for user root Jul 15 11:04:25.774189 sshd[1435]: pam_unix(sshd:session): session closed for user core Jul 15 11:04:25.776389 systemd[1]: sshd@4-10.0.0.27:22-10.0.0.1:57888.service: Deactivated successfully. Jul 15 11:04:25.777230 systemd[1]: session-5.scope: Deactivated successfully. Jul 15 11:04:25.778060 systemd-logind[1308]: Session 5 logged out. Waiting for processes to exit. Jul 15 11:04:25.778754 systemd-logind[1308]: Removed session 5. Jul 15 11:04:26.070733 kubelet[1564]: I0715 11:04:26.070583 1564 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 15 11:04:26.070860 kubelet[1564]: W0715 11:04:26.070754 1564 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 15 11:04:26.070984 kubelet[1564]: W0715 11:04:26.070913 1564 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 15 11:04:26.070984 kubelet[1564]: W0715 11:04:26.070970 1564 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 15 11:04:26.129577 kubelet[1564]: I0715 11:04:26.129529 1564 apiserver.go:52] "Watching apiserver" Jul 15 11:04:26.129577 kubelet[1564]: E0715 11:04:26.129539 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:26.161070 kubelet[1564]: I0715 11:04:26.161029 1564 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Jul 15 11:04:26.164951 kubelet[1564]: I0715 11:04:26.164919 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sschn\" (UniqueName: \"kubernetes.io/projected/ba8be627-c18e-45f7-8269-f3bbc85188a4-kube-api-access-sschn\") pod \"kube-flannel-ds-vxfdd\" (UID: \"ba8be627-c18e-45f7-8269-f3bbc85188a4\") " pod="kube-flannel/kube-flannel-ds-vxfdd" Jul 15 11:04:26.164951 kubelet[1564]: I0715 11:04:26.164950 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qzk4d\" (UniqueName: \"kubernetes.io/projected/36afc6a1-610f-44f5-99e8-4cdd1ffda3af-kube-api-access-qzk4d\") pod \"kube-proxy-czgdp\" (UID: \"36afc6a1-610f-44f5-99e8-4cdd1ffda3af\") " pod="kube-system/kube-proxy-czgdp" Jul 15 11:04:26.165056 kubelet[1564]: I0715 11:04:26.164969 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/ba8be627-c18e-45f7-8269-f3bbc85188a4-run\") pod \"kube-flannel-ds-vxfdd\" (UID: \"ba8be627-c18e-45f7-8269-f3bbc85188a4\") " pod="kube-flannel/kube-flannel-ds-vxfdd" Jul 15 11:04:26.165056 kubelet[1564]: I0715 11:04:26.164985 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/36afc6a1-610f-44f5-99e8-4cdd1ffda3af-lib-modules\") pod \"kube-proxy-czgdp\" (UID: \"36afc6a1-610f-44f5-99e8-4cdd1ffda3af\") " pod="kube-system/kube-proxy-czgdp" Jul 15 11:04:26.165056 kubelet[1564]: I0715 11:04:26.165001 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/ba8be627-c18e-45f7-8269-f3bbc85188a4-cni-plugin\") pod \"kube-flannel-ds-vxfdd\" (UID: \"ba8be627-c18e-45f7-8269-f3bbc85188a4\") " pod="kube-flannel/kube-flannel-ds-vxfdd" Jul 15 11:04:26.165056 kubelet[1564]: I0715 11:04:26.165014 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/ba8be627-c18e-45f7-8269-f3bbc85188a4-cni\") pod \"kube-flannel-ds-vxfdd\" (UID: \"ba8be627-c18e-45f7-8269-f3bbc85188a4\") " pod="kube-flannel/kube-flannel-ds-vxfdd" Jul 15 11:04:26.165208 kubelet[1564]: I0715 11:04:26.165079 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/ba8be627-c18e-45f7-8269-f3bbc85188a4-flannel-cfg\") pod \"kube-flannel-ds-vxfdd\" (UID: \"ba8be627-c18e-45f7-8269-f3bbc85188a4\") " pod="kube-flannel/kube-flannel-ds-vxfdd" Jul 15 11:04:26.165208 kubelet[1564]: I0715 11:04:26.165134 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/ba8be627-c18e-45f7-8269-f3bbc85188a4-xtables-lock\") pod \"kube-flannel-ds-vxfdd\" (UID: \"ba8be627-c18e-45f7-8269-f3bbc85188a4\") " pod="kube-flannel/kube-flannel-ds-vxfdd" Jul 15 11:04:26.165208 kubelet[1564]: I0715 11:04:26.165166 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/36afc6a1-610f-44f5-99e8-4cdd1ffda3af-kube-proxy\") pod \"kube-proxy-czgdp\" (UID: \"36afc6a1-610f-44f5-99e8-4cdd1ffda3af\") " pod="kube-system/kube-proxy-czgdp" Jul 15 11:04:26.165208 kubelet[1564]: I0715 11:04:26.165203 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/36afc6a1-610f-44f5-99e8-4cdd1ffda3af-xtables-lock\") pod \"kube-proxy-czgdp\" (UID: \"36afc6a1-610f-44f5-99e8-4cdd1ffda3af\") " pod="kube-system/kube-proxy-czgdp" Jul 15 11:04:26.273265 kubelet[1564]: I0715 11:04:26.273224 1564 swap_util.go:74] "error creating dir to test if tmpfs noswap is enabled. Assuming not supported" mount path="" error="stat /var/lib/kubelet/plugins/kubernetes.io/empty-dir: no such file or directory" Jul 15 11:04:26.435873 kubelet[1564]: E0715 11:04:26.435773 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:26.436367 kubelet[1564]: E0715 11:04:26.436225 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:26.437059 env[1324]: time="2025-07-15T11:04:26.436809398Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-czgdp,Uid:36afc6a1-610f-44f5-99e8-4cdd1ffda3af,Namespace:kube-system,Attempt:0,}" Jul 15 11:04:26.437059 env[1324]: time="2025-07-15T11:04:26.436908508Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vxfdd,Uid:ba8be627-c18e-45f7-8269-f3bbc85188a4,Namespace:kube-flannel,Attempt:0,}" Jul 15 11:04:27.077985 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1290096356.mount: Deactivated successfully. Jul 15 11:04:27.082420 env[1324]: time="2025-07-15T11:04:27.082363643Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.083608 env[1324]: time="2025-07-15T11:04:27.083576769Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.084572 env[1324]: time="2025-07-15T11:04:27.084538469Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.086112 env[1324]: time="2025-07-15T11:04:27.086087355Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.086833 env[1324]: time="2025-07-15T11:04:27.086804845Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.089017 env[1324]: time="2025-07-15T11:04:27.088986769Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.090806 env[1324]: time="2025-07-15T11:04:27.090328269Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.092620 env[1324]: time="2025-07-15T11:04:27.092593619Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:27.112215 env[1324]: time="2025-07-15T11:04:27.111992240Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 15 11:04:27.112215 env[1324]: time="2025-07-15T11:04:27.112041445Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 15 11:04:27.112215 env[1324]: time="2025-07-15T11:04:27.112051617Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 15 11:04:27.112502 env[1324]: time="2025-07-15T11:04:27.112450617Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 15 11:04:27.112502 env[1324]: time="2025-07-15T11:04:27.112483420Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 15 11:04:27.112502 env[1324]: time="2025-07-15T11:04:27.112493277Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 15 11:04:27.112502 env[1324]: time="2025-07-15T11:04:27.112469266Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad pid=1627 runtime=io.containerd.runc.v2 Jul 15 11:04:27.112649 env[1324]: time="2025-07-15T11:04:27.112617590Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/e09e70116661c6644a48ac2bf0d640452a3b1c003863cef404106297d24c3015 pid=1628 runtime=io.containerd.runc.v2 Jul 15 11:04:27.129828 kubelet[1564]: E0715 11:04:27.129778 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:27.168665 env[1324]: time="2025-07-15T11:04:27.168613747Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-czgdp,Uid:36afc6a1-610f-44f5-99e8-4cdd1ffda3af,Namespace:kube-system,Attempt:0,} returns sandbox id \"e09e70116661c6644a48ac2bf0d640452a3b1c003863cef404106297d24c3015\"" Jul 15 11:04:27.170101 kubelet[1564]: E0715 11:04:27.170067 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:27.171873 env[1324]: time="2025-07-15T11:04:27.171498691Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\"" Jul 15 11:04:27.173412 env[1324]: time="2025-07-15T11:04:27.173381049Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vxfdd,Uid:ba8be627-c18e-45f7-8269-f3bbc85188a4,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad\"" Jul 15 11:04:27.174083 kubelet[1564]: E0715 11:04:27.173950 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:28.129892 kubelet[1564]: E0715 11:04:28.129854 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:28.162606 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1564805835.mount: Deactivated successfully. Jul 15 11:04:28.604243 env[1324]: time="2025-07-15T11:04:28.604029747Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.31.10,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:28.605213 env[1324]: time="2025-07-15T11:04:28.605182985Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:dde5ff0da443b455e81aefc7bf6a216fdd659d1cbe13b8e8ac8129c3ecd27f89,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:28.606451 env[1324]: time="2025-07-15T11:04:28.606389270Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.31.10,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:28.607537 env[1324]: time="2025-07-15T11:04:28.607513557Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:28.608464 env[1324]: time="2025-07-15T11:04:28.608439836Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\" returns image reference \"sha256:dde5ff0da443b455e81aefc7bf6a216fdd659d1cbe13b8e8ac8129c3ecd27f89\"" Jul 15 11:04:28.610054 env[1324]: time="2025-07-15T11:04:28.610020410Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 15 11:04:28.610845 env[1324]: time="2025-07-15T11:04:28.610790749Z" level=info msg="CreateContainer within sandbox \"e09e70116661c6644a48ac2bf0d640452a3b1c003863cef404106297d24c3015\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 15 11:04:28.620194 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1042494120.mount: Deactivated successfully. Jul 15 11:04:28.623265 env[1324]: time="2025-07-15T11:04:28.623232667Z" level=info msg="CreateContainer within sandbox \"e09e70116661c6644a48ac2bf0d640452a3b1c003863cef404106297d24c3015\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"b556dc1c7f672df32835cd1c95647717d2694d6464d541737fec31b51119b2c4\"" Jul 15 11:04:28.623893 env[1324]: time="2025-07-15T11:04:28.623869818Z" level=info msg="StartContainer for \"b556dc1c7f672df32835cd1c95647717d2694d6464d541737fec31b51119b2c4\"" Jul 15 11:04:28.683860 env[1324]: time="2025-07-15T11:04:28.680722458Z" level=info msg="StartContainer for \"b556dc1c7f672df32835cd1c95647717d2694d6464d541737fec31b51119b2c4\" returns successfully" Jul 15 11:04:29.130567 kubelet[1564]: E0715 11:04:29.130533 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:29.316935 kubelet[1564]: E0715 11:04:29.316894 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:29.740132 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3987385779.mount: Deactivated successfully. Jul 15 11:04:29.776440 env[1324]: time="2025-07-15T11:04:29.776398284Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:29.777492 env[1324]: time="2025-07-15T11:04:29.777443452Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:29.779502 env[1324]: time="2025-07-15T11:04:29.779467799Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:29.780420 env[1324]: time="2025-07-15T11:04:29.780389655Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:29.781110 env[1324]: time="2025-07-15T11:04:29.781063619Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 15 11:04:29.783055 env[1324]: time="2025-07-15T11:04:29.783014818Z" level=info msg="CreateContainer within sandbox \"3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 15 11:04:29.791687 env[1324]: time="2025-07-15T11:04:29.791647351Z" level=info msg="CreateContainer within sandbox \"3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"997e436eb0586e3eadca78f1756f588335d65b4d671944ea0add785f90c5273b\"" Jul 15 11:04:29.792101 env[1324]: time="2025-07-15T11:04:29.792077817Z" level=info msg="StartContainer for \"997e436eb0586e3eadca78f1756f588335d65b4d671944ea0add785f90c5273b\"" Jul 15 11:04:29.845423 env[1324]: time="2025-07-15T11:04:29.845370883Z" level=info msg="StartContainer for \"997e436eb0586e3eadca78f1756f588335d65b4d671944ea0add785f90c5273b\" returns successfully" Jul 15 11:04:30.006151 env[1324]: time="2025-07-15T11:04:30.006036391Z" level=info msg="shim disconnected" id=997e436eb0586e3eadca78f1756f588335d65b4d671944ea0add785f90c5273b Jul 15 11:04:30.006151 env[1324]: time="2025-07-15T11:04:30.006081118Z" level=warning msg="cleaning up after shim disconnected" id=997e436eb0586e3eadca78f1756f588335d65b4d671944ea0add785f90c5273b namespace=k8s.io Jul 15 11:04:30.006151 env[1324]: time="2025-07-15T11:04:30.006091576Z" level=info msg="cleaning up dead shim" Jul 15 11:04:30.012371 env[1324]: time="2025-07-15T11:04:30.012334909Z" level=warning msg="cleanup warnings time=\"2025-07-15T11:04:30Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1909 runtime=io.containerd.runc.v2\n" Jul 15 11:04:30.131210 kubelet[1564]: E0715 11:04:30.131176 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:30.319254 kubelet[1564]: E0715 11:04:30.319180 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:30.319254 kubelet[1564]: E0715 11:04:30.319204 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:30.320040 env[1324]: time="2025-07-15T11:04:30.319996165Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 15 11:04:30.328968 kubelet[1564]: I0715 11:04:30.328916 1564 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-czgdp" podStartSLOduration=3.890663129 podStartE2EDuration="5.328903202s" podCreationTimestamp="2025-07-15 11:04:25 +0000 UTC" firstStartedPulling="2025-07-15 11:04:27.170965482 +0000 UTC m=+2.915922397" lastFinishedPulling="2025-07-15 11:04:28.609205555 +0000 UTC m=+4.354162470" observedRunningTime="2025-07-15 11:04:29.327538754 +0000 UTC m=+5.072495670" watchObservedRunningTime="2025-07-15 11:04:30.328903202 +0000 UTC m=+6.073860157" Jul 15 11:04:31.131912 kubelet[1564]: E0715 11:04:31.131881 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:31.486242 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount578105700.mount: Deactivated successfully. Jul 15 11:04:32.132277 kubelet[1564]: E0715 11:04:32.132215 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:32.133313 env[1324]: time="2025-07-15T11:04:32.133276079Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:32.134443 env[1324]: time="2025-07-15T11:04:32.134417191Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:32.135896 env[1324]: time="2025-07-15T11:04:32.135860585Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:32.138374 env[1324]: time="2025-07-15T11:04:32.138339791Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:32.138688 env[1324]: time="2025-07-15T11:04:32.138653350Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 15 11:04:32.141235 env[1324]: time="2025-07-15T11:04:32.141204265Z" level=info msg="CreateContainer within sandbox \"3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 15 11:04:32.149550 env[1324]: time="2025-07-15T11:04:32.149509737Z" level=info msg="CreateContainer within sandbox \"3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"84dfd2db8d30f0585f4b2d90ebb2fda4fc296e2b8e3aea166a251d3318901e63\"" Jul 15 11:04:32.150022 env[1324]: time="2025-07-15T11:04:32.149995064Z" level=info msg="StartContainer for \"84dfd2db8d30f0585f4b2d90ebb2fda4fc296e2b8e3aea166a251d3318901e63\"" Jul 15 11:04:32.197028 env[1324]: time="2025-07-15T11:04:32.196984232Z" level=info msg="StartContainer for \"84dfd2db8d30f0585f4b2d90ebb2fda4fc296e2b8e3aea166a251d3318901e63\" returns successfully" Jul 15 11:04:32.215330 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-84dfd2db8d30f0585f4b2d90ebb2fda4fc296e2b8e3aea166a251d3318901e63-rootfs.mount: Deactivated successfully. Jul 15 11:04:32.284797 kubelet[1564]: I0715 11:04:32.284764 1564 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Jul 15 11:04:32.323552 kubelet[1564]: E0715 11:04:32.323521 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:32.324254 env[1324]: time="2025-07-15T11:04:32.324214712Z" level=info msg="shim disconnected" id=84dfd2db8d30f0585f4b2d90ebb2fda4fc296e2b8e3aea166a251d3318901e63 Jul 15 11:04:32.324346 env[1324]: time="2025-07-15T11:04:32.324258826Z" level=warning msg="cleaning up after shim disconnected" id=84dfd2db8d30f0585f4b2d90ebb2fda4fc296e2b8e3aea166a251d3318901e63 namespace=k8s.io Jul 15 11:04:32.324346 env[1324]: time="2025-07-15T11:04:32.324269427Z" level=info msg="cleaning up dead shim" Jul 15 11:04:32.331653 env[1324]: time="2025-07-15T11:04:32.331614252Z" level=warning msg="cleanup warnings time=\"2025-07-15T11:04:32Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1963 runtime=io.containerd.runc.v2\n" Jul 15 11:04:33.132811 kubelet[1564]: E0715 11:04:33.132771 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:33.326178 kubelet[1564]: E0715 11:04:33.326149 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:33.328068 env[1324]: time="2025-07-15T11:04:33.328035097Z" level=info msg="CreateContainer within sandbox \"3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 15 11:04:33.338065 env[1324]: time="2025-07-15T11:04:33.338022778Z" level=info msg="CreateContainer within sandbox \"3d237e829fd68749da7228dfe158a1a16b2641083263a9c5869cce88235f90ad\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"74d80733f05dbceb8bf1c068e60ab6345aefbfffb6b40066d19b1922a59edc74\"" Jul 15 11:04:33.338538 env[1324]: time="2025-07-15T11:04:33.338504262Z" level=info msg="StartContainer for \"74d80733f05dbceb8bf1c068e60ab6345aefbfffb6b40066d19b1922a59edc74\"" Jul 15 11:04:33.387953 env[1324]: time="2025-07-15T11:04:33.387867152Z" level=info msg="StartContainer for \"74d80733f05dbceb8bf1c068e60ab6345aefbfffb6b40066d19b1922a59edc74\" returns successfully" Jul 15 11:04:34.133687 kubelet[1564]: E0715 11:04:34.133654 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:34.330082 kubelet[1564]: E0715 11:04:34.330056 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:34.457285 systemd-networkd[1104]: flannel.1: Link UP Jul 15 11:04:34.457292 systemd-networkd[1104]: flannel.1: Gained carrier Jul 15 11:04:35.134604 kubelet[1564]: E0715 11:04:35.134564 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:35.331576 kubelet[1564]: E0715 11:04:35.331543 1564 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 15 11:04:35.686996 systemd-networkd[1104]: flannel.1: Gained IPv6LL Jul 15 11:04:36.135084 kubelet[1564]: E0715 11:04:36.134803 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:36.368493 kubelet[1564]: I0715 11:04:36.368438 1564 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-vxfdd" podStartSLOduration=6.403228789 podStartE2EDuration="11.368417267s" podCreationTimestamp="2025-07-15 11:04:25 +0000 UTC" firstStartedPulling="2025-07-15 11:04:27.174681663 +0000 UTC m=+2.919638579" lastFinishedPulling="2025-07-15 11:04:32.139870142 +0000 UTC m=+7.884827057" observedRunningTime="2025-07-15 11:04:34.356144363 +0000 UTC m=+10.101101278" watchObservedRunningTime="2025-07-15 11:04:36.368417267 +0000 UTC m=+12.113374183" Jul 15 11:04:36.512425 kubelet[1564]: I0715 11:04:36.512082 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dspj6\" (UniqueName: \"kubernetes.io/projected/6a9b4a3d-e057-4364-85a4-58d43232cb24-kube-api-access-dspj6\") pod \"nginx-deployment-8587fbcb89-nhttv\" (UID: \"6a9b4a3d-e057-4364-85a4-58d43232cb24\") " pod="default/nginx-deployment-8587fbcb89-nhttv" Jul 15 11:04:36.672014 env[1324]: time="2025-07-15T11:04:36.671969619Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-nhttv,Uid:6a9b4a3d-e057-4364-85a4-58d43232cb24,Namespace:default,Attempt:0,}" Jul 15 11:04:36.704185 systemd-networkd[1104]: cni0: Link UP Jul 15 11:04:36.711496 systemd-networkd[1104]: vethb40a8a10: Link UP Jul 15 11:04:36.713023 kernel: cni0: port 1(vethb40a8a10) entered blocking state Jul 15 11:04:36.713057 kernel: cni0: port 1(vethb40a8a10) entered disabled state Jul 15 11:04:36.713075 kernel: device vethb40a8a10 entered promiscuous mode Jul 15 11:04:36.714087 kernel: cni0: port 1(vethb40a8a10) entered blocking state Jul 15 11:04:36.714128 kernel: cni0: port 1(vethb40a8a10) entered forwarding state Jul 15 11:04:36.715865 kernel: cni0: port 1(vethb40a8a10) entered disabled state Jul 15 11:04:36.725285 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethb40a8a10: link becomes ready Jul 15 11:04:36.725572 kernel: cni0: port 1(vethb40a8a10) entered blocking state Jul 15 11:04:36.725592 kernel: cni0: port 1(vethb40a8a10) entered forwarding state Jul 15 11:04:36.725608 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): cni0: link becomes ready Jul 15 11:04:36.725355 systemd-networkd[1104]: vethb40a8a10: Gained carrier Jul 15 11:04:36.725919 systemd-networkd[1104]: cni0: Gained carrier Jul 15 11:04:36.727951 env[1324]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40001148e8), "name":"cbr0", "type":"bridge"} Jul 15 11:04:36.727951 env[1324]: delegateAdd: netconf sent to delegate plugin: Jul 15 11:04:36.740243 env[1324]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-15T11:04:36.740172666Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 15 11:04:36.740243 env[1324]: time="2025-07-15T11:04:36.740219822Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 15 11:04:36.740243 env[1324]: time="2025-07-15T11:04:36.740229978Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 15 11:04:36.740475 env[1324]: time="2025-07-15T11:04:36.740436684Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/3e8d06adb6eeddde89fb2b93a23c279778111b3ea78a33c29c6552363ecdf4fd pid=2147 runtime=io.containerd.runc.v2 Jul 15 11:04:36.773622 systemd-resolved[1242]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 15 11:04:36.789707 env[1324]: time="2025-07-15T11:04:36.789650246Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-nhttv,Uid:6a9b4a3d-e057-4364-85a4-58d43232cb24,Namespace:default,Attempt:0,} returns sandbox id \"3e8d06adb6eeddde89fb2b93a23c279778111b3ea78a33c29c6552363ecdf4fd\"" Jul 15 11:04:36.791170 env[1324]: time="2025-07-15T11:04:36.791142072Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 15 11:04:37.135785 kubelet[1564]: E0715 11:04:37.135683 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:38.136134 kubelet[1564]: E0715 11:04:38.136097 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:38.182943 systemd-networkd[1104]: cni0: Gained IPv6LL Jul 15 11:04:38.502962 systemd-networkd[1104]: vethb40a8a10: Gained IPv6LL Jul 15 11:04:38.663187 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1180967082.mount: Deactivated successfully. Jul 15 11:04:39.136768 kubelet[1564]: E0715 11:04:39.136717 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:39.867126 env[1324]: time="2025-07-15T11:04:39.867078863Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:39.868496 env[1324]: time="2025-07-15T11:04:39.868451450Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:39.870891 env[1324]: time="2025-07-15T11:04:39.870867976Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:39.872460 env[1324]: time="2025-07-15T11:04:39.872435360Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:39.874049 env[1324]: time="2025-07-15T11:04:39.874013393Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 15 11:04:39.875607 env[1324]: time="2025-07-15T11:04:39.875561831Z" level=info msg="CreateContainer within sandbox \"3e8d06adb6eeddde89fb2b93a23c279778111b3ea78a33c29c6552363ecdf4fd\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 15 11:04:39.886033 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3341630861.mount: Deactivated successfully. Jul 15 11:04:39.887592 env[1324]: time="2025-07-15T11:04:39.887533822Z" level=info msg="CreateContainer within sandbox \"3e8d06adb6eeddde89fb2b93a23c279778111b3ea78a33c29c6552363ecdf4fd\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"cc99b61cea4136142df8804775a205e93c7b798b84d250f3b62453f2e5786c55\"" Jul 15 11:04:39.888037 env[1324]: time="2025-07-15T11:04:39.888010323Z" level=info msg="StartContainer for \"cc99b61cea4136142df8804775a205e93c7b798b84d250f3b62453f2e5786c55\"" Jul 15 11:04:39.939370 env[1324]: time="2025-07-15T11:04:39.939309133Z" level=info msg="StartContainer for \"cc99b61cea4136142df8804775a205e93c7b798b84d250f3b62453f2e5786c55\" returns successfully" Jul 15 11:04:40.137796 kubelet[1564]: E0715 11:04:40.137680 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:40.350294 kubelet[1564]: I0715 11:04:40.350220 1564 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-nhttv" podStartSLOduration=1.266494112 podStartE2EDuration="4.350203185s" podCreationTimestamp="2025-07-15 11:04:36 +0000 UTC" firstStartedPulling="2025-07-15 11:04:36.790902907 +0000 UTC m=+12.535859823" lastFinishedPulling="2025-07-15 11:04:39.87461198 +0000 UTC m=+15.619568896" observedRunningTime="2025-07-15 11:04:40.347617451 +0000 UTC m=+16.092574407" watchObservedRunningTime="2025-07-15 11:04:40.350203185 +0000 UTC m=+16.095160101" Jul 15 11:04:41.138857 kubelet[1564]: E0715 11:04:41.138790 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:42.139286 kubelet[1564]: E0715 11:04:42.139242 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:43.036128 kubelet[1564]: I0715 11:04:43.036082 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/0c56d04e-1726-4c63-a65a-e1c98ca94c4a-data\") pod \"nfs-server-provisioner-0\" (UID: \"0c56d04e-1726-4c63-a65a-e1c98ca94c4a\") " pod="default/nfs-server-provisioner-0" Jul 15 11:04:43.036271 kubelet[1564]: I0715 11:04:43.036137 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ftr5w\" (UniqueName: \"kubernetes.io/projected/0c56d04e-1726-4c63-a65a-e1c98ca94c4a-kube-api-access-ftr5w\") pod \"nfs-server-provisioner-0\" (UID: \"0c56d04e-1726-4c63-a65a-e1c98ca94c4a\") " pod="default/nfs-server-provisioner-0" Jul 15 11:04:43.139565 kubelet[1564]: E0715 11:04:43.139520 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:43.269382 env[1324]: time="2025-07-15T11:04:43.269335916Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:0c56d04e-1726-4c63-a65a-e1c98ca94c4a,Namespace:default,Attempt:0,}" Jul 15 11:04:43.286833 systemd-networkd[1104]: veth9d6798ca: Link UP Jul 15 11:04:43.292006 kernel: cni0: port 2(veth9d6798ca) entered blocking state Jul 15 11:04:43.292078 kernel: cni0: port 2(veth9d6798ca) entered disabled state Jul 15 11:04:43.292102 kernel: device veth9d6798ca entered promiscuous mode Jul 15 11:04:43.292119 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 15 11:04:43.293074 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth9d6798ca: link becomes ready Jul 15 11:04:43.293124 kernel: cni0: port 2(veth9d6798ca) entered blocking state Jul 15 11:04:43.293860 kernel: cni0: port 2(veth9d6798ca) entered forwarding state Jul 15 11:04:43.294008 systemd-networkd[1104]: veth9d6798ca: Gained carrier Jul 15 11:04:43.297635 env[1324]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001c928), "name":"cbr0", "type":"bridge"} Jul 15 11:04:43.297635 env[1324]: delegateAdd: netconf sent to delegate plugin: Jul 15 11:04:43.308403 env[1324]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-15T11:04:43.308343578Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 15 11:04:43.308403 env[1324]: time="2025-07-15T11:04:43.308383350Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 15 11:04:43.308403 env[1324]: time="2025-07-15T11:04:43.308400681Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 15 11:04:43.308634 env[1324]: time="2025-07-15T11:04:43.308602100Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/89bd61ed0669bf941bb53d135ed0eabee878183e716bdb9cf595472f19ca389d pid=2315 runtime=io.containerd.runc.v2 Jul 15 11:04:43.345029 systemd-resolved[1242]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 15 11:04:43.361589 env[1324]: time="2025-07-15T11:04:43.361546705Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:0c56d04e-1726-4c63-a65a-e1c98ca94c4a,Namespace:default,Attempt:0,} returns sandbox id \"89bd61ed0669bf941bb53d135ed0eabee878183e716bdb9cf595472f19ca389d\"" Jul 15 11:04:43.363392 env[1324]: time="2025-07-15T11:04:43.363356957Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 15 11:04:44.140411 kubelet[1564]: E0715 11:04:44.140366 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:44.966982 systemd-networkd[1104]: veth9d6798ca: Gained IPv6LL Jul 15 11:04:45.129935 kubelet[1564]: E0715 11:04:45.129885 1564 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:45.141118 kubelet[1564]: E0715 11:04:45.141082 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:45.528545 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2531124505.mount: Deactivated successfully. Jul 15 11:04:46.141625 kubelet[1564]: E0715 11:04:46.141575 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:47.142757 kubelet[1564]: E0715 11:04:47.142713 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:47.249527 env[1324]: time="2025-07-15T11:04:47.249478572Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:47.250700 env[1324]: time="2025-07-15T11:04:47.250669150Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:47.252642 env[1324]: time="2025-07-15T11:04:47.252606028Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:47.256689 env[1324]: time="2025-07-15T11:04:47.256644060Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:47.257478 env[1324]: time="2025-07-15T11:04:47.257434635Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 15 11:04:47.260005 env[1324]: time="2025-07-15T11:04:47.259970918Z" level=info msg="CreateContainer within sandbox \"89bd61ed0669bf941bb53d135ed0eabee878183e716bdb9cf595472f19ca389d\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 15 11:04:47.272706 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3720424818.mount: Deactivated successfully. Jul 15 11:04:47.277037 env[1324]: time="2025-07-15T11:04:47.276989745Z" level=info msg="CreateContainer within sandbox \"89bd61ed0669bf941bb53d135ed0eabee878183e716bdb9cf595472f19ca389d\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"908c31202372175f59928faac92ee46f9a33d43bdbb880a9a43f4525253a45ef\"" Jul 15 11:04:47.277532 env[1324]: time="2025-07-15T11:04:47.277503675Z" level=info msg="StartContainer for \"908c31202372175f59928faac92ee46f9a33d43bdbb880a9a43f4525253a45ef\"" Jul 15 11:04:47.331098 env[1324]: time="2025-07-15T11:04:47.331055520Z" level=info msg="StartContainer for \"908c31202372175f59928faac92ee46f9a33d43bdbb880a9a43f4525253a45ef\" returns successfully" Jul 15 11:04:47.361580 kubelet[1564]: I0715 11:04:47.361295 1564 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.465358879 podStartE2EDuration="5.361279639s" podCreationTimestamp="2025-07-15 11:04:42 +0000 UTC" firstStartedPulling="2025-07-15 11:04:43.362832206 +0000 UTC m=+19.107789122" lastFinishedPulling="2025-07-15 11:04:47.258752966 +0000 UTC m=+23.003709882" observedRunningTime="2025-07-15 11:04:47.361152206 +0000 UTC m=+23.106109122" watchObservedRunningTime="2025-07-15 11:04:47.361279639 +0000 UTC m=+23.106236555" Jul 15 11:04:48.143108 kubelet[1564]: E0715 11:04:48.143045 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:49.143885 kubelet[1564]: E0715 11:04:49.143819 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:50.144184 kubelet[1564]: E0715 11:04:50.144149 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:51.144897 kubelet[1564]: E0715 11:04:51.144850 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:52.145594 kubelet[1564]: E0715 11:04:52.145543 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:53.146501 kubelet[1564]: E0715 11:04:53.146456 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:54.147296 kubelet[1564]: E0715 11:04:54.147248 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:55.148181 kubelet[1564]: E0715 11:04:55.148114 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:56.148294 kubelet[1564]: E0715 11:04:56.148254 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:57.148792 kubelet[1564]: E0715 11:04:57.148755 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:57.699455 kubelet[1564]: I0715 11:04:57.699419 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jklvk\" (UniqueName: \"kubernetes.io/projected/b6fa199c-b3d4-4d86-b654-c1739e0c9456-kube-api-access-jklvk\") pod \"test-pod-1\" (UID: \"b6fa199c-b3d4-4d86-b654-c1739e0c9456\") " pod="default/test-pod-1" Jul 15 11:04:57.699684 kubelet[1564]: I0715 11:04:57.699660 1564 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-d07f3183-ed9c-470c-bf3c-737353cc106e\" (UniqueName: \"kubernetes.io/nfs/b6fa199c-b3d4-4d86-b654-c1739e0c9456-pvc-d07f3183-ed9c-470c-bf3c-737353cc106e\") pod \"test-pod-1\" (UID: \"b6fa199c-b3d4-4d86-b654-c1739e0c9456\") " pod="default/test-pod-1" Jul 15 11:04:57.819860 kernel: FS-Cache: Loaded Jul 15 11:04:57.847004 kernel: RPC: Registered named UNIX socket transport module. Jul 15 11:04:57.847095 kernel: RPC: Registered udp transport module. Jul 15 11:04:57.847117 kernel: RPC: Registered tcp transport module. Jul 15 11:04:57.847134 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 15 11:04:57.892861 kernel: FS-Cache: Netfs 'nfs' registered for caching Jul 15 11:04:58.020181 kernel: NFS: Registering the id_resolver key type Jul 15 11:04:58.020274 kernel: Key type id_resolver registered Jul 15 11:04:58.020317 kernel: Key type id_legacy registered Jul 15 11:04:58.042287 nfsidmap[2493]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 15 11:04:58.045480 nfsidmap[2496]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 15 11:04:58.149829 kubelet[1564]: E0715 11:04:58.149783 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:58.162567 env[1324]: time="2025-07-15T11:04:58.162479954Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b6fa199c-b3d4-4d86-b654-c1739e0c9456,Namespace:default,Attempt:0,}" Jul 15 11:04:58.177501 systemd-networkd[1104]: vethee9f9312: Link UP Jul 15 11:04:58.179956 kernel: cni0: port 3(vethee9f9312) entered blocking state Jul 15 11:04:58.180021 kernel: cni0: port 3(vethee9f9312) entered disabled state Jul 15 11:04:58.180849 kernel: device vethee9f9312 entered promiscuous mode Jul 15 11:04:58.187209 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 15 11:04:58.187275 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethee9f9312: link becomes ready Jul 15 11:04:58.187294 kernel: cni0: port 3(vethee9f9312) entered blocking state Jul 15 11:04:58.187309 kernel: cni0: port 3(vethee9f9312) entered forwarding state Jul 15 11:04:58.187723 systemd-networkd[1104]: vethee9f9312: Gained carrier Jul 15 11:04:58.189336 env[1324]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40001148e8), "name":"cbr0", "type":"bridge"} Jul 15 11:04:58.189336 env[1324]: delegateAdd: netconf sent to delegate plugin: Jul 15 11:04:58.201736 env[1324]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-15T11:04:58.201678295Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 15 11:04:58.201910 env[1324]: time="2025-07-15T11:04:58.201884343Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 15 11:04:58.201990 env[1324]: time="2025-07-15T11:04:58.201969924Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 15 11:04:58.202240 env[1324]: time="2025-07-15T11:04:58.202207700Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/f3fbaa0ea610f90fdaf8f4cefc55edbb17f436a944c79c96127a721a9c6e5ab9 pid=2549 runtime=io.containerd.runc.v2 Jul 15 11:04:58.232812 systemd-resolved[1242]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 15 11:04:58.248864 env[1324]: time="2025-07-15T11:04:58.248815584Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b6fa199c-b3d4-4d86-b654-c1739e0c9456,Namespace:default,Attempt:0,} returns sandbox id \"f3fbaa0ea610f90fdaf8f4cefc55edbb17f436a944c79c96127a721a9c6e5ab9\"" Jul 15 11:04:58.250680 env[1324]: time="2025-07-15T11:04:58.250645334Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 15 11:04:58.471781 env[1324]: time="2025-07-15T11:04:58.471675529Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:58.473534 env[1324]: time="2025-07-15T11:04:58.473489796Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:58.475927 env[1324]: time="2025-07-15T11:04:58.475894602Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:58.477588 env[1324]: time="2025-07-15T11:04:58.477554232Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 15 11:04:58.478424 env[1324]: time="2025-07-15T11:04:58.478393470Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 15 11:04:58.481232 env[1324]: time="2025-07-15T11:04:58.481191328Z" level=info msg="CreateContainer within sandbox \"f3fbaa0ea610f90fdaf8f4cefc55edbb17f436a944c79c96127a721a9c6e5ab9\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 15 11:04:58.491228 env[1324]: time="2025-07-15T11:04:58.491173676Z" level=info msg="CreateContainer within sandbox \"f3fbaa0ea610f90fdaf8f4cefc55edbb17f436a944c79c96127a721a9c6e5ab9\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"5b00b5dc113fae5222fa0f1cf54590932031bb903793a5b1448960fbf86e8fbf\"" Jul 15 11:04:58.491647 env[1324]: time="2025-07-15T11:04:58.491583733Z" level=info msg="StartContainer for \"5b00b5dc113fae5222fa0f1cf54590932031bb903793a5b1448960fbf86e8fbf\"" Jul 15 11:04:58.550632 env[1324]: time="2025-07-15T11:04:58.550590894Z" level=info msg="StartContainer for \"5b00b5dc113fae5222fa0f1cf54590932031bb903793a5b1448960fbf86e8fbf\" returns successfully" Jul 15 11:04:59.150051 kubelet[1564]: E0715 11:04:59.149992 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:04:59.380506 kubelet[1564]: I0715 11:04:59.380438 1564 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=16.150807563 podStartE2EDuration="16.380422138s" podCreationTimestamp="2025-07-15 11:04:43 +0000 UTC" firstStartedPulling="2025-07-15 11:04:58.250216033 +0000 UTC m=+33.995172949" lastFinishedPulling="2025-07-15 11:04:58.479830608 +0000 UTC m=+34.224787524" observedRunningTime="2025-07-15 11:04:59.379965716 +0000 UTC m=+35.124922672" watchObservedRunningTime="2025-07-15 11:04:59.380422138 +0000 UTC m=+35.125379054" Jul 15 11:04:59.878986 systemd-networkd[1104]: vethee9f9312: Gained IPv6LL Jul 15 11:05:00.150244 kubelet[1564]: E0715 11:05:00.150125 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:05:01.150440 kubelet[1564]: E0715 11:05:01.150397 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:05:02.151207 kubelet[1564]: E0715 11:05:02.151162 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:05:03.023207 update_engine[1313]: I0715 11:05:03.023154 1313 update_attempter.cc:509] Updating boot flags... Jul 15 11:05:03.152337 kubelet[1564]: E0715 11:05:03.152276 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:05:04.152825 kubelet[1564]: E0715 11:05:04.152777 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:05:05.129994 kubelet[1564]: E0715 11:05:05.129960 1564 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 15 11:05:05.153832 kubelet[1564]: E0715 11:05:05.153800 1564 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"