Jul 6 23:52:22.894488 kernel: Linux version 6.6.95-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Sun Jul 6 21:53:45 -00 2025 Jul 6 23:52:22.894509 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=akamai verity.usrhash=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:52:22.894517 kernel: BIOS-provided physical RAM map: Jul 6 23:52:22.894524 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable Jul 6 23:52:22.894529 kernel: BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved Jul 6 23:52:22.894537 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Jul 6 23:52:22.894544 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdcfff] usable Jul 6 23:52:22.894550 kernel: BIOS-e820: [mem 0x000000007ffdd000-0x000000007fffffff] reserved Jul 6 23:52:22.894555 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Jul 6 23:52:22.894561 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Jul 6 23:52:22.894566 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Jul 6 23:52:22.894572 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Jul 6 23:52:22.894578 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000017fffffff] usable Jul 6 23:52:22.894584 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Jul 6 23:52:22.894593 kernel: NX (Execute Disable) protection: active Jul 6 23:52:22.894599 kernel: APIC: Static calls initialized Jul 6 23:52:22.894605 kernel: SMBIOS 2.8 present. Jul 6 23:52:22.894611 kernel: DMI: Linode Compute Instance/Standard PC (Q35 + ICH9, 2009), BIOS Not Specified Jul 6 23:52:22.894617 kernel: Hypervisor detected: KVM Jul 6 23:52:22.894626 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Jul 6 23:52:22.894632 kernel: kvm-clock: using sched offset of 4655328223 cycles Jul 6 23:52:22.894638 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jul 6 23:52:22.894645 kernel: tsc: Detected 1999.999 MHz processor Jul 6 23:52:22.894651 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 6 23:52:22.894658 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 6 23:52:22.894664 kernel: last_pfn = 0x180000 max_arch_pfn = 0x400000000 Jul 6 23:52:22.894671 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Jul 6 23:52:22.894677 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 6 23:52:22.894685 kernel: last_pfn = 0x7ffdd max_arch_pfn = 0x400000000 Jul 6 23:52:22.894691 kernel: Using GB pages for direct mapping Jul 6 23:52:22.894698 kernel: ACPI: Early table checksum verification disabled Jul 6 23:52:22.894704 kernel: ACPI: RSDP 0x00000000000F5160 000014 (v00 BOCHS ) Jul 6 23:52:22.894710 kernel: ACPI: RSDT 0x000000007FFE2307 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:52:22.894716 kernel: ACPI: FACP 0x000000007FFE20F7 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:52:22.894722 kernel: ACPI: DSDT 0x000000007FFE0040 0020B7 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:52:22.894728 kernel: ACPI: FACS 0x000000007FFE0000 000040 Jul 6 23:52:22.894735 kernel: ACPI: APIC 0x000000007FFE21EB 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:52:22.894743 kernel: ACPI: HPET 0x000000007FFE226B 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:52:22.894749 kernel: ACPI: MCFG 0x000000007FFE22A3 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:52:22.894775 kernel: ACPI: WAET 0x000000007FFE22DF 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:52:22.894786 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe20f7-0x7ffe21ea] Jul 6 23:52:22.894793 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe20f6] Jul 6 23:52:22.894799 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Jul 6 23:52:22.894808 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe21eb-0x7ffe226a] Jul 6 23:52:22.894815 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe226b-0x7ffe22a2] Jul 6 23:52:22.894821 kernel: ACPI: Reserving MCFG table memory at [mem 0x7ffe22a3-0x7ffe22de] Jul 6 23:52:22.894827 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe22df-0x7ffe2306] Jul 6 23:52:22.894834 kernel: No NUMA configuration found Jul 6 23:52:22.894840 kernel: Faking a node at [mem 0x0000000000000000-0x000000017fffffff] Jul 6 23:52:22.894847 kernel: NODE_DATA(0) allocated [mem 0x17fffa000-0x17fffffff] Jul 6 23:52:22.894853 kernel: Zone ranges: Jul 6 23:52:22.894860 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 6 23:52:22.894868 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 6 23:52:22.894875 kernel: Normal [mem 0x0000000100000000-0x000000017fffffff] Jul 6 23:52:22.894881 kernel: Movable zone start for each node Jul 6 23:52:22.894887 kernel: Early memory node ranges Jul 6 23:52:22.894894 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Jul 6 23:52:22.894900 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdcfff] Jul 6 23:52:22.894907 kernel: node 0: [mem 0x0000000100000000-0x000000017fffffff] Jul 6 23:52:22.894913 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000017fffffff] Jul 6 23:52:22.894919 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 6 23:52:22.894928 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Jul 6 23:52:22.894935 kernel: On node 0, zone Normal: 35 pages in unavailable ranges Jul 6 23:52:22.894941 kernel: ACPI: PM-Timer IO Port: 0x608 Jul 6 23:52:22.894948 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Jul 6 23:52:22.894954 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Jul 6 23:52:22.894960 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jul 6 23:52:22.894967 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Jul 6 23:52:22.894973 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 6 23:52:22.894980 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Jul 6 23:52:22.894988 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Jul 6 23:52:22.894995 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 6 23:52:22.895001 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Jul 6 23:52:22.895008 kernel: TSC deadline timer available Jul 6 23:52:22.895014 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Jul 6 23:52:22.895021 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Jul 6 23:52:22.895027 kernel: kvm-guest: KVM setup pv remote TLB flush Jul 6 23:52:22.895033 kernel: kvm-guest: setup PV sched yield Jul 6 23:52:22.895040 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Jul 6 23:52:22.895048 kernel: Booting paravirtualized kernel on KVM Jul 6 23:52:22.895055 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 6 23:52:22.895062 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Jul 6 23:52:22.895068 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u1048576 Jul 6 23:52:22.895074 kernel: pcpu-alloc: s197096 r8192 d32280 u1048576 alloc=1*2097152 Jul 6 23:52:22.895081 kernel: pcpu-alloc: [0] 0 1 Jul 6 23:52:22.895087 kernel: kvm-guest: PV spinlocks enabled Jul 6 23:52:22.895093 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 6 23:52:22.895101 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=akamai verity.usrhash=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:52:22.895110 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 6 23:52:22.895116 kernel: random: crng init done Jul 6 23:52:22.895123 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 6 23:52:22.895129 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 6 23:52:22.895136 kernel: Fallback order for Node 0: 0 Jul 6 23:52:22.895142 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031901 Jul 6 23:52:22.895149 kernel: Policy zone: Normal Jul 6 23:52:22.895155 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 6 23:52:22.895164 kernel: software IO TLB: area num 2. Jul 6 23:52:22.895170 kernel: Memory: 3964164K/4193772K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43492K init, 1584K bss, 229348K reserved, 0K cma-reserved) Jul 6 23:52:22.895177 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jul 6 23:52:22.895183 kernel: ftrace: allocating 37940 entries in 149 pages Jul 6 23:52:22.895190 kernel: ftrace: allocated 149 pages with 4 groups Jul 6 23:52:22.895196 kernel: Dynamic Preempt: voluntary Jul 6 23:52:22.895203 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 6 23:52:22.895210 kernel: rcu: RCU event tracing is enabled. Jul 6 23:52:22.895216 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Jul 6 23:52:22.895225 kernel: Trampoline variant of Tasks RCU enabled. Jul 6 23:52:22.895232 kernel: Rude variant of Tasks RCU enabled. Jul 6 23:52:22.895238 kernel: Tracing variant of Tasks RCU enabled. Jul 6 23:52:22.895245 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 6 23:52:22.895251 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jul 6 23:52:22.895258 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Jul 6 23:52:22.895264 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 6 23:52:22.895270 kernel: Console: colour VGA+ 80x25 Jul 6 23:52:22.895277 kernel: printk: console [tty0] enabled Jul 6 23:52:22.895285 kernel: printk: console [ttyS0] enabled Jul 6 23:52:22.895292 kernel: ACPI: Core revision 20230628 Jul 6 23:52:22.895298 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Jul 6 23:52:22.895305 kernel: APIC: Switch to symmetric I/O mode setup Jul 6 23:52:22.895319 kernel: x2apic enabled Jul 6 23:52:22.895328 kernel: APIC: Switched APIC routing to: physical x2apic Jul 6 23:52:22.895334 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Jul 6 23:52:22.895341 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Jul 6 23:52:22.895348 kernel: kvm-guest: setup PV IPIs Jul 6 23:52:22.895355 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Jul 6 23:52:22.895361 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Jul 6 23:52:22.895368 kernel: Calibrating delay loop (skipped) preset value.. 3999.99 BogoMIPS (lpj=1999999) Jul 6 23:52:22.895377 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Jul 6 23:52:22.895384 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Jul 6 23:52:22.895391 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Jul 6 23:52:22.895398 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 6 23:52:22.895404 kernel: Spectre V2 : Mitigation: Retpolines Jul 6 23:52:22.895414 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 6 23:52:22.895420 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Jul 6 23:52:22.895427 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Jul 6 23:52:22.895434 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Jul 6 23:52:22.895441 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Jul 6 23:52:22.895448 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Jul 6 23:52:22.895455 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Jul 6 23:52:22.895462 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 6 23:52:22.895471 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 6 23:52:22.895477 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 6 23:52:22.895484 kernel: x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' Jul 6 23:52:22.895491 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 6 23:52:22.895498 kernel: x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8 Jul 6 23:52:22.895504 kernel: x86/fpu: Enabled xstate features 0x207, context size is 840 bytes, using 'compacted' format. Jul 6 23:52:22.895511 kernel: Freeing SMP alternatives memory: 32K Jul 6 23:52:22.895518 kernel: pid_max: default: 32768 minimum: 301 Jul 6 23:52:22.895524 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jul 6 23:52:22.895533 kernel: landlock: Up and running. Jul 6 23:52:22.895540 kernel: SELinux: Initializing. Jul 6 23:52:22.895547 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 6 23:52:22.895554 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 6 23:52:22.895560 kernel: smpboot: CPU0: AMD EPYC 7713 64-Core Processor (family: 0x19, model: 0x1, stepping: 0x1) Jul 6 23:52:22.895567 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 6 23:52:22.895574 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 6 23:52:22.895581 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 6 23:52:22.895588 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Jul 6 23:52:22.895597 kernel: ... version: 0 Jul 6 23:52:22.895603 kernel: ... bit width: 48 Jul 6 23:52:22.895610 kernel: ... generic registers: 6 Jul 6 23:52:22.895616 kernel: ... value mask: 0000ffffffffffff Jul 6 23:52:22.895623 kernel: ... max period: 00007fffffffffff Jul 6 23:52:22.895630 kernel: ... fixed-purpose events: 0 Jul 6 23:52:22.895636 kernel: ... event mask: 000000000000003f Jul 6 23:52:22.895643 kernel: signal: max sigframe size: 3376 Jul 6 23:52:22.895650 kernel: rcu: Hierarchical SRCU implementation. Jul 6 23:52:22.895659 kernel: rcu: Max phase no-delay instances is 400. Jul 6 23:52:22.895666 kernel: smp: Bringing up secondary CPUs ... Jul 6 23:52:22.895672 kernel: smpboot: x86: Booting SMP configuration: Jul 6 23:52:22.895679 kernel: .... node #0, CPUs: #1 Jul 6 23:52:22.895686 kernel: smp: Brought up 1 node, 2 CPUs Jul 6 23:52:22.895692 kernel: smpboot: Max logical packages: 1 Jul 6 23:52:22.895699 kernel: smpboot: Total of 2 processors activated (7999.99 BogoMIPS) Jul 6 23:52:22.895706 kernel: devtmpfs: initialized Jul 6 23:52:22.895712 kernel: x86/mm: Memory block size: 128MB Jul 6 23:52:22.895721 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 6 23:52:22.895728 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jul 6 23:52:22.895735 kernel: pinctrl core: initialized pinctrl subsystem Jul 6 23:52:22.895742 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 6 23:52:22.895748 kernel: audit: initializing netlink subsys (disabled) Jul 6 23:52:22.896902 kernel: audit: type=2000 audit(1751845942.724:1): state=initialized audit_enabled=0 res=1 Jul 6 23:52:22.896914 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 6 23:52:22.896922 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 6 23:52:22.896928 kernel: cpuidle: using governor menu Jul 6 23:52:22.896939 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 6 23:52:22.896946 kernel: dca service started, version 1.12.1 Jul 6 23:52:22.896953 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Jul 6 23:52:22.896960 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Jul 6 23:52:22.896967 kernel: PCI: Using configuration type 1 for base access Jul 6 23:52:22.896974 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 6 23:52:22.896981 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 6 23:52:22.896987 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 6 23:52:22.896994 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 6 23:52:22.897003 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 6 23:52:22.897010 kernel: ACPI: Added _OSI(Module Device) Jul 6 23:52:22.897017 kernel: ACPI: Added _OSI(Processor Device) Jul 6 23:52:22.897023 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 6 23:52:22.897030 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 6 23:52:22.897037 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jul 6 23:52:22.897044 kernel: ACPI: Interpreter enabled Jul 6 23:52:22.897050 kernel: ACPI: PM: (supports S0 S3 S5) Jul 6 23:52:22.897057 kernel: ACPI: Using IOAPIC for interrupt routing Jul 6 23:52:22.897066 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 6 23:52:22.897073 kernel: PCI: Using E820 reservations for host bridge windows Jul 6 23:52:22.897080 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Jul 6 23:52:22.897086 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 6 23:52:22.897263 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 6 23:52:22.897388 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Jul 6 23:52:22.897504 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Jul 6 23:52:22.897517 kernel: PCI host bridge to bus 0000:00 Jul 6 23:52:22.897638 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jul 6 23:52:22.897743 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jul 6 23:52:22.897866 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jul 6 23:52:22.897971 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xafffffff window] Jul 6 23:52:22.898074 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Jul 6 23:52:22.898175 kernel: pci_bus 0000:00: root bus resource [mem 0x180000000-0x97fffffff window] Jul 6 23:52:22.898284 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 6 23:52:22.898414 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Jul 6 23:52:22.898539 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Jul 6 23:52:22.898652 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Jul 6 23:52:22.898789 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] Jul 6 23:52:22.898907 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Jul 6 23:52:22.899019 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jul 6 23:52:22.899147 kernel: pci 0000:00:02.0: [1af4:1004] type 00 class 0x010000 Jul 6 23:52:22.899262 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc000-0xc03f] Jul 6 23:52:22.899375 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] Jul 6 23:52:22.899488 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Jul 6 23:52:22.899613 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Jul 6 23:52:22.899728 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc040-0xc07f] Jul 6 23:52:22.899865 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] Jul 6 23:52:22.899986 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Jul 6 23:52:22.900098 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Jul 6 23:52:22.900217 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Jul 6 23:52:22.900330 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Jul 6 23:52:22.900450 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Jul 6 23:52:22.900562 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc0c0-0xc0df] Jul 6 23:52:22.900677 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd3000-0xfebd3fff] Jul 6 23:52:22.900817 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Jul 6 23:52:22.900932 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Jul 6 23:52:22.900942 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Jul 6 23:52:22.900949 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Jul 6 23:52:22.900956 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Jul 6 23:52:22.900963 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Jul 6 23:52:22.900970 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Jul 6 23:52:22.900980 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Jul 6 23:52:22.900988 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Jul 6 23:52:22.900994 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Jul 6 23:52:22.901001 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Jul 6 23:52:22.901008 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Jul 6 23:52:22.901015 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Jul 6 23:52:22.901022 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Jul 6 23:52:22.901029 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Jul 6 23:52:22.901035 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Jul 6 23:52:22.901045 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Jul 6 23:52:22.901051 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Jul 6 23:52:22.901058 kernel: iommu: Default domain type: Translated Jul 6 23:52:22.901065 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 6 23:52:22.901072 kernel: PCI: Using ACPI for IRQ routing Jul 6 23:52:22.901079 kernel: PCI: pci_cache_line_size set to 64 bytes Jul 6 23:52:22.901085 kernel: e820: reserve RAM buffer [mem 0x0009f800-0x0009ffff] Jul 6 23:52:22.901092 kernel: e820: reserve RAM buffer [mem 0x7ffdd000-0x7fffffff] Jul 6 23:52:22.901204 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Jul 6 23:52:22.901320 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Jul 6 23:52:22.901431 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jul 6 23:52:22.901440 kernel: vgaarb: loaded Jul 6 23:52:22.901447 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Jul 6 23:52:22.901455 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Jul 6 23:52:22.901461 kernel: clocksource: Switched to clocksource kvm-clock Jul 6 23:52:22.901468 kernel: VFS: Disk quotas dquot_6.6.0 Jul 6 23:52:22.901475 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 6 23:52:22.901485 kernel: pnp: PnP ACPI init Jul 6 23:52:22.901608 kernel: system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved Jul 6 23:52:22.901619 kernel: pnp: PnP ACPI: found 5 devices Jul 6 23:52:22.901626 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 6 23:52:22.901633 kernel: NET: Registered PF_INET protocol family Jul 6 23:52:22.901640 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 6 23:52:22.901647 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 6 23:52:22.901654 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 6 23:52:22.901664 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 6 23:52:22.901671 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jul 6 23:52:22.901678 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 6 23:52:22.901684 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 6 23:52:22.903821 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 6 23:52:22.903839 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 6 23:52:22.903848 kernel: NET: Registered PF_XDP protocol family Jul 6 23:52:22.903972 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jul 6 23:52:22.904086 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jul 6 23:52:22.904196 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jul 6 23:52:22.904299 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xafffffff window] Jul 6 23:52:22.904407 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Jul 6 23:52:22.904508 kernel: pci_bus 0000:00: resource 9 [mem 0x180000000-0x97fffffff window] Jul 6 23:52:22.904517 kernel: PCI: CLS 0 bytes, default 64 Jul 6 23:52:22.904525 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 6 23:52:22.904532 kernel: software IO TLB: mapped [mem 0x000000007bfdd000-0x000000007ffdd000] (64MB) Jul 6 23:52:22.904539 kernel: Initialise system trusted keyrings Jul 6 23:52:22.904546 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 6 23:52:22.904557 kernel: Key type asymmetric registered Jul 6 23:52:22.904564 kernel: Asymmetric key parser 'x509' registered Jul 6 23:52:22.904571 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jul 6 23:52:22.904578 kernel: io scheduler mq-deadline registered Jul 6 23:52:22.904584 kernel: io scheduler kyber registered Jul 6 23:52:22.904591 kernel: io scheduler bfq registered Jul 6 23:52:22.904598 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 6 23:52:22.904605 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Jul 6 23:52:22.904612 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Jul 6 23:52:22.904621 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 6 23:52:22.904628 kernel: 00:02: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 6 23:52:22.904635 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Jul 6 23:52:22.904642 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Jul 6 23:52:22.904649 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Jul 6 23:52:22.904829 kernel: rtc_cmos 00:03: RTC can wake from S4 Jul 6 23:52:22.904842 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Jul 6 23:52:22.904951 kernel: rtc_cmos 00:03: registered as rtc0 Jul 6 23:52:22.905063 kernel: rtc_cmos 00:03: setting system clock to 2025-07-06T23:52:22 UTC (1751845942) Jul 6 23:52:22.905168 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Jul 6 23:52:22.905177 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Jul 6 23:52:22.905184 kernel: NET: Registered PF_INET6 protocol family Jul 6 23:52:22.905191 kernel: Segment Routing with IPv6 Jul 6 23:52:22.905197 kernel: In-situ OAM (IOAM) with IPv6 Jul 6 23:52:22.905204 kernel: NET: Registered PF_PACKET protocol family Jul 6 23:52:22.905211 kernel: Key type dns_resolver registered Jul 6 23:52:22.905218 kernel: IPI shorthand broadcast: enabled Jul 6 23:52:22.905228 kernel: sched_clock: Marking stable (724003937, 218595854)->(1008479984, -65880193) Jul 6 23:52:22.905235 kernel: registered taskstats version 1 Jul 6 23:52:22.905242 kernel: Loading compiled-in X.509 certificates Jul 6 23:52:22.905249 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.95-flatcar: f74b958d282931d4f0d8d911dd18abd0ec707734' Jul 6 23:52:22.905256 kernel: Key type .fscrypt registered Jul 6 23:52:22.905263 kernel: Key type fscrypt-provisioning registered Jul 6 23:52:22.905270 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 6 23:52:22.905277 kernel: ima: Allocated hash algorithm: sha1 Jul 6 23:52:22.905286 kernel: ima: No architecture policies found Jul 6 23:52:22.905292 kernel: clk: Disabling unused clocks Jul 6 23:52:22.905299 kernel: Freeing unused kernel image (initmem) memory: 43492K Jul 6 23:52:22.905306 kernel: Write protecting the kernel read-only data: 38912k Jul 6 23:52:22.905313 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Jul 6 23:52:22.905320 kernel: Run /init as init process Jul 6 23:52:22.905327 kernel: with arguments: Jul 6 23:52:22.905334 kernel: /init Jul 6 23:52:22.905340 kernel: with environment: Jul 6 23:52:22.905347 kernel: HOME=/ Jul 6 23:52:22.905356 kernel: TERM=linux Jul 6 23:52:22.905363 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 6 23:52:22.905371 systemd[1]: Successfully made /usr/ read-only. Jul 6 23:52:22.905381 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 6 23:52:22.905389 systemd[1]: Detected virtualization kvm. Jul 6 23:52:22.905396 systemd[1]: Detected architecture x86-64. Jul 6 23:52:22.905403 systemd[1]: Running in initrd. Jul 6 23:52:22.905412 systemd[1]: No hostname configured, using default hostname. Jul 6 23:52:22.905420 systemd[1]: Hostname set to . Jul 6 23:52:22.905427 systemd[1]: Initializing machine ID from random generator. Jul 6 23:52:22.905434 systemd[1]: Queued start job for default target initrd.target. Jul 6 23:52:22.905442 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:52:22.905462 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:52:22.905474 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 6 23:52:22.905482 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:52:22.905490 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 6 23:52:22.905498 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 6 23:52:22.905506 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 6 23:52:22.905514 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 6 23:52:22.905524 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:52:22.905531 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:52:22.905539 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:52:22.905546 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:52:22.905554 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:52:22.905561 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:52:22.905569 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:52:22.905576 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:52:22.905584 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 6 23:52:22.905594 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 6 23:52:22.905601 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:52:22.905609 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:52:22.905616 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:52:22.905623 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:52:22.905631 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 6 23:52:22.905639 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:52:22.905646 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 6 23:52:22.905654 systemd[1]: Starting systemd-fsck-usr.service... Jul 6 23:52:22.905663 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:52:22.905671 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:52:22.905678 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:52:22.905686 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 6 23:52:22.905713 systemd-journald[177]: Collecting audit messages is disabled. Jul 6 23:52:22.905735 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:52:22.905747 systemd[1]: Finished systemd-fsck-usr.service. Jul 6 23:52:22.905773 systemd-journald[177]: Journal started Jul 6 23:52:22.905791 systemd-journald[177]: Runtime Journal (/run/log/journal/2cc1ba883d8647e7b2216f0100779ab9) is 8M, max 78.3M, 70.3M free. Jul 6 23:52:22.909813 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:52:22.909859 systemd-modules-load[178]: Inserted module 'overlay' Jul 6 23:52:22.961359 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 6 23:52:22.961384 kernel: Bridge firewalling registered Jul 6 23:52:22.938436 systemd-modules-load[178]: Inserted module 'br_netfilter' Jul 6 23:52:22.961662 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:52:22.962727 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:52:22.970008 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:52:22.974960 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:52:22.979173 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 6 23:52:22.985782 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:52:22.990407 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:52:23.023988 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:52:23.025208 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:52:23.033966 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 6 23:52:23.036387 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:52:23.037377 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:52:23.045900 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:52:23.051796 dracut-cmdline[208]: dracut-dracut-053 Jul 6 23:52:23.055474 dracut-cmdline[208]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=akamai verity.usrhash=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:52:23.060988 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:52:23.083367 systemd-resolved[215]: Positive Trust Anchors: Jul 6 23:52:23.083381 systemd-resolved[215]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:52:23.083407 systemd-resolved[215]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 6 23:52:23.089390 systemd-resolved[215]: Defaulting to hostname 'linux'. Jul 6 23:52:23.090469 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:52:23.091312 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:52:23.130782 kernel: SCSI subsystem initialized Jul 6 23:52:23.139775 kernel: Loading iSCSI transport class v2.0-870. Jul 6 23:52:23.150784 kernel: iscsi: registered transport (tcp) Jul 6 23:52:23.170328 kernel: iscsi: registered transport (qla4xxx) Jul 6 23:52:23.170374 kernel: QLogic iSCSI HBA Driver Jul 6 23:52:23.209466 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 6 23:52:23.216939 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 6 23:52:23.240098 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 6 23:52:23.240140 kernel: device-mapper: uevent: version 1.0.3 Jul 6 23:52:23.242147 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jul 6 23:52:23.281783 kernel: raid6: avx2x4 gen() 27121 MB/s Jul 6 23:52:23.299777 kernel: raid6: avx2x2 gen() 24892 MB/s Jul 6 23:52:23.318419 kernel: raid6: avx2x1 gen() 17094 MB/s Jul 6 23:52:23.318434 kernel: raid6: using algorithm avx2x4 gen() 27121 MB/s Jul 6 23:52:23.337531 kernel: raid6: .... xor() 5408 MB/s, rmw enabled Jul 6 23:52:23.337565 kernel: raid6: using avx2x2 recovery algorithm Jul 6 23:52:23.360779 kernel: xor: automatically using best checksumming function avx Jul 6 23:52:23.483786 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 6 23:52:23.497595 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:52:23.507903 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:52:23.521845 systemd-udevd[396]: Using default interface naming scheme 'v255'. Jul 6 23:52:23.526541 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:52:23.535018 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 6 23:52:23.548087 dracut-pre-trigger[404]: rd.md=0: removing MD RAID activation Jul 6 23:52:23.578602 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:52:23.584928 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:52:23.649014 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:52:23.656370 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 6 23:52:23.674444 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 6 23:52:23.677530 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:52:23.679458 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:52:23.681953 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:52:23.688615 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 6 23:52:23.703170 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:52:23.817903 kernel: cryptd: max_cpu_qlen set to 1000 Jul 6 23:52:23.825013 kernel: libata version 3.00 loaded. Jul 6 23:52:23.828677 kernel: scsi host0: Virtio SCSI HBA Jul 6 23:52:23.835810 kernel: scsi 0:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Jul 6 23:52:23.854741 kernel: AVX2 version of gcm_enc/dec engaged. Jul 6 23:52:23.854782 kernel: AES CTR mode by8 optimization enabled Jul 6 23:52:23.860294 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 6 23:52:23.860428 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:52:23.880117 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:52:23.881577 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:52:23.882996 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:52:23.883781 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:52:23.891065 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:52:23.892482 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:52:23.896044 kernel: ahci 0000:00:1f.2: version 3.0 Jul 6 23:52:23.896261 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Jul 6 23:52:23.902889 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Jul 6 23:52:23.903076 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Jul 6 23:52:23.913380 kernel: scsi host1: ahci Jul 6 23:52:23.917829 kernel: scsi host2: ahci Jul 6 23:52:23.924799 kernel: scsi host3: ahci Jul 6 23:52:23.928963 kernel: scsi host4: ahci Jul 6 23:52:23.929790 kernel: scsi host5: ahci Jul 6 23:52:23.932414 kernel: scsi host6: ahci Jul 6 23:52:23.932637 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd3000 port 0xfebd3100 irq 46 Jul 6 23:52:23.932651 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd3000 port 0xfebd3180 irq 46 Jul 6 23:52:23.932661 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd3000 port 0xfebd3200 irq 46 Jul 6 23:52:23.932671 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd3000 port 0xfebd3280 irq 46 Jul 6 23:52:23.932680 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd3000 port 0xfebd3300 irq 46 Jul 6 23:52:23.932690 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd3000 port 0xfebd3380 irq 46 Jul 6 23:52:23.995461 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:52:23.999902 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:52:24.022446 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:52:24.241835 kernel: ata2: SATA link down (SStatus 0 SControl 300) Jul 6 23:52:24.241937 kernel: ata1: SATA link down (SStatus 0 SControl 300) Jul 6 23:52:24.241948 kernel: ata4: SATA link down (SStatus 0 SControl 300) Jul 6 23:52:24.242862 kernel: ata3: SATA link down (SStatus 0 SControl 300) Jul 6 23:52:24.249783 kernel: ata6: SATA link down (SStatus 0 SControl 300) Jul 6 23:52:24.250003 kernel: ata5: SATA link down (SStatus 0 SControl 300) Jul 6 23:52:24.264785 kernel: sd 0:0:0:0: Power-on or device reset occurred Jul 6 23:52:24.288794 kernel: sd 0:0:0:0: [sda] 167739392 512-byte logical blocks: (85.9 GB/80.0 GiB) Jul 6 23:52:24.288965 kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 6 23:52:24.290955 kernel: sd 0:0:0:0: [sda] Mode Sense: 63 00 00 08 Jul 6 23:52:24.291110 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jul 6 23:52:24.297176 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 6 23:52:24.297231 kernel: GPT:9289727 != 167739391 Jul 6 23:52:24.299259 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 6 23:52:24.299276 kernel: GPT:9289727 != 167739391 Jul 6 23:52:24.300392 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 6 23:52:24.301647 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:52:24.304807 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 6 23:52:24.352435 kernel: BTRFS: device fsid 25bdfe43-d649-4808-8940-e1722efc7a2e devid 1 transid 37 /dev/sda3 scanned by (udev-worker) (454) Jul 6 23:52:24.357802 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/sda6 scanned by (udev-worker) (441) Jul 6 23:52:24.372195 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - QEMU_HARDDISK ROOT. Jul 6 23:52:24.382262 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - QEMU_HARDDISK EFI-SYSTEM. Jul 6 23:52:24.390382 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - QEMU_HARDDISK USR-A. Jul 6 23:52:24.391193 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - QEMU_HARDDISK USR-A. Jul 6 23:52:24.400233 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - QEMU_HARDDISK OEM. Jul 6 23:52:24.413060 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 6 23:52:24.418857 disk-uuid[568]: Primary Header is updated. Jul 6 23:52:24.418857 disk-uuid[568]: Secondary Entries is updated. Jul 6 23:52:24.418857 disk-uuid[568]: Secondary Header is updated. Jul 6 23:52:24.424855 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:52:24.430779 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:52:25.437817 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:52:25.439130 disk-uuid[569]: The operation has completed successfully. Jul 6 23:52:25.508207 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 6 23:52:25.508348 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 6 23:52:25.529909 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 6 23:52:25.534286 sh[583]: Success Jul 6 23:52:25.549978 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Jul 6 23:52:25.615202 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 6 23:52:25.627858 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 6 23:52:25.629404 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 6 23:52:25.646008 kernel: BTRFS info (device dm-0): first mount of filesystem 25bdfe43-d649-4808-8940-e1722efc7a2e Jul 6 23:52:25.646042 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:52:25.646062 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jul 6 23:52:25.648128 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jul 6 23:52:25.649631 kernel: BTRFS info (device dm-0): using free space tree Jul 6 23:52:25.659788 kernel: BTRFS info (device dm-0): enabling ssd optimizations Jul 6 23:52:25.661218 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 6 23:52:25.663090 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 6 23:52:25.674997 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 6 23:52:25.676925 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 6 23:52:25.705105 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:52:25.705146 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:52:25.705158 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:52:25.714410 kernel: BTRFS info (device sda6): enabling ssd optimizations Jul 6 23:52:25.714438 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:52:25.723824 kernel: BTRFS info (device sda6): last unmount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:52:25.726921 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 6 23:52:25.735025 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 6 23:52:25.799461 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:52:25.807942 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:52:25.834216 ignition[684]: Ignition 2.20.0 Jul 6 23:52:25.834227 ignition[684]: Stage: fetch-offline Jul 6 23:52:25.834262 ignition[684]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:52:25.834273 ignition[684]: no config dir at "/usr/lib/ignition/base.platform.d/akamai" Jul 6 23:52:25.834367 ignition[684]: parsed url from cmdline: "" Jul 6 23:52:25.834371 ignition[684]: no config URL provided Jul 6 23:52:25.834377 ignition[684]: reading system config file "/usr/lib/ignition/user.ign" Jul 6 23:52:25.834386 ignition[684]: no config at "/usr/lib/ignition/user.ign" Jul 6 23:52:25.834391 ignition[684]: failed to fetch config: resource requires networking Jul 6 23:52:25.834541 ignition[684]: Ignition finished successfully Jul 6 23:52:25.840060 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:52:25.845519 systemd-networkd[762]: lo: Link UP Jul 6 23:52:25.845532 systemd-networkd[762]: lo: Gained carrier Jul 6 23:52:25.847636 systemd-networkd[762]: Enumeration completed Jul 6 23:52:25.848236 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:52:25.849358 systemd[1]: Reached target network.target - Network. Jul 6 23:52:25.849408 systemd-networkd[762]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:52:25.849413 systemd-networkd[762]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:52:25.852859 systemd-networkd[762]: eth0: Link UP Jul 6 23:52:25.852864 systemd-networkd[762]: eth0: Gained carrier Jul 6 23:52:25.852871 systemd-networkd[762]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:52:25.858956 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 6 23:52:25.870917 ignition[771]: Ignition 2.20.0 Jul 6 23:52:25.870930 ignition[771]: Stage: fetch Jul 6 23:52:25.871069 ignition[771]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:52:25.871081 ignition[771]: no config dir at "/usr/lib/ignition/base.platform.d/akamai" Jul 6 23:52:25.871154 ignition[771]: parsed url from cmdline: "" Jul 6 23:52:25.871158 ignition[771]: no config URL provided Jul 6 23:52:25.871163 ignition[771]: reading system config file "/usr/lib/ignition/user.ign" Jul 6 23:52:25.871171 ignition[771]: no config at "/usr/lib/ignition/user.ign" Jul 6 23:52:25.871196 ignition[771]: PUT http://169.254.169.254/v1/token: attempt #1 Jul 6 23:52:25.871350 ignition[771]: PUT error: Put "http://169.254.169.254/v1/token": dial tcp 169.254.169.254:80: connect: network is unreachable Jul 6 23:52:26.072389 ignition[771]: PUT http://169.254.169.254/v1/token: attempt #2 Jul 6 23:52:26.072578 ignition[771]: PUT error: Put "http://169.254.169.254/v1/token": dial tcp 169.254.169.254:80: connect: network is unreachable Jul 6 23:52:26.366835 systemd-networkd[762]: eth0: DHCPv4 address 172.236.103.247/24, gateway 172.236.103.1 acquired from 23.40.197.159 Jul 6 23:52:26.472991 ignition[771]: PUT http://169.254.169.254/v1/token: attempt #3 Jul 6 23:52:26.564723 ignition[771]: PUT result: OK Jul 6 23:52:26.564791 ignition[771]: GET http://169.254.169.254/v1/user-data: attempt #1 Jul 6 23:52:26.676546 ignition[771]: GET result: OK Jul 6 23:52:26.676602 ignition[771]: parsing config with SHA512: a3d04ea9111894f0999b14e71ad0ef5b89a4d7d0b9cf4634163f3275e8aede24f3656cd7adf5cedee258a0e9532c27bbabb60bd44609538502959270d98259b2 Jul 6 23:52:26.679468 unknown[771]: fetched base config from "system" Jul 6 23:52:26.679650 ignition[771]: fetch: fetch complete Jul 6 23:52:26.679480 unknown[771]: fetched base config from "system" Jul 6 23:52:26.679654 ignition[771]: fetch: fetch passed Jul 6 23:52:26.679487 unknown[771]: fetched user config from "akamai" Jul 6 23:52:26.679692 ignition[771]: Ignition finished successfully Jul 6 23:52:26.683994 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 6 23:52:26.695902 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 6 23:52:26.709828 ignition[779]: Ignition 2.20.0 Jul 6 23:52:26.709839 ignition[779]: Stage: kargs Jul 6 23:52:26.709993 ignition[779]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:52:26.710005 ignition[779]: no config dir at "/usr/lib/ignition/base.platform.d/akamai" Jul 6 23:52:26.711748 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 6 23:52:26.710500 ignition[779]: kargs: kargs passed Jul 6 23:52:26.710534 ignition[779]: Ignition finished successfully Jul 6 23:52:26.717861 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 6 23:52:26.730072 ignition[785]: Ignition 2.20.0 Jul 6 23:52:26.730087 ignition[785]: Stage: disks Jul 6 23:52:26.730217 ignition[785]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:52:26.730229 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/akamai" Jul 6 23:52:26.732156 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 6 23:52:26.730730 ignition[785]: disks: disks passed Jul 6 23:52:26.730783 ignition[785]: Ignition finished successfully Jul 6 23:52:26.755968 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 6 23:52:26.756629 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 6 23:52:26.757378 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:52:26.758340 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:52:26.759479 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:52:26.765997 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 6 23:52:26.783342 systemd-fsck[794]: ROOT: clean, 14/553520 files, 52654/553472 blocks Jul 6 23:52:26.786477 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 6 23:52:26.791883 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 6 23:52:26.876798 kernel: EXT4-fs (sda9): mounted filesystem daab0c95-3783-44c0-bef8-9d61a5c53c14 r/w with ordered data mode. Quota mode: none. Jul 6 23:52:26.878033 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 6 23:52:26.879538 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 6 23:52:26.889874 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:52:26.892436 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 6 23:52:26.893238 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jul 6 23:52:26.893292 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 6 23:52:26.893324 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:52:26.902628 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 6 23:52:26.905596 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by mount (802) Jul 6 23:52:26.905628 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:52:26.905642 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:52:26.909782 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:52:26.914312 kernel: BTRFS info (device sda6): enabling ssd optimizations Jul 6 23:52:26.914337 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:52:26.918945 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 6 23:52:26.922309 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:52:26.971356 initrd-setup-root[826]: cut: /sysroot/etc/passwd: No such file or directory Jul 6 23:52:26.978317 initrd-setup-root[833]: cut: /sysroot/etc/group: No such file or directory Jul 6 23:52:26.984134 initrd-setup-root[840]: cut: /sysroot/etc/shadow: No such file or directory Jul 6 23:52:26.989365 initrd-setup-root[847]: cut: /sysroot/etc/gshadow: No such file or directory Jul 6 23:52:27.102378 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 6 23:52:27.109001 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 6 23:52:27.111913 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 6 23:52:27.121172 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 6 23:52:27.125639 kernel: BTRFS info (device sda6): last unmount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:52:27.147298 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 6 23:52:27.150035 ignition[914]: INFO : Ignition 2.20.0 Jul 6 23:52:27.150035 ignition[914]: INFO : Stage: mount Jul 6 23:52:27.151352 ignition[914]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:52:27.151352 ignition[914]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/akamai" Jul 6 23:52:27.151352 ignition[914]: INFO : mount: mount passed Jul 6 23:52:27.151352 ignition[914]: INFO : Ignition finished successfully Jul 6 23:52:27.152962 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 6 23:52:27.158851 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 6 23:52:27.703089 systemd-networkd[762]: eth0: Gained IPv6LL Jul 6 23:52:27.882900 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:52:27.896794 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (927) Jul 6 23:52:27.901385 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:52:27.901422 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:52:27.901435 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:52:27.908054 kernel: BTRFS info (device sda6): enabling ssd optimizations Jul 6 23:52:27.908091 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:52:27.910344 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:52:27.938463 ignition[944]: INFO : Ignition 2.20.0 Jul 6 23:52:27.938463 ignition[944]: INFO : Stage: files Jul 6 23:52:27.940229 ignition[944]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:52:27.940229 ignition[944]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/akamai" Jul 6 23:52:27.940229 ignition[944]: DEBUG : files: compiled without relabeling support, skipping Jul 6 23:52:27.942942 ignition[944]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 6 23:52:27.942942 ignition[944]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 6 23:52:27.944679 ignition[944]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 6 23:52:27.944679 ignition[944]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 6 23:52:27.944679 ignition[944]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 6 23:52:27.943880 unknown[944]: wrote ssh authorized keys file for user: core Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 6 23:52:27.948195 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-x86-64.raw: attempt #1 Jul 6 23:52:28.400854 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 6 23:52:28.739667 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 6 23:52:28.739667 ignition[944]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Jul 6 23:52:28.741876 ignition[944]: INFO : files: op(7): op(8): [started] writing systemd drop-in "00-custom-metadata.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/00-custom-metadata.conf" Jul 6 23:52:28.741876 ignition[944]: INFO : files: op(7): op(8): [finished] writing systemd drop-in "00-custom-metadata.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/00-custom-metadata.conf" Jul 6 23:52:28.741876 ignition[944]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Jul 6 23:52:28.741876 ignition[944]: INFO : files: createResultFile: createFiles: op(9): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:52:28.741876 ignition[944]: INFO : files: createResultFile: createFiles: op(9): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:52:28.741876 ignition[944]: INFO : files: files passed Jul 6 23:52:28.741876 ignition[944]: INFO : Ignition finished successfully Jul 6 23:52:28.743353 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 6 23:52:28.753087 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 6 23:52:28.756340 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 6 23:52:28.757918 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 6 23:52:28.758025 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 6 23:52:28.773546 initrd-setup-root-after-ignition[972]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:52:28.796283 initrd-setup-root-after-ignition[972]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:52:28.778798 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:52:28.798578 initrd-setup-root-after-ignition[976]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:52:28.798051 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 6 23:52:28.803901 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 6 23:52:28.826160 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 6 23:52:28.826295 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 6 23:52:28.828243 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 6 23:52:28.829141 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 6 23:52:28.830558 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 6 23:52:28.836004 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 6 23:52:28.850527 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:52:28.858003 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 6 23:52:28.868573 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:52:28.869247 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:52:28.870552 systemd[1]: Stopped target timers.target - Timer Units. Jul 6 23:52:28.871709 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 6 23:52:28.871857 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:52:28.873293 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 6 23:52:28.874234 systemd[1]: Stopped target basic.target - Basic System. Jul 6 23:52:28.875406 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 6 23:52:28.876503 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:52:28.877557 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 6 23:52:28.879123 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 6 23:52:28.880349 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:52:28.881601 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 6 23:52:28.882811 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 6 23:52:28.884047 systemd[1]: Stopped target swap.target - Swaps. Jul 6 23:52:28.885149 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 6 23:52:28.885252 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:52:28.886581 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:52:28.887379 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:52:28.888425 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 6 23:52:28.889064 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:52:28.889674 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 6 23:52:28.889796 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 6 23:52:28.891443 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 6 23:52:28.891553 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:52:28.892513 systemd[1]: ignition-files.service: Deactivated successfully. Jul 6 23:52:28.892640 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 6 23:52:28.903097 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 6 23:52:28.907059 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 6 23:52:28.908131 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 6 23:52:28.908285 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:52:28.910723 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 6 23:52:28.910861 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:52:28.917520 ignition[996]: INFO : Ignition 2.20.0 Jul 6 23:52:28.917520 ignition[996]: INFO : Stage: umount Jul 6 23:52:28.925869 ignition[996]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:52:28.925869 ignition[996]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/akamai" Jul 6 23:52:28.925869 ignition[996]: INFO : umount: umount passed Jul 6 23:52:28.925869 ignition[996]: INFO : Ignition finished successfully Jul 6 23:52:28.927096 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 6 23:52:28.927202 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 6 23:52:28.928430 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 6 23:52:28.929094 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 6 23:52:28.933866 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 6 23:52:28.933941 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 6 23:52:28.936853 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 6 23:52:28.936913 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 6 23:52:28.937471 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 6 23:52:28.937519 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 6 23:52:28.939979 systemd[1]: Stopped target network.target - Network. Jul 6 23:52:28.940715 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 6 23:52:28.940789 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:52:28.941381 systemd[1]: Stopped target paths.target - Path Units. Jul 6 23:52:28.944086 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 6 23:52:28.951324 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:52:28.951918 systemd[1]: Stopped target slices.target - Slice Units. Jul 6 23:52:28.952478 systemd[1]: Stopped target sockets.target - Socket Units. Jul 6 23:52:28.954890 systemd[1]: iscsid.socket: Deactivated successfully. Jul 6 23:52:28.954935 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:52:28.959882 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 6 23:52:28.959945 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:52:28.960497 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 6 23:52:28.960564 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 6 23:52:28.961400 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 6 23:52:28.961452 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 6 23:52:28.963264 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 6 23:52:28.964150 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 6 23:52:28.969388 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 6 23:52:28.970085 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 6 23:52:28.970218 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 6 23:52:28.975259 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 6 23:52:28.976364 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 6 23:52:28.976495 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 6 23:52:28.998962 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 6 23:52:28.999096 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 6 23:52:29.001044 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 6 23:52:29.003167 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 6 23:52:29.003233 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:52:29.004579 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 6 23:52:29.004638 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 6 23:52:29.010977 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 6 23:52:29.012145 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 6 23:52:29.012208 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:52:29.013031 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 6 23:52:29.013083 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:52:29.014919 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 6 23:52:29.014972 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 6 23:52:29.015928 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 6 23:52:29.015976 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:52:29.017928 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:52:29.024486 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 6 23:52:29.024558 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:52:29.032530 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 6 23:52:29.032675 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 6 23:52:29.040693 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 6 23:52:29.040918 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:52:29.045162 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 6 23:52:29.045231 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 6 23:52:29.046604 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 6 23:52:29.046644 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:52:29.048075 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 6 23:52:29.048130 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:52:29.049977 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 6 23:52:29.050031 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 6 23:52:29.051204 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 6 23:52:29.051255 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:52:29.062189 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 6 23:52:29.062751 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 6 23:52:29.062824 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:52:29.063503 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 6 23:52:29.063553 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:52:29.064155 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 6 23:52:29.064204 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:52:29.065476 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:52:29.065527 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:52:29.067789 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 6 23:52:29.067856 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:52:29.069047 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 6 23:52:29.069166 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 6 23:52:29.070676 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 6 23:52:29.077015 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 6 23:52:29.086858 systemd[1]: Switching root. Jul 6 23:52:29.119922 systemd-journald[177]: Journal stopped Jul 6 23:52:30.261940 systemd-journald[177]: Received SIGTERM from PID 1 (systemd). Jul 6 23:52:30.261966 kernel: SELinux: policy capability network_peer_controls=1 Jul 6 23:52:30.261978 kernel: SELinux: policy capability open_perms=1 Jul 6 23:52:30.261988 kernel: SELinux: policy capability extended_socket_class=1 Jul 6 23:52:30.261996 kernel: SELinux: policy capability always_check_network=0 Jul 6 23:52:30.262009 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 6 23:52:30.262018 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 6 23:52:30.262029 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 6 23:52:30.262038 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 6 23:52:30.262047 kernel: audit: type=1403 audit(1751845949.248:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 6 23:52:30.262057 systemd[1]: Successfully loaded SELinux policy in 50.071ms. Jul 6 23:52:30.262072 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.351ms. Jul 6 23:52:30.262083 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 6 23:52:30.262093 systemd[1]: Detected virtualization kvm. Jul 6 23:52:30.262104 systemd[1]: Detected architecture x86-64. Jul 6 23:52:30.262114 systemd[1]: Detected first boot. Jul 6 23:52:30.262126 systemd[1]: Initializing machine ID from random generator. Jul 6 23:52:30.262136 zram_generator::config[1041]: No configuration found. Jul 6 23:52:30.262147 kernel: Guest personality initialized and is inactive Jul 6 23:52:30.262156 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Jul 6 23:52:30.262165 kernel: Initialized host personality Jul 6 23:52:30.262175 kernel: NET: Registered PF_VSOCK protocol family Jul 6 23:52:30.262185 systemd[1]: Populated /etc with preset unit settings. Jul 6 23:52:30.262198 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 6 23:52:30.262208 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 6 23:52:30.262217 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 6 23:52:30.262227 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 6 23:52:30.262238 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 6 23:52:30.262248 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 6 23:52:30.262258 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 6 23:52:30.262270 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 6 23:52:30.262281 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 6 23:52:30.262291 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 6 23:52:30.262301 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 6 23:52:30.262311 systemd[1]: Created slice user.slice - User and Session Slice. Jul 6 23:52:30.262321 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:52:30.262331 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:52:30.262341 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 6 23:52:30.262352 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 6 23:52:30.262365 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 6 23:52:30.262378 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:52:30.262389 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 6 23:52:30.262399 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:52:30.262409 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 6 23:52:30.262419 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 6 23:52:30.262429 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 6 23:52:30.262441 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 6 23:52:30.262451 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:52:30.262461 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:52:30.262472 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:52:30.262482 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:52:30.262492 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 6 23:52:30.262503 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 6 23:52:30.262512 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 6 23:52:30.262523 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:52:30.262536 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:52:30.262546 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:52:30.262556 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 6 23:52:30.262566 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 6 23:52:30.262579 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 6 23:52:30.262589 systemd[1]: Mounting media.mount - External Media Directory... Jul 6 23:52:30.262599 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:52:30.262610 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 6 23:52:30.262620 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 6 23:52:30.262630 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 6 23:52:30.262641 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 6 23:52:30.262651 systemd[1]: Reached target machines.target - Containers. Jul 6 23:52:30.262664 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 6 23:52:30.262675 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:52:30.262685 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:52:30.262695 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 6 23:52:30.262705 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:52:30.262715 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:52:30.262726 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:52:30.262736 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 6 23:52:30.262746 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:52:30.262772 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 6 23:52:30.262782 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 6 23:52:30.262793 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 6 23:52:30.262803 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 6 23:52:30.262813 systemd[1]: Stopped systemd-fsck-usr.service. Jul 6 23:52:30.262824 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 6 23:52:30.262834 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:52:30.262845 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:52:30.262858 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 6 23:52:30.262869 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 6 23:52:30.262878 kernel: loop: module loaded Jul 6 23:52:30.262888 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 6 23:52:30.262898 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:52:30.262909 systemd[1]: verity-setup.service: Deactivated successfully. Jul 6 23:52:30.262919 systemd[1]: Stopped verity-setup.service. Jul 6 23:52:30.262929 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:52:30.262942 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 6 23:52:30.262952 kernel: ACPI: bus type drm_connector registered Jul 6 23:52:30.262962 kernel: fuse: init (API version 7.39) Jul 6 23:52:30.262972 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 6 23:52:30.262982 systemd[1]: Mounted media.mount - External Media Directory. Jul 6 23:52:30.262992 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 6 23:52:30.263003 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 6 23:52:30.263013 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 6 23:52:30.263025 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 6 23:52:30.263035 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:52:30.263045 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 6 23:52:30.263073 systemd-journald[1124]: Collecting audit messages is disabled. Jul 6 23:52:30.263099 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 6 23:52:30.263110 systemd-journald[1124]: Journal started Jul 6 23:52:30.263129 systemd-journald[1124]: Runtime Journal (/run/log/journal/dd69580b6fdd4b7184c64b7a218e54b3) is 8M, max 78.3M, 70.3M free. Jul 6 23:52:29.890100 systemd[1]: Queued start job for default target multi-user.target. Jul 6 23:52:29.900540 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jul 6 23:52:29.901290 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 6 23:52:30.297504 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:52:30.268005 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:52:30.268232 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:52:30.269122 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:52:30.269338 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:52:30.270207 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:52:30.270424 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:52:30.271367 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 6 23:52:30.271585 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 6 23:52:30.272466 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:52:30.272679 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:52:30.274090 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:52:30.275111 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 6 23:52:30.298740 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 6 23:52:30.300056 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 6 23:52:30.317984 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 6 23:52:30.327204 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 6 23:52:30.334844 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 6 23:52:30.336835 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 6 23:52:30.336870 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:52:30.338718 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 6 23:52:30.345578 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 6 23:52:30.350842 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 6 23:52:30.352371 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:52:30.354868 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 6 23:52:30.359901 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 6 23:52:30.360563 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:52:30.369466 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 6 23:52:30.370680 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:52:30.372002 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:52:30.376300 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 6 23:52:30.383903 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 6 23:52:30.385581 systemd-journald[1124]: Time spent on flushing to /var/log/journal/dd69580b6fdd4b7184c64b7a218e54b3 is 47.993ms for 970 entries. Jul 6 23:52:30.385581 systemd-journald[1124]: System Journal (/var/log/journal/dd69580b6fdd4b7184c64b7a218e54b3) is 8M, max 195.6M, 187.6M free. Jul 6 23:52:30.452393 systemd-journald[1124]: Received client request to flush runtime journal. Jul 6 23:52:30.452440 kernel: loop0: detected capacity change from 0 to 221472 Jul 6 23:52:30.389336 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:52:30.390468 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 6 23:52:30.397366 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 6 23:52:30.398648 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 6 23:52:30.411838 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 6 23:52:30.424613 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 6 23:52:30.437746 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 6 23:52:30.450520 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jul 6 23:52:30.459230 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 6 23:52:30.490035 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 6 23:52:30.524925 kernel: loop1: detected capacity change from 0 to 138176 Jul 6 23:52:30.514627 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 6 23:52:30.519375 systemd-tmpfiles[1168]: ACLs are not supported, ignoring. Jul 6 23:52:30.519392 systemd-tmpfiles[1168]: ACLs are not supported, ignoring. Jul 6 23:52:30.522427 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:52:30.526326 udevadm[1176]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 6 23:52:30.542585 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:52:30.550906 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 6 23:52:30.581792 kernel: loop2: detected capacity change from 0 to 8 Jul 6 23:52:30.618802 kernel: loop3: detected capacity change from 0 to 147912 Jul 6 23:52:30.620470 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 6 23:52:30.633205 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:52:30.666165 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Jul 6 23:52:30.666185 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Jul 6 23:52:30.672189 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:52:30.679792 kernel: loop4: detected capacity change from 0 to 221472 Jul 6 23:52:30.704894 kernel: loop5: detected capacity change from 0 to 138176 Jul 6 23:52:30.729049 kernel: loop6: detected capacity change from 0 to 8 Jul 6 23:52:30.735884 kernel: loop7: detected capacity change from 0 to 147912 Jul 6 23:52:30.770033 (sd-merge)[1196]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-akamai'. Jul 6 23:52:30.771509 (sd-merge)[1196]: Merged extensions into '/usr'. Jul 6 23:52:30.777711 systemd[1]: Reload requested from client PID 1167 ('systemd-sysext') (unit systemd-sysext.service)... Jul 6 23:52:30.777731 systemd[1]: Reloading... Jul 6 23:52:30.938801 zram_generator::config[1233]: No configuration found. Jul 6 23:52:31.075088 ldconfig[1162]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 6 23:52:31.087228 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:52:31.176449 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 6 23:52:31.177665 systemd[1]: Reloading finished in 399 ms. Jul 6 23:52:31.214806 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 6 23:52:31.216161 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 6 23:52:31.217487 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 6 23:52:31.232450 systemd[1]: Starting ensure-sysext.service... Jul 6 23:52:31.236904 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:52:31.241084 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:52:31.262807 systemd[1]: Reload requested from client PID 1268 ('systemctl') (unit ensure-sysext.service)... Jul 6 23:52:31.262836 systemd[1]: Reloading... Jul 6 23:52:31.274018 systemd-tmpfiles[1269]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 6 23:52:31.274264 systemd-tmpfiles[1269]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 6 23:52:31.275305 systemd-tmpfiles[1269]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 6 23:52:31.275538 systemd-tmpfiles[1269]: ACLs are not supported, ignoring. Jul 6 23:52:31.275608 systemd-tmpfiles[1269]: ACLs are not supported, ignoring. Jul 6 23:52:31.284123 systemd-tmpfiles[1269]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:52:31.284207 systemd-tmpfiles[1269]: Skipping /boot Jul 6 23:52:31.301641 systemd-tmpfiles[1269]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:52:31.301702 systemd-tmpfiles[1269]: Skipping /boot Jul 6 23:52:31.335867 systemd-udevd[1270]: Using default interface naming scheme 'v255'. Jul 6 23:52:31.405906 zram_generator::config[1311]: No configuration found. Jul 6 23:52:31.578830 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1320) Jul 6 23:52:31.639939 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Jul 6 23:52:31.641691 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:52:31.664219 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Jul 6 23:52:31.671292 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Jul 6 23:52:31.671547 kernel: ACPI: button: Power Button [PWRF] Jul 6 23:52:31.671563 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Jul 6 23:52:31.689835 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Jul 6 23:52:31.765785 kernel: mousedev: PS/2 mouse device common for all mice Jul 6 23:52:31.770980 kernel: EDAC MC: Ver: 3.0.0 Jul 6 23:52:31.827563 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 6 23:52:31.828896 systemd[1]: Reloading finished in 565 ms. Jul 6 23:52:31.846195 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:52:31.873402 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:52:31.895304 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jul 6 23:52:31.907164 systemd[1]: Finished ensure-sysext.service. Jul 6 23:52:31.935371 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - QEMU_HARDDISK OEM. Jul 6 23:52:31.945129 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:52:31.950086 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 6 23:52:31.953449 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 6 23:52:31.956137 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:52:31.958237 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jul 6 23:52:31.963916 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:52:31.971079 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:52:31.975904 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:52:31.978879 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:52:31.981124 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:52:31.982342 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 6 23:52:31.982936 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 6 23:52:31.985915 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 6 23:52:31.993397 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:52:31.999328 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:52:32.004047 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jul 6 23:52:32.016070 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 6 23:52:32.022973 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:52:32.023553 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:52:32.024629 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:52:32.027904 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:52:32.028960 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:52:32.029175 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:52:32.031229 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:52:32.031508 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:52:32.032375 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:52:32.033834 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:52:32.049554 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:52:32.049619 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:52:32.054326 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 6 23:52:32.058483 lvm[1378]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 6 23:52:32.073827 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 6 23:52:32.084666 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 6 23:52:32.103374 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 6 23:52:32.105983 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 6 23:52:32.114889 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 6 23:52:32.123924 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 6 23:52:32.133176 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jul 6 23:52:32.134027 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:52:32.144149 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jul 6 23:52:32.153109 lvm[1422]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 6 23:52:32.153327 augenrules[1424]: No rules Jul 6 23:52:32.154035 systemd[1]: audit-rules.service: Deactivated successfully. Jul 6 23:52:32.154313 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 6 23:52:32.165513 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 6 23:52:32.169572 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 6 23:52:32.185961 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jul 6 23:52:32.255912 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:52:32.309728 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jul 6 23:52:32.310376 systemd[1]: Reached target time-set.target - System Time Set. Jul 6 23:52:32.312033 systemd-resolved[1392]: Positive Trust Anchors: Jul 6 23:52:32.312322 systemd-resolved[1392]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:52:32.312429 systemd-resolved[1392]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 6 23:52:32.315493 systemd-networkd[1389]: lo: Link UP Jul 6 23:52:32.315746 systemd-networkd[1389]: lo: Gained carrier Jul 6 23:52:32.318342 systemd-resolved[1392]: Defaulting to hostname 'linux'. Jul 6 23:52:32.318532 systemd-networkd[1389]: Enumeration completed Jul 6 23:52:32.318630 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:52:32.320977 systemd-networkd[1389]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:52:32.320993 systemd-networkd[1389]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:52:32.322297 systemd-networkd[1389]: eth0: Link UP Jul 6 23:52:32.322310 systemd-networkd[1389]: eth0: Gained carrier Jul 6 23:52:32.322322 systemd-networkd[1389]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:52:32.325911 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 6 23:52:32.329015 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 6 23:52:32.329943 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:52:32.330869 systemd[1]: Reached target network.target - Network. Jul 6 23:52:32.332816 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:52:32.333405 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:52:32.334027 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 6 23:52:32.334687 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 6 23:52:32.335534 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 6 23:52:32.336171 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 6 23:52:32.336738 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 6 23:52:32.337499 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 6 23:52:32.337528 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:52:32.338212 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:52:32.340074 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 6 23:52:32.342843 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 6 23:52:32.348038 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 6 23:52:32.349018 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 6 23:52:32.349593 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 6 23:52:32.357061 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 6 23:52:32.358308 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 6 23:52:32.360127 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 6 23:52:32.361071 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 6 23:52:32.372728 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:52:32.373286 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:52:32.374061 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:52:32.374104 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:52:32.378881 systemd[1]: Starting containerd.service - containerd container runtime... Jul 6 23:52:32.380927 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 6 23:52:32.384906 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 6 23:52:32.388113 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 6 23:52:32.392803 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 6 23:52:32.393365 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Jul 6 23:52:32.395100 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 6 23:52:32.398094 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 6 23:52:32.403966 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 6 23:52:32.415167 jq[1453]: false Jul 6 23:52:32.415888 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 6 23:52:32.417721 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 6 23:52:32.418184 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 6 23:52:32.420903 systemd[1]: Starting update-engine.service - Update Engine... Jul 6 23:52:32.423198 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 6 23:52:32.447441 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 6 23:52:32.447568 jq[1461]: true Jul 6 23:52:32.447714 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 6 23:52:32.448174 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 6 23:52:32.448411 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 6 23:52:32.462143 extend-filesystems[1454]: Found loop4 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found loop5 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found loop6 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found loop7 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda1 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda2 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda3 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found usr Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda4 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda6 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda7 Jul 6 23:52:32.471285 extend-filesystems[1454]: Found sda9 Jul 6 23:52:32.471285 extend-filesystems[1454]: Checking size of /dev/sda9 Jul 6 23:52:32.500801 extend-filesystems[1454]: Resized partition /dev/sda9 Jul 6 23:52:32.501474 jq[1468]: true Jul 6 23:52:32.502307 dbus-daemon[1452]: [system] SELinux support is enabled Jul 6 23:52:32.503881 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 6 23:52:32.506296 extend-filesystems[1483]: resize2fs 1.47.1 (20-May-2024) Jul 6 23:52:32.515969 kernel: EXT4-fs (sda9): resizing filesystem from 553472 to 20360187 blocks Jul 6 23:52:32.512359 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Jul 6 23:52:32.512413 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 6 23:52:32.514111 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 6 23:52:32.514130 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 6 23:52:32.524994 update_engine[1459]: I20250706 23:52:32.524602 1459 main.cc:92] Flatcar Update Engine starting Jul 6 23:52:32.531532 systemd[1]: Started update-engine.service - Update Engine. Jul 6 23:52:32.532831 update_engine[1459]: I20250706 23:52:32.532584 1459 update_check_scheduler.cc:74] Next update check in 7m19s Jul 6 23:52:32.535206 (ntainerd)[1479]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 6 23:52:32.535389 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 6 23:52:32.544924 systemd[1]: motdgen.service: Deactivated successfully. Jul 6 23:52:32.545200 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 6 23:52:32.607803 bash[1506]: Updated "/home/core/.ssh/authorized_keys" Jul 6 23:52:32.608010 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 6 23:52:32.616985 systemd[1]: Starting sshkeys.service... Jul 6 23:52:32.637114 systemd-logind[1458]: Watching system buttons on /dev/input/event1 (Power Button) Jul 6 23:52:32.637154 systemd-logind[1458]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 6 23:52:32.644934 systemd-logind[1458]: New seat seat0. Jul 6 23:52:32.650632 systemd[1]: Started systemd-logind.service - User Login Management. Jul 6 23:52:32.667262 coreos-metadata[1451]: Jul 06 23:52:32.665 INFO Putting http://169.254.169.254/v1/token: Attempt #1 Jul 6 23:52:32.675190 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Jul 6 23:52:32.681783 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Jul 6 23:52:32.729488 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1320) Jul 6 23:52:32.849952 kernel: EXT4-fs (sda9): resized filesystem to 20360187 Jul 6 23:52:32.850056 coreos-metadata[1514]: Jul 06 23:52:32.849 INFO Putting http://169.254.169.254/v1/token: Attempt #1 Jul 6 23:52:32.851886 systemd-networkd[1389]: eth0: DHCPv4 address 172.236.103.247/24, gateway 172.236.103.1 acquired from 23.40.197.159 Jul 6 23:52:32.851982 dbus-daemon[1452]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1389 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Jul 6 23:52:32.853463 systemd-timesyncd[1393]: Network configuration changed, trying to establish connection. Jul 6 23:52:32.858857 locksmithd[1496]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 6 23:52:32.862738 extend-filesystems[1483]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Jul 6 23:52:32.862738 extend-filesystems[1483]: old_desc_blocks = 1, new_desc_blocks = 10 Jul 6 23:52:32.862738 extend-filesystems[1483]: The filesystem on /dev/sda9 is now 20360187 (4k) blocks long. Jul 6 23:52:32.869188 extend-filesystems[1454]: Resized filesystem in /dev/sda9 Jul 6 23:52:32.867886 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jul 6 23:52:32.905613 containerd[1479]: time="2025-07-06T23:52:32.865002399Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jul 6 23:52:32.868928 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 6 23:52:32.869184 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 6 23:52:32.926822 containerd[1479]: time="2025-07-06T23:52:32.926139049Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930023 containerd[1479]: time="2025-07-06T23:52:32.929986371Z" 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/6.6.95-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930023 containerd[1479]: time="2025-07-06T23:52:32.930016481Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 6 23:52:32.930073 containerd[1479]: time="2025-07-06T23:52:32.930033721Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 6 23:52:32.930231 containerd[1479]: time="2025-07-06T23:52:32.930202851Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jul 6 23:52:32.930231 containerd[1479]: time="2025-07-06T23:52:32.930228301Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930341 containerd[1479]: time="2025-07-06T23:52:32.930292881Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930341 containerd[1479]: time="2025-07-06T23:52:32.930317711Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930567 containerd[1479]: time="2025-07-06T23:52:32.930535971Z" 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 6 23:52:32.930567 containerd[1479]: time="2025-07-06T23:52:32.930559361Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930615 containerd[1479]: time="2025-07-06T23:52:32.930571741Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930615 containerd[1479]: time="2025-07-06T23:52:32.930581111Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 6 23:52:32.930705 containerd[1479]: time="2025-07-06T23:52:32.930675352Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:52:32.931020 containerd[1479]: time="2025-07-06T23:52:32.930990072Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:52:32.931178 containerd[1479]: time="2025-07-06T23:52:32.931141882Z" 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 6 23:52:32.931178 containerd[1479]: time="2025-07-06T23:52:32.931162412Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 6 23:52:32.931798 containerd[1479]: time="2025-07-06T23:52:32.931263982Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 6 23:52:32.931798 containerd[1479]: time="2025-07-06T23:52:32.931326002Z" level=info msg="metadata content store policy set" policy=shared Jul 6 23:52:32.935506 containerd[1479]: time="2025-07-06T23:52:32.935463194Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 6 23:52:32.936804 containerd[1479]: time="2025-07-06T23:52:32.936774555Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 6 23:52:32.936804 containerd[1479]: time="2025-07-06T23:52:32.936800875Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jul 6 23:52:32.936850 containerd[1479]: time="2025-07-06T23:52:32.936816915Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jul 6 23:52:32.936850 containerd[1479]: time="2025-07-06T23:52:32.936830005Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 6 23:52:32.936979 containerd[1479]: time="2025-07-06T23:52:32.936949505Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 6 23:52:32.937247 containerd[1479]: time="2025-07-06T23:52:32.937219055Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 6 23:52:32.937363 containerd[1479]: time="2025-07-06T23:52:32.937335785Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jul 6 23:52:32.937363 containerd[1479]: time="2025-07-06T23:52:32.937360305Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jul 6 23:52:32.937401 containerd[1479]: time="2025-07-06T23:52:32.937373885Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jul 6 23:52:32.937401 containerd[1479]: time="2025-07-06T23:52:32.937387055Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937401 containerd[1479]: time="2025-07-06T23:52:32.937397515Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937457 containerd[1479]: time="2025-07-06T23:52:32.937408665Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937457 containerd[1479]: time="2025-07-06T23:52:32.937420215Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937457 containerd[1479]: time="2025-07-06T23:52:32.937432645Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937457 containerd[1479]: time="2025-07-06T23:52:32.937443615Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937457 containerd[1479]: time="2025-07-06T23:52:32.937454725Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937539 containerd[1479]: time="2025-07-06T23:52:32.937464245Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 6 23:52:32.937539 containerd[1479]: time="2025-07-06T23:52:32.937483515Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937539 containerd[1479]: time="2025-07-06T23:52:32.937494835Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937539 containerd[1479]: time="2025-07-06T23:52:32.937505285Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937539 containerd[1479]: time="2025-07-06T23:52:32.937515665Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937539 containerd[1479]: time="2025-07-06T23:52:32.937526465Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937544025Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937555805Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937566585Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937578125Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937590995Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937602135Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937612885Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937623215Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937639 containerd[1479]: time="2025-07-06T23:52:32.937635695Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jul 6 23:52:32.937791 containerd[1479]: time="2025-07-06T23:52:32.937653665Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937791 containerd[1479]: time="2025-07-06T23:52:32.937665125Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937791 containerd[1479]: time="2025-07-06T23:52:32.937674585Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 6 23:52:32.937791 containerd[1479]: time="2025-07-06T23:52:32.937725965Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 6 23:52:32.937791 containerd[1479]: time="2025-07-06T23:52:32.937739945Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Jul 6 23:52:32.937791 containerd[1479]: time="2025-07-06T23:52:32.937748615Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 6 23:52:32.937894 containerd[1479]: time="2025-07-06T23:52:32.937829425Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jul 6 23:52:32.937894 containerd[1479]: time="2025-07-06T23:52:32.937841035Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.937894 containerd[1479]: time="2025-07-06T23:52:32.937853655Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jul 6 23:52:32.937894 containerd[1479]: time="2025-07-06T23:52:32.937862875Z" level=info msg="NRI interface is disabled by configuration." Jul 6 23:52:32.937894 containerd[1479]: time="2025-07-06T23:52:32.937873065Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 6 23:52:32.938798 containerd[1479]: time="2025-07-06T23:52:32.938104635Z" 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 PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false 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:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 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 EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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 6 23:52:32.938798 containerd[1479]: time="2025-07-06T23:52:32.938149285Z" level=info msg="Connect containerd service" Jul 6 23:52:32.938798 containerd[1479]: time="2025-07-06T23:52:32.938175245Z" level=info msg="using legacy CRI server" Jul 6 23:52:32.938798 containerd[1479]: time="2025-07-06T23:52:32.938181595Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 6 23:52:32.938798 containerd[1479]: time="2025-07-06T23:52:32.938272975Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941100137Z" 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 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941229567Z" level=info msg="Start subscribing containerd event" Jul 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941267377Z" level=info msg="Start recovering state" Jul 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941323467Z" level=info msg="Start event monitor" Jul 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941342167Z" level=info msg="Start snapshots syncer" Jul 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941349467Z" level=info msg="Start cni network conf syncer for default" Jul 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941357457Z" level=info msg="Start streaming server" Jul 6 23:52:32.941790 containerd[1479]: time="2025-07-06T23:52:32.941730237Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 6 23:52:32.941938 containerd[1479]: time="2025-07-06T23:52:32.941864277Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 6 23:52:32.942611 systemd[1]: Started containerd.service - containerd container runtime. Jul 6 23:52:32.946452 containerd[1479]: time="2025-07-06T23:52:32.946415449Z" level=info msg="containerd successfully booted in 0.092053s" Jul 6 23:52:32.965060 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jul 6 23:52:32.966907 dbus-daemon[1452]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 6 23:52:32.967358 dbus-daemon[1452]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=1525 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Jul 6 23:52:32.976422 systemd[1]: Starting polkit.service - Authorization Manager... Jul 6 23:52:32.985080 polkitd[1529]: Started polkitd version 121 Jul 6 23:52:32.988863 polkitd[1529]: Loading rules from directory /etc/polkit-1/rules.d Jul 6 23:52:32.988968 polkitd[1529]: Loading rules from directory /usr/share/polkit-1/rules.d Jul 6 23:52:32.989457 polkitd[1529]: Finished loading, compiling and executing 2 rules Jul 6 23:52:32.989936 dbus-daemon[1452]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 6 23:52:32.990114 systemd[1]: Started polkit.service - Authorization Manager. Jul 6 23:52:32.991656 polkitd[1529]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Jul 6 23:52:32.999008 systemd-hostnamed[1525]: Hostname set to <172-236-103-247> (transient) Jul 6 23:52:33.001309 systemd-resolved[1392]: System hostname changed to '172-236-103-247'. Jul 6 23:52:33.022282 sshd_keygen[1467]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 6 23:52:33.042924 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 6 23:52:33.049346 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 6 23:52:33.056864 systemd[1]: issuegen.service: Deactivated successfully. Jul 6 23:52:33.057097 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 6 23:52:33.071183 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 6 23:52:33.080338 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 6 23:52:33.088127 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 6 23:52:33.090126 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 6 23:52:33.091031 systemd[1]: Reached target getty.target - Login Prompts. Jul 6 23:52:33.675933 coreos-metadata[1451]: Jul 06 23:52:33.675 INFO Putting http://169.254.169.254/v1/token: Attempt #2 Jul 6 23:52:33.771712 coreos-metadata[1451]: Jul 06 23:52:33.771 INFO Fetching http://169.254.169.254/v1/instance: Attempt #1 Jul 6 23:52:33.846979 systemd-networkd[1389]: eth0: Gained IPv6LL Jul 6 23:52:33.847705 systemd-timesyncd[1393]: Network configuration changed, trying to establish connection. Jul 6 23:52:33.850271 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 6 23:52:33.851509 systemd[1]: Reached target network-online.target - Network is Online. Jul 6 23:52:33.859320 coreos-metadata[1514]: Jul 06 23:52:33.859 INFO Putting http://169.254.169.254/v1/token: Attempt #2 Jul 6 23:52:33.861885 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:52:33.864588 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 6 23:52:33.888330 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 6 23:52:33.950250 coreos-metadata[1514]: Jul 06 23:52:33.949 INFO Fetching http://169.254.169.254/v1/ssh-keys: Attempt #1 Jul 6 23:52:33.955667 coreos-metadata[1451]: Jul 06 23:52:33.955 INFO Fetch successful Jul 6 23:52:33.955667 coreos-metadata[1451]: Jul 06 23:52:33.955 INFO Fetching http://169.254.169.254/v1/network: Attempt #1 Jul 6 23:52:34.080749 coreos-metadata[1514]: Jul 06 23:52:34.080 INFO Fetch successful Jul 6 23:52:34.095835 update-ssh-keys[1570]: Updated "/home/core/.ssh/authorized_keys" Jul 6 23:52:34.097195 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Jul 6 23:52:34.100628 systemd[1]: Finished sshkeys.service. Jul 6 23:52:34.218513 coreos-metadata[1451]: Jul 06 23:52:34.218 INFO Fetch successful Jul 6 23:52:34.294144 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 6 23:52:34.295796 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 6 23:52:34.834561 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:52:34.835639 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 6 23:52:34.836656 systemd[1]: Startup finished in 850ms (kernel) + 6.547s (initrd) + 5.636s (userspace) = 13.034s. Jul 6 23:52:34.879390 (kubelet)[1598]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 6 23:52:35.349437 systemd-timesyncd[1393]: Network configuration changed, trying to establish connection. Jul 6 23:52:35.421968 kubelet[1598]: E0706 23:52:35.421663 1598 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 6 23:52:35.425783 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 6 23:52:35.425993 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 6 23:52:35.426408 systemd[1]: kubelet.service: Consumed 913ms CPU time, 266.5M memory peak. Jul 6 23:52:36.847708 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 6 23:52:36.851957 systemd[1]: Started sshd@0-172.236.103.247:22-147.75.109.163:33374.service - OpenSSH per-connection server daemon (147.75.109.163:33374). Jul 6 23:52:36.857681 systemd-timesyncd[1393]: Network configuration changed, trying to establish connection. Jul 6 23:52:37.232429 sshd[1610]: Accepted publickey for core from 147.75.109.163 port 33374 ssh2: RSA SHA256:/eDCPZUdFWI+U3+wi39zDPruseM35VxqLVYPXblev1E Jul 6 23:52:37.234194 sshd-session[1610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:52:37.243108 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 6 23:52:37.248112 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 6 23:52:37.250667 systemd-logind[1458]: New session 1 of user core. Jul 6 23:52:37.260604 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 6 23:52:37.267998 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 6 23:52:37.270791 (systemd)[1614]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 6 23:52:37.273725 systemd-logind[1458]: New session c1 of user core. Jul 6 23:52:37.404048 systemd[1614]: Queued start job for default target default.target. Jul 6 23:52:37.415056 systemd[1614]: Created slice app.slice - User Application Slice. Jul 6 23:52:37.415084 systemd[1614]: Reached target paths.target - Paths. Jul 6 23:52:37.415128 systemd[1614]: Reached target timers.target - Timers. Jul 6 23:52:37.416970 systemd[1614]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 6 23:52:37.428315 systemd[1614]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 6 23:52:37.428432 systemd[1614]: Reached target sockets.target - Sockets. Jul 6 23:52:37.428652 systemd[1614]: Reached target basic.target - Basic System. Jul 6 23:52:37.428740 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 6 23:52:37.429298 systemd[1614]: Reached target default.target - Main User Target. Jul 6 23:52:37.429338 systemd[1614]: Startup finished in 150ms. Jul 6 23:52:37.435878 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 6 23:52:37.721374 systemd[1]: Started sshd@1-172.236.103.247:22-147.75.109.163:33386.service - OpenSSH per-connection server daemon (147.75.109.163:33386). Jul 6 23:52:38.079999 sshd[1625]: Accepted publickey for core from 147.75.109.163 port 33386 ssh2: RSA SHA256:/eDCPZUdFWI+U3+wi39zDPruseM35VxqLVYPXblev1E Jul 6 23:52:38.081412 sshd-session[1625]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:52:38.085218 systemd-logind[1458]: New session 2 of user core. Jul 6 23:52:38.099873 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 6 23:52:38.345623 sshd[1627]: Connection closed by 147.75.109.163 port 33386 Jul 6 23:52:38.346314 sshd-session[1625]: pam_unix(sshd:session): session closed for user core Jul 6 23:52:38.348924 systemd[1]: sshd@1-172.236.103.247:22-147.75.109.163:33386.service: Deactivated successfully. Jul 6 23:52:38.350557 systemd[1]: session-2.scope: Deactivated successfully. Jul 6 23:52:38.351848 systemd-logind[1458]: Session 2 logged out. Waiting for processes to exit. Jul 6 23:52:38.352806 systemd-logind[1458]: Removed session 2. Jul 6 23:52:38.409567 systemd[1]: Started sshd@2-172.236.103.247:22-147.75.109.163:33388.service - OpenSSH per-connection server daemon (147.75.109.163:33388). Jul 6 23:52:38.769532 sshd[1633]: Accepted publickey for core from 147.75.109.163 port 33388 ssh2: RSA SHA256:/eDCPZUdFWI+U3+wi39zDPruseM35VxqLVYPXblev1E Jul 6 23:52:38.771427 sshd-session[1633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:52:38.776374 systemd-logind[1458]: New session 3 of user core. Jul 6 23:52:38.782869 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 6 23:52:39.033799 sshd[1635]: Connection closed by 147.75.109.163 port 33388 Jul 6 23:52:39.034556 sshd-session[1633]: pam_unix(sshd:session): session closed for user core Jul 6 23:52:39.038819 systemd[1]: sshd@2-172.236.103.247:22-147.75.109.163:33388.service: Deactivated successfully. Jul 6 23:52:39.040976 systemd[1]: session-3.scope: Deactivated successfully. Jul 6 23:52:39.041688 systemd-logind[1458]: Session 3 logged out. Waiting for processes to exit. Jul 6 23:52:39.042717 systemd-logind[1458]: Removed session 3. Jul 6 23:52:39.102905 systemd[1]: Started sshd@3-172.236.103.247:22-147.75.109.163:33396.service - OpenSSH per-connection server daemon (147.75.109.163:33396). Jul 6 23:52:39.462359 sshd[1641]: Accepted publickey for core from 147.75.109.163 port 33396 ssh2: RSA SHA256:/eDCPZUdFWI+U3+wi39zDPruseM35VxqLVYPXblev1E Jul 6 23:52:39.463728 sshd-session[1641]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:52:39.468903 systemd-logind[1458]: New session 4 of user core. Jul 6 23:52:39.475883 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 6 23:52:39.728834 sshd[1643]: Connection closed by 147.75.109.163 port 33396 Jul 6 23:52:39.729527 sshd-session[1641]: pam_unix(sshd:session): session closed for user core Jul 6 23:52:39.732254 systemd[1]: sshd@3-172.236.103.247:22-147.75.109.163:33396.service: Deactivated successfully. Jul 6 23:52:39.733902 systemd[1]: session-4.scope: Deactivated successfully. Jul 6 23:52:39.735001 systemd-logind[1458]: Session 4 logged out. Waiting for processes to exit. Jul 6 23:52:39.735932 systemd-logind[1458]: Removed session 4. Jul 6 23:52:39.792661 systemd[1]: Started sshd@4-172.236.103.247:22-147.75.109.163:33402.service - OpenSSH per-connection server daemon (147.75.109.163:33402). Jul 6 23:52:40.147984 sshd[1649]: Accepted publickey for core from 147.75.109.163 port 33402 ssh2: RSA SHA256:/eDCPZUdFWI+U3+wi39zDPruseM35VxqLVYPXblev1E Jul 6 23:52:40.149374 sshd-session[1649]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:52:40.153863 systemd-logind[1458]: New session 5 of user core. Jul 6 23:52:40.158886 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 6 23:52:40.365452 sudo[1652]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 6 23:52:40.365776 sudo[1652]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:52:40.911428 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:52:40.911567 systemd[1]: kubelet.service: Consumed 913ms CPU time, 266.5M memory peak. Jul 6 23:52:40.924140 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:52:40.955141 systemd[1]: Reload requested from client PID 1685 ('systemctl') (unit session-5.scope)... Jul 6 23:52:40.955156 systemd[1]: Reloading... Jul 6 23:52:41.103404 zram_generator::config[1736]: No configuration found. Jul 6 23:52:41.200180 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:52:41.295007 systemd[1]: Reloading finished in 339 ms. Jul 6 23:52:41.347970 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:52:41.350241 (kubelet)[1774]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 6 23:52:41.357212 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:52:41.360246 systemd[1]: kubelet.service: Deactivated successfully. Jul 6 23:52:41.360533 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:52:41.360580 systemd[1]: kubelet.service: Consumed 140ms CPU time, 99.8M memory peak. Jul 6 23:52:41.365034 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:52:41.507325 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:52:41.510849 (kubelet)[1790]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 6 23:52:41.542783 kubelet[1790]: 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 6 23:52:41.542783 kubelet[1790]: 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 6 23:52:41.542783 kubelet[1790]: 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 6 23:52:41.542783 kubelet[1790]: I0706 23:52:41.542628 1790 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 6 23:52:41.780354 kubelet[1790]: I0706 23:52:41.780271 1790 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Jul 6 23:52:41.780620 kubelet[1790]: I0706 23:52:41.780608 1790 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 6 23:52:41.781778 kubelet[1790]: I0706 23:52:41.780994 1790 server.go:934] "Client rotation is on, will bootstrap in background" Jul 6 23:52:41.806079 kubelet[1790]: I0706 23:52:41.806036 1790 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 6 23:52:41.811460 kubelet[1790]: E0706 23:52:41.811387 1790 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Jul 6 23:52:41.811460 kubelet[1790]: I0706 23:52:41.811407 1790 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 6 23:52:41.815985 kubelet[1790]: I0706 23:52:41.815862 1790 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 6 23:52:41.817795 kubelet[1790]: I0706 23:52:41.817328 1790 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Jul 6 23:52:41.817795 kubelet[1790]: I0706 23:52:41.817449 1790 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 6 23:52:41.817795 kubelet[1790]: I0706 23:52:41.817470 1790 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"192.168.184.63","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"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},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"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":2} Jul 6 23:52:41.817795 kubelet[1790]: I0706 23:52:41.817596 1790 topology_manager.go:138] "Creating topology manager with none policy" Jul 6 23:52:41.817965 kubelet[1790]: I0706 23:52:41.817604 1790 container_manager_linux.go:300] "Creating device plugin manager" Jul 6 23:52:41.817965 kubelet[1790]: I0706 23:52:41.817692 1790 state_mem.go:36] "Initialized new in-memory state store" Jul 6 23:52:41.820333 kubelet[1790]: I0706 23:52:41.820305 1790 kubelet.go:408] "Attempting to sync node with API server" Jul 6 23:52:41.820333 kubelet[1790]: I0706 23:52:41.820327 1790 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 6 23:52:41.820401 kubelet[1790]: I0706 23:52:41.820365 1790 kubelet.go:314] "Adding apiserver pod source" Jul 6 23:52:41.820401 kubelet[1790]: I0706 23:52:41.820381 1790 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 6 23:52:41.822411 kubelet[1790]: E0706 23:52:41.822256 1790 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:41.822411 kubelet[1790]: E0706 23:52:41.822293 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:41.822478 kubelet[1790]: I0706 23:52:41.822440 1790 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Jul 6 23:52:41.823045 kubelet[1790]: I0706 23:52:41.823024 1790 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 6 23:52:41.824201 kubelet[1790]: W0706 23:52:41.823984 1790 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 6 23:52:41.825802 kubelet[1790]: I0706 23:52:41.825788 1790 server.go:1274] "Started kubelet" Jul 6 23:52:41.829782 kubelet[1790]: I0706 23:52:41.828932 1790 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Jul 6 23:52:41.829782 kubelet[1790]: I0706 23:52:41.829167 1790 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 6 23:52:41.829782 kubelet[1790]: I0706 23:52:41.829441 1790 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 6 23:52:41.829782 kubelet[1790]: I0706 23:52:41.829685 1790 server.go:449] "Adding debug handlers to kubelet server" Jul 6 23:52:41.832612 kubelet[1790]: I0706 23:52:41.832599 1790 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 6 23:52:41.836555 kubelet[1790]: I0706 23:52:41.836540 1790 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 6 23:52:41.838684 kubelet[1790]: I0706 23:52:41.838672 1790 volume_manager.go:289] "Starting Kubelet Volume Manager" Jul 6 23:52:41.839455 kubelet[1790]: I0706 23:52:41.838843 1790 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Jul 6 23:52:41.839501 kubelet[1790]: I0706 23:52:41.839464 1790 reconciler.go:26] "Reconciler: start to sync state" Jul 6 23:52:41.839501 kubelet[1790]: E0706 23:52:41.839035 1790 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"192.168.184.63\" not found" Jul 6 23:52:41.840421 kubelet[1790]: I0706 23:52:41.840393 1790 factory.go:221] Registration of the systemd container factory successfully Jul 6 23:52:41.840962 kubelet[1790]: I0706 23:52:41.840944 1790 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 6 23:52:41.841155 kubelet[1790]: E0706 23:52:41.840720 1790 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 6 23:52:41.841893 kubelet[1790]: E0706 23:52:41.841876 1790 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"192.168.184.63\" not found" node="192.168.184.63" Jul 6 23:52:41.842918 kubelet[1790]: I0706 23:52:41.842882 1790 factory.go:221] Registration of the containerd container factory successfully Jul 6 23:52:41.875258 kubelet[1790]: I0706 23:52:41.875243 1790 cpu_manager.go:214] "Starting CPU manager" policy="none" Jul 6 23:52:41.875358 kubelet[1790]: I0706 23:52:41.875347 1790 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jul 6 23:52:41.875445 kubelet[1790]: I0706 23:52:41.875435 1790 state_mem.go:36] "Initialized new in-memory state store" Jul 6 23:52:41.879405 kubelet[1790]: I0706 23:52:41.879388 1790 policy_none.go:49] "None policy: Start" Jul 6 23:52:41.880441 kubelet[1790]: I0706 23:52:41.880215 1790 memory_manager.go:170] "Starting memorymanager" policy="None" Jul 6 23:52:41.880441 kubelet[1790]: I0706 23:52:41.880234 1790 state_mem.go:35] "Initializing new in-memory state store" Jul 6 23:52:41.888455 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jul 6 23:52:41.899347 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jul 6 23:52:41.902468 kubelet[1790]: I0706 23:52:41.902429 1790 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 6 23:52:41.904502 kubelet[1790]: I0706 23:52:41.904475 1790 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 6 23:52:41.904635 kubelet[1790]: I0706 23:52:41.904594 1790 status_manager.go:217] "Starting to sync pod status with apiserver" Jul 6 23:52:41.905573 kubelet[1790]: I0706 23:52:41.905561 1790 kubelet.go:2321] "Starting kubelet main sync loop" Jul 6 23:52:41.906136 kubelet[1790]: E0706 23:52:41.905685 1790 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Jul 6 23:52:41.906595 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jul 6 23:52:41.910266 kubelet[1790]: I0706 23:52:41.910234 1790 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 6 23:52:41.910422 kubelet[1790]: I0706 23:52:41.910396 1790 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 6 23:52:41.910457 kubelet[1790]: I0706 23:52:41.910411 1790 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 6 23:52:41.910863 kubelet[1790]: I0706 23:52:41.910850 1790 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 6 23:52:41.912740 kubelet[1790]: E0706 23:52:41.912724 1790 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"192.168.184.63\" not found" Jul 6 23:52:42.011118 kubelet[1790]: I0706 23:52:42.011084 1790 kubelet_node_status.go:72] "Attempting to register node" node="192.168.184.63" Jul 6 23:52:42.015055 kubelet[1790]: I0706 23:52:42.015010 1790 kubelet_node_status.go:75] "Successfully registered node" node="192.168.184.63" Jul 6 23:52:42.022858 kubelet[1790]: I0706 23:52:42.021907 1790 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 6 23:52:42.023102 containerd[1479]: time="2025-07-06T23:52:42.022455314Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 6 23:52:42.023641 kubelet[1790]: I0706 23:52:42.023493 1790 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 6 23:52:42.045331 sudo[1652]: pam_unix(sudo:session): session closed for user root Jul 6 23:52:42.101658 sshd[1651]: Connection closed by 147.75.109.163 port 33402 Jul 6 23:52:42.102847 sshd-session[1649]: pam_unix(sshd:session): session closed for user core Jul 6 23:52:42.108998 systemd[1]: sshd@4-172.236.103.247:22-147.75.109.163:33402.service: Deactivated successfully. Jul 6 23:52:42.112154 systemd[1]: session-5.scope: Deactivated successfully. Jul 6 23:52:42.112382 systemd[1]: session-5.scope: Consumed 438ms CPU time, 74.8M memory peak. Jul 6 23:52:42.114032 systemd-logind[1458]: Session 5 logged out. Waiting for processes to exit. Jul 6 23:52:42.115046 systemd-logind[1458]: Removed session 5. Jul 6 23:52:42.785179 kubelet[1790]: I0706 23:52:42.785114 1790 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 6 23:52:42.785785 kubelet[1790]: W0706 23:52:42.785329 1790 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 6 23:52:42.785785 kubelet[1790]: W0706 23:52:42.785719 1790 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 6 23:52:42.785785 kubelet[1790]: W0706 23:52:42.785751 1790 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 6 23:52:42.823162 kubelet[1790]: I0706 23:52:42.823096 1790 apiserver.go:52] "Watching apiserver" Jul 6 23:52:42.823368 kubelet[1790]: E0706 23:52:42.823103 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:42.838241 systemd[1]: Created slice kubepods-besteffort-pod0082a0d9_5ef1_4243_b83c_24cc447cf5d1.slice - libcontainer container kubepods-besteffort-pod0082a0d9_5ef1_4243_b83c_24cc447cf5d1.slice. Jul 6 23:52:42.842183 kubelet[1790]: I0706 23:52:42.842115 1790 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Jul 6 23:52:42.845249 kubelet[1790]: I0706 23:52:42.845164 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/31b4122c-4a38-40db-85df-f61099d8ff68-flannel-cfg\") pod \"kube-flannel-ds-vbslp\" (UID: \"31b4122c-4a38-40db-85df-f61099d8ff68\") " pod="kube-flannel/kube-flannel-ds-vbslp" Jul 6 23:52:42.845249 kubelet[1790]: I0706 23:52:42.845213 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0082a0d9-5ef1-4243-b83c-24cc447cf5d1-xtables-lock\") pod \"kube-proxy-47wxb\" (UID: \"0082a0d9-5ef1-4243-b83c-24cc447cf5d1\") " pod="kube-system/kube-proxy-47wxb" Jul 6 23:52:42.845249 kubelet[1790]: I0706 23:52:42.845236 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/31b4122c-4a38-40db-85df-f61099d8ff68-run\") pod \"kube-flannel-ds-vbslp\" (UID: \"31b4122c-4a38-40db-85df-f61099d8ff68\") " pod="kube-flannel/kube-flannel-ds-vbslp" Jul 6 23:52:42.845340 kubelet[1790]: I0706 23:52:42.845254 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/31b4122c-4a38-40db-85df-f61099d8ff68-cni-plugin\") pod \"kube-flannel-ds-vbslp\" (UID: \"31b4122c-4a38-40db-85df-f61099d8ff68\") " pod="kube-flannel/kube-flannel-ds-vbslp" Jul 6 23:52:42.845340 kubelet[1790]: I0706 23:52:42.845273 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/31b4122c-4a38-40db-85df-f61099d8ff68-cni\") pod \"kube-flannel-ds-vbslp\" (UID: \"31b4122c-4a38-40db-85df-f61099d8ff68\") " pod="kube-flannel/kube-flannel-ds-vbslp" Jul 6 23:52:42.845340 kubelet[1790]: I0706 23:52:42.845291 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0082a0d9-5ef1-4243-b83c-24cc447cf5d1-lib-modules\") pod \"kube-proxy-47wxb\" (UID: \"0082a0d9-5ef1-4243-b83c-24cc447cf5d1\") " pod="kube-system/kube-proxy-47wxb" Jul 6 23:52:42.845340 kubelet[1790]: I0706 23:52:42.845309 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-29vls\" (UniqueName: \"kubernetes.io/projected/0082a0d9-5ef1-4243-b83c-24cc447cf5d1-kube-api-access-29vls\") pod \"kube-proxy-47wxb\" (UID: \"0082a0d9-5ef1-4243-b83c-24cc447cf5d1\") " pod="kube-system/kube-proxy-47wxb" Jul 6 23:52:42.845340 kubelet[1790]: I0706 23:52:42.845325 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/31b4122c-4a38-40db-85df-f61099d8ff68-xtables-lock\") pod \"kube-flannel-ds-vbslp\" (UID: \"31b4122c-4a38-40db-85df-f61099d8ff68\") " pod="kube-flannel/kube-flannel-ds-vbslp" Jul 6 23:52:42.845448 kubelet[1790]: I0706 23:52:42.845344 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rtvlg\" (UniqueName: \"kubernetes.io/projected/31b4122c-4a38-40db-85df-f61099d8ff68-kube-api-access-rtvlg\") pod \"kube-flannel-ds-vbslp\" (UID: \"31b4122c-4a38-40db-85df-f61099d8ff68\") " pod="kube-flannel/kube-flannel-ds-vbslp" Jul 6 23:52:42.845448 kubelet[1790]: I0706 23:52:42.845369 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/0082a0d9-5ef1-4243-b83c-24cc447cf5d1-kube-proxy\") pod \"kube-proxy-47wxb\" (UID: \"0082a0d9-5ef1-4243-b83c-24cc447cf5d1\") " pod="kube-system/kube-proxy-47wxb" Jul 6 23:52:42.861283 systemd[1]: Created slice kubepods-burstable-pod31b4122c_4a38_40db_85df_f61099d8ff68.slice - libcontainer container kubepods-burstable-pod31b4122c_4a38_40db_85df_f61099d8ff68.slice. Jul 6 23:52:43.159425 kubelet[1790]: E0706 23:52:43.159240 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:43.160679 containerd[1479]: time="2025-07-06T23:52:43.160604323Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-47wxb,Uid:0082a0d9-5ef1-4243-b83c-24cc447cf5d1,Namespace:kube-system,Attempt:0,}" Jul 6 23:52:43.174458 kubelet[1790]: E0706 23:52:43.174422 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:43.174860 containerd[1479]: time="2025-07-06T23:52:43.174835650Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vbslp,Uid:31b4122c-4a38-40db-85df-f61099d8ff68,Namespace:kube-flannel,Attempt:0,}" Jul 6 23:52:43.705073 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1606078258.mount: Deactivated successfully. Jul 6 23:52:43.711085 containerd[1479]: time="2025-07-06T23:52:43.710818168Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:52:43.712038 containerd[1479]: time="2025-07-06T23:52:43.711996198Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:52:43.712742 containerd[1479]: time="2025-07-06T23:52:43.712695029Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Jul 6 23:52:43.713020 containerd[1479]: time="2025-07-06T23:52:43.712924869Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312062" Jul 6 23:52:43.714776 containerd[1479]: time="2025-07-06T23:52:43.713517649Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:52:43.716444 containerd[1479]: time="2025-07-06T23:52:43.716402891Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:52:43.717249 containerd[1479]: time="2025-07-06T23:52:43.717206031Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 542.301821ms" Jul 6 23:52:43.718911 containerd[1479]: time="2025-07-06T23:52:43.718888392Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 557.903539ms" Jul 6 23:52:43.821503 containerd[1479]: time="2025-07-06T23:52:43.821059573Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:52:43.821503 containerd[1479]: time="2025-07-06T23:52:43.821306553Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:52:43.821503 containerd[1479]: time="2025-07-06T23:52:43.821317993Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:52:43.821503 containerd[1479]: time="2025-07-06T23:52:43.821409103Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:52:43.824298 kubelet[1790]: E0706 23:52:43.824255 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:43.826412 containerd[1479]: time="2025-07-06T23:52:43.826211006Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:52:43.826558 containerd[1479]: time="2025-07-06T23:52:43.826458106Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:52:43.826602 containerd[1479]: time="2025-07-06T23:52:43.826565386Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:52:43.827872 containerd[1479]: time="2025-07-06T23:52:43.826794746Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:52:43.887874 systemd[1]: Started cri-containerd-98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d.scope - libcontainer container 98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d. Jul 6 23:52:43.893192 systemd[1]: Started cri-containerd-603adeac07654b117dbb1f8b90a8be22113c0182e19ed57e5b3927e5ee0279d1.scope - libcontainer container 603adeac07654b117dbb1f8b90a8be22113c0182e19ed57e5b3927e5ee0279d1. Jul 6 23:52:43.931656 containerd[1479]: time="2025-07-06T23:52:43.931591858Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-47wxb,Uid:0082a0d9-5ef1-4243-b83c-24cc447cf5d1,Namespace:kube-system,Attempt:0,} returns sandbox id \"603adeac07654b117dbb1f8b90a8be22113c0182e19ed57e5b3927e5ee0279d1\"" Jul 6 23:52:43.933355 kubelet[1790]: E0706 23:52:43.933321 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:43.936267 containerd[1479]: time="2025-07-06T23:52:43.935828820Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\"" Jul 6 23:52:43.939568 containerd[1479]: time="2025-07-06T23:52:43.939530382Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vbslp,Uid:31b4122c-4a38-40db-85df-f61099d8ff68,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d\"" Jul 6 23:52:43.940260 kubelet[1790]: E0706 23:52:43.940238 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:44.825798 kubelet[1790]: E0706 23:52:44.825433 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:44.899844 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1129143738.mount: Deactivated successfully. Jul 6 23:52:45.255540 containerd[1479]: time="2025-07-06T23:52:45.255414370Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:45.256452 containerd[1479]: time="2025-07-06T23:52:45.256316330Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.10: active requests=0, bytes read=30383949" Jul 6 23:52:45.257148 containerd[1479]: time="2025-07-06T23:52:45.256932440Z" level=info msg="ImageCreate event name:\"sha256:3ed600862d3e69931e0f9f4dbf5c2b46343af40aa079772434f13de771bdc30c\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:45.258558 containerd[1479]: time="2025-07-06T23:52:45.258528671Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:45.259221 containerd[1479]: time="2025-07-06T23:52:45.259184932Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.10\" with image id \"sha256:3ed600862d3e69931e0f9f4dbf5c2b46343af40aa079772434f13de771bdc30c\", repo tag \"registry.k8s.io/kube-proxy:v1.31.10\", repo digest \"registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d\", size \"30382962\" in 1.323310262s" Jul 6 23:52:45.259221 containerd[1479]: time="2025-07-06T23:52:45.259218092Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\" returns image reference \"sha256:3ed600862d3e69931e0f9f4dbf5c2b46343af40aa079772434f13de771bdc30c\"" Jul 6 23:52:45.260836 containerd[1479]: time="2025-07-06T23:52:45.260196862Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 6 23:52:45.261921 containerd[1479]: time="2025-07-06T23:52:45.261894063Z" level=info msg="CreateContainer within sandbox \"603adeac07654b117dbb1f8b90a8be22113c0182e19ed57e5b3927e5ee0279d1\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 6 23:52:45.262620 systemd-resolved[1392]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 172.232.0.20. Jul 6 23:52:45.277544 containerd[1479]: time="2025-07-06T23:52:45.277515751Z" level=info msg="CreateContainer within sandbox \"603adeac07654b117dbb1f8b90a8be22113c0182e19ed57e5b3927e5ee0279d1\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"7fbc2685d1b1db30da4b9831c91f568b3403532e16b9b2b4f71de371cdf3c900\"" Jul 6 23:52:45.278248 containerd[1479]: time="2025-07-06T23:52:45.278228971Z" level=info msg="StartContainer for \"7fbc2685d1b1db30da4b9831c91f568b3403532e16b9b2b4f71de371cdf3c900\"" Jul 6 23:52:45.304637 systemd[1]: run-containerd-runc-k8s.io-7fbc2685d1b1db30da4b9831c91f568b3403532e16b9b2b4f71de371cdf3c900-runc.QvFmjz.mount: Deactivated successfully. Jul 6 23:52:45.315957 systemd[1]: Started cri-containerd-7fbc2685d1b1db30da4b9831c91f568b3403532e16b9b2b4f71de371cdf3c900.scope - libcontainer container 7fbc2685d1b1db30da4b9831c91f568b3403532e16b9b2b4f71de371cdf3c900. Jul 6 23:52:45.350568 containerd[1479]: time="2025-07-06T23:52:45.350491557Z" level=info msg="StartContainer for \"7fbc2685d1b1db30da4b9831c91f568b3403532e16b9b2b4f71de371cdf3c900\" returns successfully" Jul 6 23:52:45.826595 kubelet[1790]: E0706 23:52:45.826541 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:45.923007 kubelet[1790]: E0706 23:52:45.922490 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:45.930897 kubelet[1790]: I0706 23:52:45.930617 1790 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-47wxb" podStartSLOduration=1.6055460350000001 podStartE2EDuration="2.930583067s" podCreationTimestamp="2025-07-06 23:52:43 +0000 UTC" firstStartedPulling="2025-07-06 23:52:43.93498964 +0000 UTC m=+2.420712761" lastFinishedPulling="2025-07-06 23:52:45.260026562 +0000 UTC m=+3.745749793" observedRunningTime="2025-07-06 23:52:45.930511277 +0000 UTC m=+4.416234388" watchObservedRunningTime="2025-07-06 23:52:45.930583067 +0000 UTC m=+4.416306198" Jul 6 23:52:46.011367 containerd[1479]: time="2025-07-06T23:52:46.010552947Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:46.011367 containerd[1479]: time="2025-07-06T23:52:46.011327867Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Jul 6 23:52:46.011845 containerd[1479]: time="2025-07-06T23:52:46.011803678Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:46.013542 containerd[1479]: time="2025-07-06T23:52:46.013520798Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:46.014233 containerd[1479]: time="2025-07-06T23:52:46.014204979Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 753.981317ms" Jul 6 23:52:46.014267 containerd[1479]: time="2025-07-06T23:52:46.014235019Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Jul 6 23:52:46.016075 containerd[1479]: time="2025-07-06T23:52:46.016052490Z" level=info msg="CreateContainer within sandbox \"98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 6 23:52:46.031459 containerd[1479]: time="2025-07-06T23:52:46.031436987Z" level=info msg="CreateContainer within sandbox \"98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29\"" Jul 6 23:52:46.031788 containerd[1479]: time="2025-07-06T23:52:46.031735108Z" level=info msg="StartContainer for \"7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29\"" Jul 6 23:52:46.060075 systemd[1]: Started cri-containerd-7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29.scope - libcontainer container 7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29. Jul 6 23:52:46.088750 containerd[1479]: time="2025-07-06T23:52:46.088234076Z" level=info msg="StartContainer for \"7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29\" returns successfully" Jul 6 23:52:46.089312 systemd[1]: cri-containerd-7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29.scope: Deactivated successfully. Jul 6 23:52:46.157509 containerd[1479]: time="2025-07-06T23:52:46.157440890Z" level=info msg="shim disconnected" id=7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29 namespace=k8s.io Jul 6 23:52:46.157509 containerd[1479]: time="2025-07-06T23:52:46.157502980Z" level=warning msg="cleaning up after shim disconnected" id=7b186f8a01ed321501216fab7a63568585a02ed79a925dc30147f5c297fb6c29 namespace=k8s.io Jul 6 23:52:46.157509 containerd[1479]: time="2025-07-06T23:52:46.157511550Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jul 6 23:52:46.827216 kubelet[1790]: E0706 23:52:46.827152 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:46.925516 kubelet[1790]: E0706 23:52:46.925461 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:46.927786 kubelet[1790]: E0706 23:52:46.926821 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:46.927956 containerd[1479]: time="2025-07-06T23:52:46.927910505Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 6 23:52:47.725362 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3101000826.mount: Deactivated successfully. Jul 6 23:52:47.828059 kubelet[1790]: E0706 23:52:47.828019 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:48.230524 containerd[1479]: time="2025-07-06T23:52:48.230479156Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:48.231369 containerd[1479]: time="2025-07-06T23:52:48.231335687Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866357" Jul 6 23:52:48.231898 containerd[1479]: time="2025-07-06T23:52:48.231868667Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:48.234107 containerd[1479]: time="2025-07-06T23:52:48.234071558Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:52:48.235488 containerd[1479]: time="2025-07-06T23:52:48.234952788Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 1.306995633s" Jul 6 23:52:48.235488 containerd[1479]: time="2025-07-06T23:52:48.234984348Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Jul 6 23:52:48.237199 containerd[1479]: time="2025-07-06T23:52:48.237165709Z" level=info msg="CreateContainer within sandbox \"98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 6 23:52:48.247819 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount671698340.mount: Deactivated successfully. Jul 6 23:52:48.253463 containerd[1479]: time="2025-07-06T23:52:48.253433878Z" level=info msg="CreateContainer within sandbox \"98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0\"" Jul 6 23:52:48.254560 containerd[1479]: time="2025-07-06T23:52:48.253883498Z" level=info msg="StartContainer for \"3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0\"" Jul 6 23:52:48.279881 systemd[1]: Started cri-containerd-3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0.scope - libcontainer container 3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0. Jul 6 23:52:48.302525 systemd[1]: cri-containerd-3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0.scope: Deactivated successfully. Jul 6 23:52:48.303408 containerd[1479]: time="2025-07-06T23:52:48.303380123Z" level=info msg="StartContainer for \"3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0\" returns successfully" Jul 6 23:52:48.358781 containerd[1479]: time="2025-07-06T23:52:48.358702700Z" level=info msg="shim disconnected" id=3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0 namespace=k8s.io Jul 6 23:52:48.359024 containerd[1479]: time="2025-07-06T23:52:48.358996580Z" level=warning msg="cleaning up after shim disconnected" id=3662ededddcfae0299ff498502a3c370260d8486c2c887ff2d4ff89bb2f71ab0 namespace=k8s.io Jul 6 23:52:48.359024 containerd[1479]: time="2025-07-06T23:52:48.359013250Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jul 6 23:52:48.362872 kubelet[1790]: I0706 23:52:48.362827 1790 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Jul 6 23:52:48.829309 kubelet[1790]: E0706 23:52:48.829278 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:48.929782 kubelet[1790]: E0706 23:52:48.929738 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:48.931261 containerd[1479]: time="2025-07-06T23:52:48.931232406Z" level=info msg="CreateContainer within sandbox \"98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 6 23:52:48.943604 containerd[1479]: time="2025-07-06T23:52:48.943573932Z" level=info msg="CreateContainer within sandbox \"98a759ecb6e0e6f0f13cc59392b340d8d40b5a3bd9e4a949addf05cbd04e699d\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"716efb142f88e37d254a9024f46bba096d00ae3045637e8bba1a66693c1bb017\"" Jul 6 23:52:48.943945 containerd[1479]: time="2025-07-06T23:52:48.943926763Z" level=info msg="StartContainer for \"716efb142f88e37d254a9024f46bba096d00ae3045637e8bba1a66693c1bb017\"" Jul 6 23:52:48.978893 systemd[1]: Started cri-containerd-716efb142f88e37d254a9024f46bba096d00ae3045637e8bba1a66693c1bb017.scope - libcontainer container 716efb142f88e37d254a9024f46bba096d00ae3045637e8bba1a66693c1bb017. Jul 6 23:52:49.011532 containerd[1479]: time="2025-07-06T23:52:49.011481676Z" level=info msg="StartContainer for \"716efb142f88e37d254a9024f46bba096d00ae3045637e8bba1a66693c1bb017\" returns successfully" Jul 6 23:52:49.406653 systemd[1]: Created slice kubepods-besteffort-podc923ba51_31d2_45eb_9344_2e64d9d91391.slice - libcontainer container kubepods-besteffort-podc923ba51_31d2_45eb_9344_2e64d9d91391.slice. Jul 6 23:52:49.491744 kubelet[1790]: I0706 23:52:49.491706 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rx65f\" (UniqueName: \"kubernetes.io/projected/c923ba51-31d2-45eb-9344-2e64d9d91391-kube-api-access-rx65f\") pod \"nginx-deployment-8587fbcb89-gzzzd\" (UID: \"c923ba51-31d2-45eb-9344-2e64d9d91391\") " pod="default/nginx-deployment-8587fbcb89-gzzzd" Jul 6 23:52:49.710653 containerd[1479]: time="2025-07-06T23:52:49.710494476Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-gzzzd,Uid:c923ba51-31d2-45eb-9344-2e64d9d91391,Namespace:default,Attempt:0,}" Jul 6 23:52:49.743495 systemd[1]: run-netns-cni\x2d6a89d77e\x2d0d94\x2db19f\x2d2847\x2df7905812e26b.mount: Deactivated successfully. Jul 6 23:52:49.743614 systemd[1]: run-containerd-io.containerd.grpc.v1.cri-sandboxes-a0f5015b1262fc6137f3cd77917b393283b79d5ad30f737d6d46df207e47333e-shm.mount: Deactivated successfully. Jul 6 23:52:49.747307 containerd[1479]: time="2025-07-06T23:52:49.747219094Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-gzzzd,Uid:c923ba51-31d2-45eb-9344-2e64d9d91391,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"a0f5015b1262fc6137f3cd77917b393283b79d5ad30f737d6d46df207e47333e\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Jul 6 23:52:49.747807 kubelet[1790]: E0706 23:52:49.747589 1790 log.go:32] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"a0f5015b1262fc6137f3cd77917b393283b79d5ad30f737d6d46df207e47333e\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Jul 6 23:52:49.747807 kubelet[1790]: E0706 23:52:49.747659 1790 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"a0f5015b1262fc6137f3cd77917b393283b79d5ad30f737d6d46df207e47333e\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-8587fbcb89-gzzzd" Jul 6 23:52:49.747807 kubelet[1790]: E0706 23:52:49.747678 1790 kuberuntime_manager.go:1170] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"a0f5015b1262fc6137f3cd77917b393283b79d5ad30f737d6d46df207e47333e\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-8587fbcb89-gzzzd" Jul 6 23:52:49.747807 kubelet[1790]: E0706 23:52:49.747715 1790 pod_workers.go:1301] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-8587fbcb89-gzzzd_default(c923ba51-31d2-45eb-9344-2e64d9d91391)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-8587fbcb89-gzzzd_default(c923ba51-31d2-45eb-9344-2e64d9d91391)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"a0f5015b1262fc6137f3cd77917b393283b79d5ad30f737d6d46df207e47333e\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-8587fbcb89-gzzzd" podUID="c923ba51-31d2-45eb-9344-2e64d9d91391" Jul 6 23:52:49.830394 kubelet[1790]: E0706 23:52:49.830275 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:49.932478 kubelet[1790]: E0706 23:52:49.932440 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:49.941982 kubelet[1790]: I0706 23:52:49.941943 1790 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-vbslp" podStartSLOduration=2.646417175 podStartE2EDuration="6.941922481s" podCreationTimestamp="2025-07-06 23:52:43 +0000 UTC" firstStartedPulling="2025-07-06 23:52:43.940600253 +0000 UTC m=+2.426323364" lastFinishedPulling="2025-07-06 23:52:48.236105559 +0000 UTC m=+6.721828670" observedRunningTime="2025-07-06 23:52:49.941350691 +0000 UTC m=+8.427073812" watchObservedRunningTime="2025-07-06 23:52:49.941922481 +0000 UTC m=+8.427645602" Jul 6 23:52:50.054249 systemd-timesyncd[1393]: Network configuration changed, trying to establish connection. Jul 6 23:52:50.066773 systemd-networkd[1389]: flannel.1: Link UP Jul 6 23:52:50.066784 systemd-networkd[1389]: flannel.1: Gained carrier Jul 6 23:52:51.161177 systemd-resolved[1392]: Clock change detected. Flushing caches. Jul 6 23:52:51.161717 systemd-timesyncd[1393]: Contacted time server [2603:c024:c005:a600:efb6:d213:cad8:251d]:123 (2.flatcar.pool.ntp.org). Jul 6 23:52:51.161973 systemd-timesyncd[1393]: Initial clock synchronization to Sun 2025-07-06 23:52:51.160820 UTC. Jul 6 23:52:51.886036 kubelet[1790]: E0706 23:52:51.885936 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:51.990561 kubelet[1790]: E0706 23:52:51.990515 1790 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 172.232.0.20 172.232.0.15 172.232.0.18" Jul 6 23:52:52.822598 systemd-networkd[1389]: flannel.1: Gained IPv6LL Jul 6 23:52:52.886873 kubelet[1790]: E0706 23:52:52.886785 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:53.887696 kubelet[1790]: E0706 23:52:53.887589 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:54.888447 kubelet[1790]: E0706 23:52:54.888377 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:55.889336 kubelet[1790]: E0706 23:52:55.889281 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:56.890464 kubelet[1790]: E0706 23:52:56.890396 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:57.891183 kubelet[1790]: E0706 23:52:57.891098 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:58.891784 kubelet[1790]: E0706 23:52:58.891720 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:52:59.892572 kubelet[1790]: E0706 23:52:59.892507 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:00.892963 kubelet[1790]: E0706 23:53:00.892898 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:01.893743 kubelet[1790]: E0706 23:53:01.893689 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:02.876598 kubelet[1790]: E0706 23:53:02.876531 1790 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:02.894068 kubelet[1790]: E0706 23:53:02.894046 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:03.894490 kubelet[1790]: E0706 23:53:03.894446 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:04.089002 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jul 6 23:53:04.895630 kubelet[1790]: E0706 23:53:04.895587 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:04.963315 containerd[1479]: time="2025-07-06T23:53:04.962906717Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-gzzzd,Uid:c923ba51-31d2-45eb-9344-2e64d9d91391,Namespace:default,Attempt:0,}" Jul 6 23:53:04.985582 systemd-networkd[1389]: cni0: Link UP Jul 6 23:53:04.985589 systemd-networkd[1389]: cni0: Gained carrier Jul 6 23:53:04.994344 kernel: cni0: port 1(veth5901c967) entered blocking state Jul 6 23:53:04.994410 kernel: cni0: port 1(veth5901c967) entered disabled state Jul 6 23:53:04.991228 systemd-networkd[1389]: veth5901c967: Link UP Jul 6 23:53:04.996566 kernel: veth5901c967: entered allmulticast mode Jul 6 23:53:04.998397 kernel: veth5901c967: entered promiscuous mode Jul 6 23:53:04.999729 systemd-networkd[1389]: cni0: Lost carrier Jul 6 23:53:05.013994 kernel: cni0: port 1(veth5901c967) entered blocking state Jul 6 23:53:05.014043 kernel: cni0: port 1(veth5901c967) entered forwarding state Jul 6 23:53:05.014573 systemd-networkd[1389]: veth5901c967: Gained carrier Jul 6 23:53:05.017062 systemd-networkd[1389]: cni0: Gained carrier Jul 6 23:53:05.022320 containerd[1479]: 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)(0xc000018938), "name":"cbr0", "type":"bridge"} Jul 6 23:53:05.022320 containerd[1479]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:53:05.046993 containerd[1479]: {"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-06T23:53:05.046895579Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:53:05.046993 containerd[1479]: time="2025-07-06T23:53:05.046947949Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:53:05.046993 containerd[1479]: time="2025-07-06T23:53:05.046961689Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:53:05.047191 containerd[1479]: time="2025-07-06T23:53:05.047039729Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:53:05.067497 systemd[1]: run-containerd-runc-k8s.io-ed7de3fe8715308807263d336387bf3f55613b84eeb15c161ca9bc8330f1f924-runc.YqiXxp.mount: Deactivated successfully. Jul 6 23:53:05.077417 systemd[1]: Started cri-containerd-ed7de3fe8715308807263d336387bf3f55613b84eeb15c161ca9bc8330f1f924.scope - libcontainer container ed7de3fe8715308807263d336387bf3f55613b84eeb15c161ca9bc8330f1f924. Jul 6 23:53:05.113114 containerd[1479]: time="2025-07-06T23:53:05.113084392Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-gzzzd,Uid:c923ba51-31d2-45eb-9344-2e64d9d91391,Namespace:default,Attempt:0,} returns sandbox id \"ed7de3fe8715308807263d336387bf3f55613b84eeb15c161ca9bc8330f1f924\"" Jul 6 23:53:05.114640 containerd[1479]: time="2025-07-06T23:53:05.114547473Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 6 23:53:05.897284 kubelet[1790]: E0706 23:53:05.896370 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:06.198425 systemd-networkd[1389]: cni0: Gained IPv6LL Jul 6 23:53:06.897361 kubelet[1790]: E0706 23:53:06.897325 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:06.965917 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3035473806.mount: Deactivated successfully. Jul 6 23:53:06.966400 systemd-networkd[1389]: veth5901c967: Gained IPv6LL Jul 6 23:53:07.867556 containerd[1479]: time="2025-07-06T23:53:07.867504318Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:53:07.868545 containerd[1479]: time="2025-07-06T23:53:07.868498819Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73313230" Jul 6 23:53:07.869170 containerd[1479]: time="2025-07-06T23:53:07.869130549Z" level=info msg="ImageCreate event name:\"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:53:07.871677 containerd[1479]: time="2025-07-06T23:53:07.871637041Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:53:07.872953 containerd[1479]: time="2025-07-06T23:53:07.872364481Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"73313108\" in 2.757792628s" Jul 6 23:53:07.872953 containerd[1479]: time="2025-07-06T23:53:07.872398001Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\"" Jul 6 23:53:07.874642 containerd[1479]: time="2025-07-06T23:53:07.874617282Z" level=info msg="CreateContainer within sandbox \"ed7de3fe8715308807263d336387bf3f55613b84eeb15c161ca9bc8330f1f924\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 6 23:53:07.884006 containerd[1479]: time="2025-07-06T23:53:07.883983097Z" level=info msg="CreateContainer within sandbox \"ed7de3fe8715308807263d336387bf3f55613b84eeb15c161ca9bc8330f1f924\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"7cdd9d2bf200988acc2c686c09cccad499468819ecaad12e94f09fe684ef8652\"" Jul 6 23:53:07.885001 containerd[1479]: time="2025-07-06T23:53:07.884948927Z" level=info msg="StartContainer for \"7cdd9d2bf200988acc2c686c09cccad499468819ecaad12e94f09fe684ef8652\"" Jul 6 23:53:07.899773 kubelet[1790]: E0706 23:53:07.898998 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:07.911141 systemd[1]: run-containerd-runc-k8s.io-7cdd9d2bf200988acc2c686c09cccad499468819ecaad12e94f09fe684ef8652-runc.j6XC87.mount: Deactivated successfully. Jul 6 23:53:07.924394 systemd[1]: Started cri-containerd-7cdd9d2bf200988acc2c686c09cccad499468819ecaad12e94f09fe684ef8652.scope - libcontainer container 7cdd9d2bf200988acc2c686c09cccad499468819ecaad12e94f09fe684ef8652. Jul 6 23:53:07.949571 containerd[1479]: time="2025-07-06T23:53:07.949499639Z" level=info msg="StartContainer for \"7cdd9d2bf200988acc2c686c09cccad499468819ecaad12e94f09fe684ef8652\" returns successfully" Jul 6 23:53:08.028679 kubelet[1790]: I0706 23:53:08.028624 1790 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-gzzzd" podStartSLOduration=15.269276541 podStartE2EDuration="18.028609949s" podCreationTimestamp="2025-07-06 23:52:50 +0000 UTC" firstStartedPulling="2025-07-06 23:53:05.114165713 +0000 UTC m=+22.544385456" lastFinishedPulling="2025-07-06 23:53:07.873499111 +0000 UTC m=+25.303718864" observedRunningTime="2025-07-06 23:53:08.028225179 +0000 UTC m=+25.458444932" watchObservedRunningTime="2025-07-06 23:53:08.028609949 +0000 UTC m=+25.458829692" Jul 6 23:53:08.899394 kubelet[1790]: E0706 23:53:08.899352 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:09.900388 kubelet[1790]: E0706 23:53:09.900345 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:10.900938 kubelet[1790]: E0706 23:53:10.900905 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:11.135936 systemd[1]: Created slice kubepods-besteffort-pod33dc703a_b770_48e2_aa06_6a251f52bf88.slice - libcontainer container kubepods-besteffort-pod33dc703a_b770_48e2_aa06_6a251f52bf88.slice. Jul 6 23:53:11.260843 kubelet[1790]: I0706 23:53:11.260684 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/33dc703a-b770-48e2-aa06-6a251f52bf88-data\") pod \"nfs-server-provisioner-0\" (UID: \"33dc703a-b770-48e2-aa06-6a251f52bf88\") " pod="default/nfs-server-provisioner-0" Jul 6 23:53:11.260843 kubelet[1790]: I0706 23:53:11.260733 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gvqjh\" (UniqueName: \"kubernetes.io/projected/33dc703a-b770-48e2-aa06-6a251f52bf88-kube-api-access-gvqjh\") pod \"nfs-server-provisioner-0\" (UID: \"33dc703a-b770-48e2-aa06-6a251f52bf88\") " pod="default/nfs-server-provisioner-0" Jul 6 23:53:11.440252 containerd[1479]: time="2025-07-06T23:53:11.440145274Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:33dc703a-b770-48e2-aa06-6a251f52bf88,Namespace:default,Attempt:0,}" Jul 6 23:53:11.463818 systemd-networkd[1389]: veth54b6578e: Link UP Jul 6 23:53:11.466423 kernel: cni0: port 2(veth54b6578e) entered blocking state Jul 6 23:53:11.466479 kernel: cni0: port 2(veth54b6578e) entered disabled state Jul 6 23:53:11.469520 kernel: veth54b6578e: entered allmulticast mode Jul 6 23:53:11.469561 kernel: veth54b6578e: entered promiscuous mode Jul 6 23:53:11.476476 kernel: cni0: port 2(veth54b6578e) entered blocking state Jul 6 23:53:11.476506 kernel: cni0: port 2(veth54b6578e) entered forwarding state Jul 6 23:53:11.476600 systemd-networkd[1389]: veth54b6578e: Gained carrier Jul 6 23:53:11.479134 containerd[1479]: 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)(0xc00001a938), "name":"cbr0", "type":"bridge"} Jul 6 23:53:11.479134 containerd[1479]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:53:11.499978 containerd[1479]: {"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-06T23:53:11.499621193Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:53:11.499978 containerd[1479]: time="2025-07-06T23:53:11.499668603Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:53:11.499978 containerd[1479]: time="2025-07-06T23:53:11.499680433Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:53:11.499978 containerd[1479]: time="2025-07-06T23:53:11.499745003Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:53:11.526402 systemd[1]: Started cri-containerd-27b9a7d2437c16204160deb0836b473910451b456a5d3269d58cfc063bce94a3.scope - libcontainer container 27b9a7d2437c16204160deb0836b473910451b456a5d3269d58cfc063bce94a3. Jul 6 23:53:11.564841 containerd[1479]: time="2025-07-06T23:53:11.564786406Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:33dc703a-b770-48e2-aa06-6a251f52bf88,Namespace:default,Attempt:0,} returns sandbox id \"27b9a7d2437c16204160deb0836b473910451b456a5d3269d58cfc063bce94a3\"" Jul 6 23:53:11.566175 containerd[1479]: time="2025-07-06T23:53:11.566145267Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 6 23:53:11.901519 kubelet[1790]: E0706 23:53:11.901371 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:12.902390 kubelet[1790]: E0706 23:53:12.902284 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:13.174439 systemd-networkd[1389]: veth54b6578e: Gained IPv6LL Jul 6 23:53:13.385734 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount830834443.mount: Deactivated successfully. Jul 6 23:53:13.903386 kubelet[1790]: E0706 23:53:13.903334 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:14.903605 kubelet[1790]: E0706 23:53:14.903559 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:14.986212 containerd[1479]: time="2025-07-06T23:53:14.986152555Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:53:14.987296 containerd[1479]: time="2025-07-06T23:53:14.987241776Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039412" Jul 6 23:53:14.987855 containerd[1479]: time="2025-07-06T23:53:14.987804486Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:53:14.990932 containerd[1479]: time="2025-07-06T23:53:14.990896888Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:53:14.995103 containerd[1479]: time="2025-07-06T23:53:14.994521900Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 3.428341003s" Jul 6 23:53:14.995103 containerd[1479]: time="2025-07-06T23:53:14.994557090Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Jul 6 23:53:14.997236 containerd[1479]: time="2025-07-06T23:53:14.997196261Z" level=info msg="CreateContainer within sandbox \"27b9a7d2437c16204160deb0836b473910451b456a5d3269d58cfc063bce94a3\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 6 23:53:15.018422 containerd[1479]: time="2025-07-06T23:53:15.018396171Z" level=info msg="CreateContainer within sandbox \"27b9a7d2437c16204160deb0836b473910451b456a5d3269d58cfc063bce94a3\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"a8f1ac4c34d8e475ddcdfa6ef335a0bde33cd57bc28e89d58ba141f052e5cdaa\"" Jul 6 23:53:15.019103 containerd[1479]: time="2025-07-06T23:53:15.019075492Z" level=info msg="StartContainer for \"a8f1ac4c34d8e475ddcdfa6ef335a0bde33cd57bc28e89d58ba141f052e5cdaa\"" Jul 6 23:53:15.062392 systemd[1]: Started cri-containerd-a8f1ac4c34d8e475ddcdfa6ef335a0bde33cd57bc28e89d58ba141f052e5cdaa.scope - libcontainer container a8f1ac4c34d8e475ddcdfa6ef335a0bde33cd57bc28e89d58ba141f052e5cdaa. Jul 6 23:53:15.088379 containerd[1479]: time="2025-07-06T23:53:15.088321076Z" level=info msg="StartContainer for \"a8f1ac4c34d8e475ddcdfa6ef335a0bde33cd57bc28e89d58ba141f052e5cdaa\" returns successfully" Jul 6 23:53:15.904352 kubelet[1790]: E0706 23:53:15.904315 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:16.050311 kubelet[1790]: I0706 23:53:16.050257 1790 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.6201453529999998 podStartE2EDuration="5.050244107s" podCreationTimestamp="2025-07-06 23:53:11 +0000 UTC" firstStartedPulling="2025-07-06 23:53:11.565768536 +0000 UTC m=+28.995988279" lastFinishedPulling="2025-07-06 23:53:14.99586728 +0000 UTC m=+32.426087033" observedRunningTime="2025-07-06 23:53:16.049591607 +0000 UTC m=+33.479811360" watchObservedRunningTime="2025-07-06 23:53:16.050244107 +0000 UTC m=+33.480463860" Jul 6 23:53:16.904489 kubelet[1790]: E0706 23:53:16.904417 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:17.904591 kubelet[1790]: E0706 23:53:17.904556 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:18.860935 update_engine[1459]: I20250706 23:53:18.860648 1459 update_attempter.cc:509] Updating boot flags... Jul 6 23:53:18.902364 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2852) Jul 6 23:53:18.905544 kubelet[1790]: E0706 23:53:18.905491 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:18.967312 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2856) Jul 6 23:53:19.044283 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2856) Jul 6 23:53:19.905652 kubelet[1790]: E0706 23:53:19.905584 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:20.906824 kubelet[1790]: E0706 23:53:20.906760 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:21.907985 kubelet[1790]: E0706 23:53:21.907909 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:22.876826 kubelet[1790]: E0706 23:53:22.876737 1790 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:22.908298 kubelet[1790]: E0706 23:53:22.908248 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:23.908646 kubelet[1790]: E0706 23:53:23.908580 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:24.866599 systemd[1]: Created slice kubepods-besteffort-pod3c6bbfc7_9a24_45ee_a142_2733988781f5.slice - libcontainer container kubepods-besteffort-pod3c6bbfc7_9a24_45ee_a142_2733988781f5.slice. Jul 6 23:53:24.909403 kubelet[1790]: E0706 23:53:24.909354 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:25.031456 kubelet[1790]: I0706 23:53:25.031319 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-prd26\" (UniqueName: \"kubernetes.io/projected/3c6bbfc7-9a24-45ee-a142-2733988781f5-kube-api-access-prd26\") pod \"test-pod-1\" (UID: \"3c6bbfc7-9a24-45ee-a142-2733988781f5\") " pod="default/test-pod-1" Jul 6 23:53:25.031456 kubelet[1790]: I0706 23:53:25.031360 1790 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-a483a773-9759-4a38-a853-130509837ea3\" (UniqueName: \"kubernetes.io/nfs/3c6bbfc7-9a24-45ee-a142-2733988781f5-pvc-a483a773-9759-4a38-a853-130509837ea3\") pod \"test-pod-1\" (UID: \"3c6bbfc7-9a24-45ee-a142-2733988781f5\") " pod="default/test-pod-1" Jul 6 23:53:25.161300 kernel: FS-Cache: Loaded Jul 6 23:53:25.217533 kernel: RPC: Registered named UNIX socket transport module. Jul 6 23:53:25.217703 kernel: RPC: Registered udp transport module. Jul 6 23:53:25.218711 kernel: RPC: Registered tcp transport module. Jul 6 23:53:25.219601 kernel: RPC: Registered tcp-with-tls transport module. Jul 6 23:53:25.221664 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 6 23:53:25.408698 kernel: NFS: Registering the id_resolver key type Jul 6 23:53:25.409074 kernel: Key type id_resolver registered Jul 6 23:53:25.409100 kernel: Key type id_legacy registered Jul 6 23:53:25.439460 nfsidmap[2901]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 6 23:53:25.442770 nfsidmap[2902]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 6 23:53:25.470495 containerd[1479]: time="2025-07-06T23:53:25.470372544Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:3c6bbfc7-9a24-45ee-a142-2733988781f5,Namespace:default,Attempt:0,}" Jul 6 23:53:25.492275 systemd-networkd[1389]: veth3fa870e4: Link UP Jul 6 23:53:25.494561 kernel: cni0: port 3(veth3fa870e4) entered blocking state Jul 6 23:53:25.494602 kernel: cni0: port 3(veth3fa870e4) entered disabled state Jul 6 23:53:25.497460 kernel: veth3fa870e4: entered allmulticast mode Jul 6 23:53:25.497539 kernel: veth3fa870e4: entered promiscuous mode Jul 6 23:53:25.505210 kernel: cni0: port 3(veth3fa870e4) entered blocking state Jul 6 23:53:25.505327 kernel: cni0: port 3(veth3fa870e4) entered forwarding state Jul 6 23:53:25.505247 systemd-networkd[1389]: veth3fa870e4: Gained carrier Jul 6 23:53:25.507614 containerd[1479]: 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)(0xc00001a938), "name":"cbr0", "type":"bridge"} Jul 6 23:53:25.507614 containerd[1479]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:53:25.526081 containerd[1479]: {"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-06T23:53:25.525981376Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:53:25.526081 containerd[1479]: time="2025-07-06T23:53:25.526038649Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:53:25.526081 containerd[1479]: time="2025-07-06T23:53:25.526051290Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:53:25.526241 containerd[1479]: time="2025-07-06T23:53:25.526113954Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:53:25.548422 systemd[1]: Started cri-containerd-55105cec6641be10b933039a38e91f08ffe8ad1cea58c827fa94c9ae886ae0f4.scope - libcontainer container 55105cec6641be10b933039a38e91f08ffe8ad1cea58c827fa94c9ae886ae0f4. Jul 6 23:53:25.582048 containerd[1479]: time="2025-07-06T23:53:25.582013752Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:3c6bbfc7-9a24-45ee-a142-2733988781f5,Namespace:default,Attempt:0,} returns sandbox id \"55105cec6641be10b933039a38e91f08ffe8ad1cea58c827fa94c9ae886ae0f4\"" Jul 6 23:53:25.583356 containerd[1479]: time="2025-07-06T23:53:25.583332786Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 6 23:53:25.747371 containerd[1479]: time="2025-07-06T23:53:25.747220483Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:53:25.748152 containerd[1479]: time="2025-07-06T23:53:25.748009909Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jul 6 23:53:25.750222 containerd[1479]: time="2025-07-06T23:53:25.750190722Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"73313108\" in 166.7416ms" Jul 6 23:53:25.750222 containerd[1479]: time="2025-07-06T23:53:25.750221604Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\"" Jul 6 23:53:25.752341 containerd[1479]: time="2025-07-06T23:53:25.752308393Z" level=info msg="CreateContainer within sandbox \"55105cec6641be10b933039a38e91f08ffe8ad1cea58c827fa94c9ae886ae0f4\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 6 23:53:25.761881 containerd[1479]: time="2025-07-06T23:53:25.761842014Z" level=info msg="CreateContainer within sandbox \"55105cec6641be10b933039a38e91f08ffe8ad1cea58c827fa94c9ae886ae0f4\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"02ad09abde17f1c0eb1618310bc3cdb0b19285dc5b891633f580cedeef2ea8f6\"" Jul 6 23:53:25.763394 containerd[1479]: time="2025-07-06T23:53:25.763197221Z" level=info msg="StartContainer for \"02ad09abde17f1c0eb1618310bc3cdb0b19285dc5b891633f580cedeef2ea8f6\"" Jul 6 23:53:25.794386 systemd[1]: Started cri-containerd-02ad09abde17f1c0eb1618310bc3cdb0b19285dc5b891633f580cedeef2ea8f6.scope - libcontainer container 02ad09abde17f1c0eb1618310bc3cdb0b19285dc5b891633f580cedeef2ea8f6. Jul 6 23:53:25.823330 containerd[1479]: time="2025-07-06T23:53:25.822118572Z" level=info msg="StartContainer for \"02ad09abde17f1c0eb1618310bc3cdb0b19285dc5b891633f580cedeef2ea8f6\" returns successfully" Jul 6 23:53:25.910301 kubelet[1790]: E0706 23:53:25.910221 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:26.062532 kubelet[1790]: I0706 23:53:26.062469 1790 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.894690279 podStartE2EDuration="15.062444026s" podCreationTimestamp="2025-07-06 23:53:11 +0000 UTC" firstStartedPulling="2025-07-06 23:53:25.583126994 +0000 UTC m=+43.013346737" lastFinishedPulling="2025-07-06 23:53:25.750880741 +0000 UTC m=+43.181100484" observedRunningTime="2025-07-06 23:53:26.062413814 +0000 UTC m=+43.492633567" watchObservedRunningTime="2025-07-06 23:53:26.062444026 +0000 UTC m=+43.492663769" Jul 6 23:53:26.911348 kubelet[1790]: E0706 23:53:26.911303 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:27.382577 systemd-networkd[1389]: veth3fa870e4: Gained IPv6LL Jul 6 23:53:27.912216 kubelet[1790]: E0706 23:53:27.912168 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:28.913084 kubelet[1790]: E0706 23:53:28.913018 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:29.913376 kubelet[1790]: E0706 23:53:29.913137 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:30.913552 kubelet[1790]: E0706 23:53:30.913440 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:31.913926 kubelet[1790]: E0706 23:53:31.913866 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:32.914981 kubelet[1790]: E0706 23:53:32.914935 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:53:33.915101 kubelet[1790]: E0706 23:53:33.915055 1790 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"