Aug 12 23:55:44.125088 kernel: Linux version 6.6.100-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 Tue Aug 12 21:47:31 -00 2025 Aug 12 23:55:44.125135 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 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:55:44.125154 kernel: BIOS-provided physical RAM map: Aug 12 23:55:44.125167 kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] reserved Aug 12 23:55:44.125180 kernel: BIOS-e820: [mem 0x0000000000001000-0x0000000000054fff] usable Aug 12 23:55:44.125194 kernel: BIOS-e820: [mem 0x0000000000055000-0x000000000005ffff] reserved Aug 12 23:55:44.125212 kernel: BIOS-e820: [mem 0x0000000000060000-0x0000000000097fff] usable Aug 12 23:55:44.125227 kernel: BIOS-e820: [mem 0x0000000000098000-0x000000000009ffff] reserved Aug 12 23:55:44.125246 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bd32afff] usable Aug 12 23:55:44.125260 kernel: BIOS-e820: [mem 0x00000000bd32b000-0x00000000bd332fff] ACPI data Aug 12 23:55:44.125274 kernel: BIOS-e820: [mem 0x00000000bd333000-0x00000000bf8ecfff] usable Aug 12 23:55:44.125288 kernel: BIOS-e820: [mem 0x00000000bf8ed000-0x00000000bfb6cfff] reserved Aug 12 23:55:44.125302 kernel: BIOS-e820: [mem 0x00000000bfb6d000-0x00000000bfb7efff] ACPI data Aug 12 23:55:44.125316 kernel: BIOS-e820: [mem 0x00000000bfb7f000-0x00000000bfbfefff] ACPI NVS Aug 12 23:55:44.125338 kernel: BIOS-e820: [mem 0x00000000bfbff000-0x00000000bffdffff] usable Aug 12 23:55:44.125354 kernel: BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved Aug 12 23:55:44.125369 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000021fffffff] usable Aug 12 23:55:44.125386 kernel: NX (Execute Disable) protection: active Aug 12 23:55:44.125402 kernel: APIC: Static calls initialized Aug 12 23:55:44.125418 kernel: efi: EFI v2.7 by EDK II Aug 12 23:55:44.125434 kernel: efi: TPMFinalLog=0xbfbf7000 ACPI=0xbfb7e000 ACPI 2.0=0xbfb7e014 SMBIOS=0xbf9e8000 RNG=0xbfb73018 TPMEventLog=0xbd32b018 Aug 12 23:55:44.125451 kernel: random: crng init done Aug 12 23:55:44.125467 kernel: secureboot: Secure boot disabled Aug 12 23:55:44.125483 kernel: SMBIOS 2.4 present. Aug 12 23:55:44.125502 kernel: DMI: Google Google Compute Engine/Google Compute Engine, BIOS Google 05/07/2025 Aug 12 23:55:44.125517 kernel: Hypervisor detected: KVM Aug 12 23:55:44.125533 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Aug 12 23:55:44.125549 kernel: kvm-clock: using sched offset of 13036847576 cycles Aug 12 23:55:44.125566 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Aug 12 23:55:44.126622 kernel: tsc: Detected 2299.998 MHz processor Aug 12 23:55:44.126648 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 12 23:55:44.126664 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 12 23:55:44.126680 kernel: last_pfn = 0x220000 max_arch_pfn = 0x400000000 Aug 12 23:55:44.126696 kernel: MTRR map: 3 entries (2 fixed + 1 variable; max 18), built from 8 variable MTRRs Aug 12 23:55:44.126718 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 12 23:55:44.126736 kernel: last_pfn = 0xbffe0 max_arch_pfn = 0x400000000 Aug 12 23:55:44.126752 kernel: Using GB pages for direct mapping Aug 12 23:55:44.126769 kernel: ACPI: Early table checksum verification disabled Aug 12 23:55:44.126787 kernel: ACPI: RSDP 0x00000000BFB7E014 000024 (v02 Google) Aug 12 23:55:44.126805 kernel: ACPI: XSDT 0x00000000BFB7D0E8 00005C (v01 Google GOOGFACP 00000001 01000013) Aug 12 23:55:44.126830 kernel: ACPI: FACP 0x00000000BFB78000 0000F4 (v02 Google GOOGFACP 00000001 GOOG 00000001) Aug 12 23:55:44.126852 kernel: ACPI: DSDT 0x00000000BFB79000 001A64 (v01 Google GOOGDSDT 00000001 GOOG 00000001) Aug 12 23:55:44.126870 kernel: ACPI: FACS 0x00000000BFBF2000 000040 Aug 12 23:55:44.126888 kernel: ACPI: SSDT 0x00000000BFB7C000 000316 (v02 GOOGLE Tpm2Tabl 00001000 INTL 20241212) Aug 12 23:55:44.126907 kernel: ACPI: TPM2 0x00000000BFB7B000 000034 (v04 GOOGLE 00000001 GOOG 00000001) Aug 12 23:55:44.126925 kernel: ACPI: SRAT 0x00000000BFB77000 0000C8 (v03 Google GOOGSRAT 00000001 GOOG 00000001) Aug 12 23:55:44.126944 kernel: ACPI: APIC 0x00000000BFB76000 000076 (v05 Google GOOGAPIC 00000001 GOOG 00000001) Aug 12 23:55:44.126963 kernel: ACPI: SSDT 0x00000000BFB75000 000980 (v01 Google GOOGSSDT 00000001 GOOG 00000001) Aug 12 23:55:44.126985 kernel: ACPI: WAET 0x00000000BFB74000 000028 (v01 Google GOOGWAET 00000001 GOOG 00000001) Aug 12 23:55:44.127003 kernel: ACPI: Reserving FACP table memory at [mem 0xbfb78000-0xbfb780f3] Aug 12 23:55:44.127021 kernel: ACPI: Reserving DSDT table memory at [mem 0xbfb79000-0xbfb7aa63] Aug 12 23:55:44.127041 kernel: ACPI: Reserving FACS table memory at [mem 0xbfbf2000-0xbfbf203f] Aug 12 23:55:44.127059 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb7c000-0xbfb7c315] Aug 12 23:55:44.127111 kernel: ACPI: Reserving TPM2 table memory at [mem 0xbfb7b000-0xbfb7b033] Aug 12 23:55:44.127158 kernel: ACPI: Reserving SRAT table memory at [mem 0xbfb77000-0xbfb770c7] Aug 12 23:55:44.127176 kernel: ACPI: Reserving APIC table memory at [mem 0xbfb76000-0xbfb76075] Aug 12 23:55:44.127194 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb75000-0xbfb7597f] Aug 12 23:55:44.127216 kernel: ACPI: Reserving WAET table memory at [mem 0xbfb74000-0xbfb74027] Aug 12 23:55:44.127234 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Aug 12 23:55:44.127252 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Aug 12 23:55:44.127269 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Aug 12 23:55:44.127287 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff] Aug 12 23:55:44.127305 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x21fffffff] Aug 12 23:55:44.127324 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00000000-0xbfffffff] Aug 12 23:55:44.127343 kernel: NUMA: Node 0 [mem 0x00000000-0xbfffffff] + [mem 0x100000000-0x21fffffff] -> [mem 0x00000000-0x21fffffff] Aug 12 23:55:44.127361 kernel: NODE_DATA(0) allocated [mem 0x21fffa000-0x21fffffff] Aug 12 23:55:44.127383 kernel: Zone ranges: Aug 12 23:55:44.127401 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 12 23:55:44.127419 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Aug 12 23:55:44.127436 kernel: Normal [mem 0x0000000100000000-0x000000021fffffff] Aug 12 23:55:44.127454 kernel: Movable zone start for each node Aug 12 23:55:44.127472 kernel: Early memory node ranges Aug 12 23:55:44.127490 kernel: node 0: [mem 0x0000000000001000-0x0000000000054fff] Aug 12 23:55:44.127508 kernel: node 0: [mem 0x0000000000060000-0x0000000000097fff] Aug 12 23:55:44.127525 kernel: node 0: [mem 0x0000000000100000-0x00000000bd32afff] Aug 12 23:55:44.127547 kernel: node 0: [mem 0x00000000bd333000-0x00000000bf8ecfff] Aug 12 23:55:44.127565 kernel: node 0: [mem 0x00000000bfbff000-0x00000000bffdffff] Aug 12 23:55:44.127628 kernel: node 0: [mem 0x0000000100000000-0x000000021fffffff] Aug 12 23:55:44.127646 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000021fffffff] Aug 12 23:55:44.127664 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 12 23:55:44.127681 kernel: On node 0, zone DMA: 11 pages in unavailable ranges Aug 12 23:55:44.127699 kernel: On node 0, zone DMA: 104 pages in unavailable ranges Aug 12 23:55:44.127717 kernel: On node 0, zone DMA32: 8 pages in unavailable ranges Aug 12 23:55:44.127735 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Aug 12 23:55:44.127758 kernel: On node 0, zone Normal: 32 pages in unavailable ranges Aug 12 23:55:44.127776 kernel: ACPI: PM-Timer IO Port: 0xb008 Aug 12 23:55:44.127793 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Aug 12 23:55:44.127811 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Aug 12 23:55:44.127829 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Aug 12 23:55:44.127846 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 12 23:55:44.127865 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Aug 12 23:55:44.127883 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Aug 12 23:55:44.127900 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 12 23:55:44.127922 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Aug 12 23:55:44.127940 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices Aug 12 23:55:44.127958 kernel: Booting paravirtualized kernel on KVM Aug 12 23:55:44.127974 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 12 23:55:44.127990 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Aug 12 23:55:44.128006 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u1048576 Aug 12 23:55:44.128022 kernel: pcpu-alloc: s197096 r8192 d32280 u1048576 alloc=1*2097152 Aug 12 23:55:44.128037 kernel: pcpu-alloc: [0] 0 1 Aug 12 23:55:44.128054 kernel: kvm-guest: PV spinlocks enabled Aug 12 23:55:44.128074 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Aug 12 23:55:44.128093 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 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:55:44.128111 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 12 23:55:44.128127 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Aug 12 23:55:44.128144 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 12 23:55:44.128160 kernel: Fallback order for Node 0: 0 Aug 12 23:55:44.128177 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1932272 Aug 12 23:55:44.128194 kernel: Policy zone: Normal Aug 12 23:55:44.128214 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 12 23:55:44.128230 kernel: software IO TLB: area num 2. Aug 12 23:55:44.128247 kernel: Memory: 7511336K/7860552K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43504K init, 1572K bss, 348960K reserved, 0K cma-reserved) Aug 12 23:55:44.128264 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Aug 12 23:55:44.128281 kernel: Kernel/User page tables isolation: enabled Aug 12 23:55:44.128297 kernel: ftrace: allocating 37942 entries in 149 pages Aug 12 23:55:44.128314 kernel: ftrace: allocated 149 pages with 4 groups Aug 12 23:55:44.128331 kernel: Dynamic Preempt: voluntary Aug 12 23:55:44.128366 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 12 23:55:44.128385 kernel: rcu: RCU event tracing is enabled. Aug 12 23:55:44.128404 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Aug 12 23:55:44.128422 kernel: Trampoline variant of Tasks RCU enabled. Aug 12 23:55:44.128444 kernel: Rude variant of Tasks RCU enabled. Aug 12 23:55:44.128462 kernel: Tracing variant of Tasks RCU enabled. Aug 12 23:55:44.128481 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 12 23:55:44.128499 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Aug 12 23:55:44.128517 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Aug 12 23:55:44.128540 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 12 23:55:44.128558 kernel: Console: colour dummy device 80x25 Aug 12 23:55:44.128576 kernel: printk: console [ttyS0] enabled Aug 12 23:55:44.130631 kernel: ACPI: Core revision 20230628 Aug 12 23:55:44.130653 kernel: APIC: Switch to symmetric I/O mode setup Aug 12 23:55:44.130673 kernel: x2apic enabled Aug 12 23:55:44.130692 kernel: APIC: Switched APIC routing to: physical x2apic Aug 12 23:55:44.130712 kernel: ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 Aug 12 23:55:44.130731 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns Aug 12 23:55:44.130757 kernel: Calibrating delay loop (skipped) preset value.. 4599.99 BogoMIPS (lpj=2299998) Aug 12 23:55:44.130777 kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 Aug 12 23:55:44.130796 kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 Aug 12 23:55:44.130816 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 12 23:55:44.130835 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on vm exit Aug 12 23:55:44.130854 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on syscall Aug 12 23:55:44.130872 kernel: Spectre V2 : Mitigation: IBRS Aug 12 23:55:44.130891 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Aug 12 23:55:44.130910 kernel: RETBleed: Mitigation: IBRS Aug 12 23:55:44.130934 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Aug 12 23:55:44.130952 kernel: Spectre V2 : User space: Mitigation: STIBP via prctl Aug 12 23:55:44.130969 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Aug 12 23:55:44.130987 kernel: MDS: Mitigation: Clear CPU buffers Aug 12 23:55:44.131004 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Aug 12 23:55:44.131021 kernel: ITS: Mitigation: Aligned branch/return thunks Aug 12 23:55:44.131039 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 12 23:55:44.131057 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 12 23:55:44.131075 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 12 23:55:44.131096 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 12 23:55:44.131115 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 12 23:55:44.131133 kernel: Freeing SMP alternatives memory: 32K Aug 12 23:55:44.131151 kernel: pid_max: default: 32768 minimum: 301 Aug 12 23:55:44.131169 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Aug 12 23:55:44.131187 kernel: landlock: Up and running. Aug 12 23:55:44.131205 kernel: SELinux: Initializing. Aug 12 23:55:44.131223 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Aug 12 23:55:44.131241 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Aug 12 23:55:44.131264 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU @ 2.30GHz (family: 0x6, model: 0x3f, stepping: 0x0) Aug 12 23:55:44.131283 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Aug 12 23:55:44.131301 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Aug 12 23:55:44.131320 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Aug 12 23:55:44.131338 kernel: Performance Events: unsupported p6 CPU model 63 no PMU driver, software events only. Aug 12 23:55:44.131356 kernel: signal: max sigframe size: 1776 Aug 12 23:55:44.131375 kernel: rcu: Hierarchical SRCU implementation. Aug 12 23:55:44.131394 kernel: rcu: Max phase no-delay instances is 400. Aug 12 23:55:44.131417 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 12 23:55:44.131435 kernel: smp: Bringing up secondary CPUs ... Aug 12 23:55:44.131453 kernel: smpboot: x86: Booting SMP configuration: Aug 12 23:55:44.131471 kernel: .... node #0, CPUs: #1 Aug 12 23:55:44.131491 kernel: Transient Scheduler Attacks: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. Aug 12 23:55:44.131511 kernel: Transient Scheduler Attacks: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details. Aug 12 23:55:44.131530 kernel: smp: Brought up 1 node, 2 CPUs Aug 12 23:55:44.131548 kernel: smpboot: Max logical packages: 1 Aug 12 23:55:44.131567 kernel: smpboot: Total of 2 processors activated (9199.99 BogoMIPS) Aug 12 23:55:44.133267 kernel: devtmpfs: initialized Aug 12 23:55:44.133292 kernel: x86/mm: Memory block size: 128MB Aug 12 23:55:44.133312 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xbfb7f000-0xbfbfefff] (524288 bytes) Aug 12 23:55:44.133330 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 12 23:55:44.133346 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Aug 12 23:55:44.133362 kernel: pinctrl core: initialized pinctrl subsystem Aug 12 23:55:44.133378 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 12 23:55:44.133394 kernel: audit: initializing netlink subsys (disabled) Aug 12 23:55:44.133411 kernel: audit: type=2000 audit(1755042943.172:1): state=initialized audit_enabled=0 res=1 Aug 12 23:55:44.133437 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 12 23:55:44.133454 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 12 23:55:44.133472 kernel: cpuidle: using governor menu Aug 12 23:55:44.133490 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 12 23:55:44.133508 kernel: dca service started, version 1.12.1 Aug 12 23:55:44.133527 kernel: PCI: Using configuration type 1 for base access Aug 12 23:55:44.133545 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 12 23:55:44.133563 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 12 23:55:44.133611 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 12 23:55:44.133636 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 12 23:55:44.133654 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 12 23:55:44.133672 kernel: ACPI: Added _OSI(Module Device) Aug 12 23:55:44.133690 kernel: ACPI: Added _OSI(Processor Device) Aug 12 23:55:44.133708 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 12 23:55:44.133726 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded Aug 12 23:55:44.133744 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Aug 12 23:55:44.133762 kernel: ACPI: Interpreter enabled Aug 12 23:55:44.133780 kernel: ACPI: PM: (supports S0 S3 S5) Aug 12 23:55:44.133803 kernel: ACPI: Using IOAPIC for interrupt routing Aug 12 23:55:44.133822 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 12 23:55:44.133841 kernel: PCI: Ignoring E820 reservations for host bridge windows Aug 12 23:55:44.133858 kernel: ACPI: Enabled 16 GPEs in block 00 to 0F Aug 12 23:55:44.133877 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 12 23:55:44.134171 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Aug 12 23:55:44.134383 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Aug 12 23:55:44.134584 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Aug 12 23:55:44.135536 kernel: PCI host bridge to bus 0000:00 Aug 12 23:55:44.135806 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Aug 12 23:55:44.135989 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Aug 12 23:55:44.136163 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Aug 12 23:55:44.136338 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfefff window] Aug 12 23:55:44.136504 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 12 23:55:44.136767 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Aug 12 23:55:44.136966 kernel: pci 0000:00:01.0: [8086:7110] type 00 class 0x060100 Aug 12 23:55:44.137158 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Aug 12 23:55:44.137340 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI Aug 12 23:55:44.137614 kernel: pci 0000:00:03.0: [1af4:1004] type 00 class 0x000000 Aug 12 23:55:44.137839 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc040-0xc07f] Aug 12 23:55:44.138078 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc0001000-0xc000107f] Aug 12 23:55:44.138292 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Aug 12 23:55:44.138484 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc03f] Aug 12 23:55:44.138742 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc0000000-0xc000007f] Aug 12 23:55:44.138942 kernel: pci 0000:00:05.0: [1af4:1005] type 00 class 0x00ff00 Aug 12 23:55:44.139131 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc09f] Aug 12 23:55:44.139337 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xc0002000-0xc000203f] Aug 12 23:55:44.139371 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Aug 12 23:55:44.139391 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Aug 12 23:55:44.139410 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Aug 12 23:55:44.139430 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Aug 12 23:55:44.139448 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Aug 12 23:55:44.139467 kernel: iommu: Default domain type: Translated Aug 12 23:55:44.139487 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 12 23:55:44.139505 kernel: efivars: Registered efivars operations Aug 12 23:55:44.139523 kernel: PCI: Using ACPI for IRQ routing Aug 12 23:55:44.139546 kernel: PCI: pci_cache_line_size set to 64 bytes Aug 12 23:55:44.139565 kernel: e820: reserve RAM buffer [mem 0x00055000-0x0005ffff] Aug 12 23:55:44.139656 kernel: e820: reserve RAM buffer [mem 0x00098000-0x0009ffff] Aug 12 23:55:44.139676 kernel: e820: reserve RAM buffer [mem 0xbd32b000-0xbfffffff] Aug 12 23:55:44.139694 kernel: e820: reserve RAM buffer [mem 0xbf8ed000-0xbfffffff] Aug 12 23:55:44.139712 kernel: e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff] Aug 12 23:55:44.139731 kernel: vgaarb: loaded Aug 12 23:55:44.139749 kernel: clocksource: Switched to clocksource kvm-clock Aug 12 23:55:44.139767 kernel: VFS: Disk quotas dquot_6.6.0 Aug 12 23:55:44.139791 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 12 23:55:44.139809 kernel: pnp: PnP ACPI init Aug 12 23:55:44.139827 kernel: pnp: PnP ACPI: found 7 devices Aug 12 23:55:44.139845 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 12 23:55:44.139864 kernel: NET: Registered PF_INET protocol family Aug 12 23:55:44.139883 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 12 23:55:44.139902 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Aug 12 23:55:44.139920 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 12 23:55:44.139939 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 12 23:55:44.139961 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 12 23:55:44.139980 kernel: TCP: Hash tables configured (established 65536 bind 65536) Aug 12 23:55:44.139998 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Aug 12 23:55:44.140016 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Aug 12 23:55:44.140035 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 12 23:55:44.140053 kernel: NET: Registered PF_XDP protocol family Aug 12 23:55:44.140241 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Aug 12 23:55:44.140409 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Aug 12 23:55:44.140612 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Aug 12 23:55:44.141411 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfefff window] Aug 12 23:55:44.142108 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Aug 12 23:55:44.142142 kernel: PCI: CLS 0 bytes, default 64 Aug 12 23:55:44.142237 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Aug 12 23:55:44.142258 kernel: software IO TLB: mapped [mem 0x00000000b7f7f000-0x00000000bbf7f000] (64MB) Aug 12 23:55:44.142277 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Aug 12 23:55:44.142297 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns Aug 12 23:55:44.142324 kernel: clocksource: Switched to clocksource tsc Aug 12 23:55:44.142343 kernel: Initialise system trusted keyrings Aug 12 23:55:44.142362 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 Aug 12 23:55:44.142381 kernel: Key type asymmetric registered Aug 12 23:55:44.142400 kernel: Asymmetric key parser 'x509' registered Aug 12 23:55:44.142419 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Aug 12 23:55:44.142438 kernel: io scheduler mq-deadline registered Aug 12 23:55:44.142457 kernel: io scheduler kyber registered Aug 12 23:55:44.142476 kernel: io scheduler bfq registered Aug 12 23:55:44.142499 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 12 23:55:44.142520 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Aug 12 23:55:44.143427 kernel: virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver Aug 12 23:55:44.143461 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 10 Aug 12 23:55:44.143693 kernel: virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver Aug 12 23:55:44.143721 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Aug 12 23:55:44.143926 kernel: virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver Aug 12 23:55:44.143952 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 12 23:55:44.143979 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 12 23:55:44.143998 kernel: 00:04: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Aug 12 23:55:44.144018 kernel: 00:05: ttyS2 at I/O 0x3e8 (irq = 6, base_baud = 115200) is a 16550A Aug 12 23:55:44.144037 kernel: 00:06: ttyS3 at I/O 0x2e8 (irq = 7, base_baud = 115200) is a 16550A Aug 12 23:55:44.144238 kernel: tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x9009, rev-id 0) Aug 12 23:55:44.144264 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Aug 12 23:55:44.144284 kernel: i8042: Warning: Keylock active Aug 12 23:55:44.144303 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Aug 12 23:55:44.144328 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Aug 12 23:55:44.144522 kernel: rtc_cmos 00:00: RTC can wake from S4 Aug 12 23:55:44.144746 kernel: rtc_cmos 00:00: registered as rtc0 Aug 12 23:55:44.144946 kernel: rtc_cmos 00:00: setting system clock to 2025-08-12T23:55:43 UTC (1755042943) Aug 12 23:55:44.145124 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram Aug 12 23:55:44.145148 kernel: intel_pstate: CPU model not supported Aug 12 23:55:44.145168 kernel: pstore: Using crash dump compression: deflate Aug 12 23:55:44.145187 kernel: pstore: Registered efi_pstore as persistent store backend Aug 12 23:55:44.145213 kernel: NET: Registered PF_INET6 protocol family Aug 12 23:55:44.145232 kernel: Segment Routing with IPv6 Aug 12 23:55:44.145251 kernel: In-situ OAM (IOAM) with IPv6 Aug 12 23:55:44.145271 kernel: NET: Registered PF_PACKET protocol family Aug 12 23:55:44.145290 kernel: Key type dns_resolver registered Aug 12 23:55:44.145308 kernel: IPI shorthand broadcast: enabled Aug 12 23:55:44.145327 kernel: sched_clock: Marking stable (886004964, 146023776)->(1082523544, -50494804) Aug 12 23:55:44.145346 kernel: registered taskstats version 1 Aug 12 23:55:44.145365 kernel: Loading compiled-in X.509 certificates Aug 12 23:55:44.145389 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.100-flatcar: dfd2b306eb54324ea79eea0261f8d493924aeeeb' Aug 12 23:55:44.145408 kernel: Key type .fscrypt registered Aug 12 23:55:44.145426 kernel: Key type fscrypt-provisioning registered Aug 12 23:55:44.145446 kernel: ima: Allocated hash algorithm: sha1 Aug 12 23:55:44.145465 kernel: ima: No architecture policies found Aug 12 23:55:44.145484 kernel: clk: Disabling unused clocks Aug 12 23:55:44.145503 kernel: Freeing unused kernel image (initmem) memory: 43504K Aug 12 23:55:44.145523 kernel: Write protecting the kernel read-only data: 38912k Aug 12 23:55:44.145543 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Aug 12 23:55:44.145567 kernel: Run /init as init process Aug 12 23:55:44.147627 kernel: with arguments: Aug 12 23:55:44.147656 kernel: /init Aug 12 23:55:44.147675 kernel: with environment: Aug 12 23:55:44.147694 kernel: HOME=/ Aug 12 23:55:44.147712 kernel: TERM=linux Aug 12 23:55:44.147731 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 12 23:55:44.147750 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Aug 12 23:55:44.147772 systemd[1]: Successfully made /usr/ read-only. Aug 12 23:55:44.147803 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) Aug 12 23:55:44.147825 systemd[1]: Detected virtualization google. Aug 12 23:55:44.147852 systemd[1]: Detected architecture x86-64. Aug 12 23:55:44.147871 systemd[1]: Running in initrd. Aug 12 23:55:44.147890 systemd[1]: No hostname configured, using default hostname. Aug 12 23:55:44.147911 systemd[1]: Hostname set to . Aug 12 23:55:44.147932 systemd[1]: Initializing machine ID from random generator. Aug 12 23:55:44.147957 systemd[1]: Queued start job for default target initrd.target. Aug 12 23:55:44.147976 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:55:44.147996 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:55:44.148017 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 12 23:55:44.148037 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 12 23:55:44.148057 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 12 23:55:44.148078 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 12 23:55:44.148105 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 12 23:55:44.148146 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 12 23:55:44.148170 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:55:44.148190 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:55:44.148209 systemd[1]: Reached target paths.target - Path Units. Aug 12 23:55:44.148227 systemd[1]: Reached target slices.target - Slice Units. Aug 12 23:55:44.148252 systemd[1]: Reached target swap.target - Swaps. Aug 12 23:55:44.148271 systemd[1]: Reached target timers.target - Timer Units. Aug 12 23:55:44.148291 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 12 23:55:44.148312 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 12 23:55:44.148332 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 12 23:55:44.148352 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 12 23:55:44.148372 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:55:44.148391 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 12 23:55:44.148416 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:55:44.148435 systemd[1]: Reached target sockets.target - Socket Units. Aug 12 23:55:44.148454 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 12 23:55:44.148475 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 12 23:55:44.148495 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 12 23:55:44.148516 systemd[1]: Starting systemd-fsck-usr.service... Aug 12 23:55:44.148537 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 12 23:55:44.148559 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 12 23:55:44.150289 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:55:44.150335 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 12 23:55:44.150358 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:55:44.150381 systemd[1]: Finished systemd-fsck-usr.service. Aug 12 23:55:44.150453 systemd-journald[184]: Collecting audit messages is disabled. Aug 12 23:55:44.150507 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 12 23:55:44.150529 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:55:44.150550 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:55:44.150571 systemd-journald[184]: Journal started Aug 12 23:55:44.150633 systemd-journald[184]: Runtime Journal (/run/log/journal/1fcb8cbec3e849a186398b0f2fd4dc0a) is 8M, max 148.6M, 140.6M free. Aug 12 23:55:44.135518 systemd-modules-load[185]: Inserted module 'overlay' Aug 12 23:55:44.163614 systemd[1]: Started systemd-journald.service - Journal Service. Aug 12 23:55:44.172273 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 12 23:55:44.182826 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 12 23:55:44.185692 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 12 23:55:44.188808 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 12 23:55:44.197325 kernel: Bridge firewalling registered Aug 12 23:55:44.196112 systemd-modules-load[185]: Inserted module 'br_netfilter' Aug 12 23:55:44.202084 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 12 23:55:44.206821 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 12 23:55:44.213867 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:55:44.228080 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 12 23:55:44.236646 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:55:44.241708 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:55:44.251390 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:55:44.267574 dracut-cmdline[212]: dracut-dracut-053 Aug 12 23:55:44.271884 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 12 23:55:44.282770 dracut-cmdline[212]: 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 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:55:44.341970 systemd-resolved[225]: Positive Trust Anchors: Aug 12 23:55:44.341995 systemd-resolved[225]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 12 23:55:44.342066 systemd-resolved[225]: 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 Aug 12 23:55:44.349447 systemd-resolved[225]: Defaulting to hostname 'linux'. Aug 12 23:55:44.352467 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 12 23:55:44.369885 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:55:44.389629 kernel: SCSI subsystem initialized Aug 12 23:55:44.402627 kernel: Loading iSCSI transport class v2.0-870. Aug 12 23:55:44.415625 kernel: iscsi: registered transport (tcp) Aug 12 23:55:44.440714 kernel: iscsi: registered transport (qla4xxx) Aug 12 23:55:44.440824 kernel: QLogic iSCSI HBA Driver Aug 12 23:55:44.494259 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 12 23:55:44.501018 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 12 23:55:44.542627 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 12 23:55:44.542718 kernel: device-mapper: uevent: version 1.0.3 Aug 12 23:55:44.542745 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 12 23:55:44.588629 kernel: raid6: avx2x4 gen() 18051 MB/s Aug 12 23:55:44.605633 kernel: raid6: avx2x2 gen() 17750 MB/s Aug 12 23:55:44.623135 kernel: raid6: avx2x1 gen() 13892 MB/s Aug 12 23:55:44.623219 kernel: raid6: using algorithm avx2x4 gen() 18051 MB/s Aug 12 23:55:44.641064 kernel: raid6: .... xor() 7545 MB/s, rmw enabled Aug 12 23:55:44.641124 kernel: raid6: using avx2x2 recovery algorithm Aug 12 23:55:44.664632 kernel: xor: automatically using best checksumming function avx Aug 12 23:55:44.830631 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 12 23:55:44.844071 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 12 23:55:44.851809 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:55:44.885305 systemd-udevd[401]: Using default interface naming scheme 'v255'. Aug 12 23:55:44.893828 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:55:44.900931 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 12 23:55:44.935694 dracut-pre-trigger[406]: rd.md=0: removing MD RAID activation Aug 12 23:55:44.973839 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 12 23:55:44.983882 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 12 23:55:45.084254 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:55:45.102862 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 12 23:55:45.154803 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 12 23:55:45.179170 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 12 23:55:45.219301 kernel: scsi host0: Virtio SCSI HBA Aug 12 23:55:45.219820 kernel: scsi 0:0:1:0: Direct-Access Google PersistentDisk 1 PQ: 0 ANSI: 6 Aug 12 23:55:45.219881 kernel: cryptd: max_cpu_qlen set to 1000 Aug 12 23:55:45.197914 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:55:45.264756 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 12 23:55:45.284831 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 12 23:55:45.301779 kernel: AVX2 version of gcm_enc/dec engaged. Aug 12 23:55:45.301820 kernel: AES CTR mode by8 optimization enabled Aug 12 23:55:45.326798 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 12 23:55:45.332862 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:55:45.359975 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:55:45.382648 kernel: sd 0:0:1:0: [sda] 25165824 512-byte logical blocks: (12.9 GB/12.0 GiB) Aug 12 23:55:45.382991 kernel: sd 0:0:1:0: [sda] 4096-byte physical blocks Aug 12 23:55:45.383221 kernel: sd 0:0:1:0: [sda] Write Protect is off Aug 12 23:55:45.376768 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:55:45.461769 kernel: sd 0:0:1:0: [sda] Mode Sense: 1f 00 00 08 Aug 12 23:55:45.462271 kernel: sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Aug 12 23:55:45.462566 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 12 23:55:45.462615 kernel: GPT:17805311 != 25165823 Aug 12 23:55:45.462651 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 12 23:55:45.462678 kernel: GPT:17805311 != 25165823 Aug 12 23:55:45.462712 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 12 23:55:45.462743 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:55:45.462767 kernel: sd 0:0:1:0: [sda] Attached SCSI disk Aug 12 23:55:45.377046 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:55:45.452334 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:55:45.479094 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:55:45.511396 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (457) Aug 12 23:55:45.527249 kernel: BTRFS: device fsid 88a9bed3-d26b-40c9-82ba-dbb7d44acae7 devid 1 transid 45 /dev/sda3 scanned by (udev-worker) (463) Aug 12 23:55:45.535650 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 12 23:55:45.559124 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:55:45.602986 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - PersistentDisk EFI-SYSTEM. Aug 12 23:55:45.625165 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. Aug 12 23:55:45.638149 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - PersistentDisk ROOT. Aug 12 23:55:45.656368 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - PersistentDisk USR-A. Aug 12 23:55:45.675891 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - PersistentDisk USR-A. Aug 12 23:55:45.697891 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 12 23:55:45.730388 disk-uuid[541]: Primary Header is updated. Aug 12 23:55:45.730388 disk-uuid[541]: Secondary Entries is updated. Aug 12 23:55:45.730388 disk-uuid[541]: Secondary Header is updated. Aug 12 23:55:45.769762 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:55:45.738879 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:55:45.787814 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:55:45.824319 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:55:46.792263 disk-uuid[542]: The operation has completed successfully. Aug 12 23:55:46.800744 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:55:46.867771 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 12 23:55:46.867940 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 12 23:55:46.929822 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 12 23:55:46.960093 sh[566]: Success Aug 12 23:55:46.985616 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Aug 12 23:55:47.076399 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 12 23:55:47.101750 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 12 23:55:47.112116 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 12 23:55:47.169904 kernel: BTRFS info (device dm-0): first mount of filesystem 88a9bed3-d26b-40c9-82ba-dbb7d44acae7 Aug 12 23:55:47.170001 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:55:47.170044 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 12 23:55:47.179693 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 12 23:55:47.186529 kernel: BTRFS info (device dm-0): using free space tree Aug 12 23:55:47.216619 kernel: BTRFS info (device dm-0): enabling ssd optimizations Aug 12 23:55:47.221699 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 12 23:55:47.222687 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 12 23:55:47.229785 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 12 23:55:47.295808 kernel: BTRFS info (device sda6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:55:47.295851 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:55:47.295877 kernel: BTRFS info (device sda6): using free space tree Aug 12 23:55:47.292898 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 12 23:55:47.341761 kernel: BTRFS info (device sda6): enabling ssd optimizations Aug 12 23:55:47.341817 kernel: BTRFS info (device sda6): auto enabling async discard Aug 12 23:55:47.341843 kernel: BTRFS info (device sda6): last unmount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:55:47.341156 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 12 23:55:47.367919 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 12 23:55:47.444319 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 12 23:55:47.450827 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 12 23:55:47.573236 ignition[682]: Ignition 2.20.0 Aug 12 23:55:47.573254 ignition[682]: Stage: fetch-offline Aug 12 23:55:47.575665 systemd-networkd[746]: lo: Link UP Aug 12 23:55:47.573332 ignition[682]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:55:47.575674 systemd-networkd[746]: lo: Gained carrier Aug 12 23:55:47.573352 ignition[682]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Aug 12 23:55:47.575675 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 12 23:55:47.573512 ignition[682]: parsed url from cmdline: "" Aug 12 23:55:47.577776 systemd-networkd[746]: Enumeration completed Aug 12 23:55:47.573519 ignition[682]: no config URL provided Aug 12 23:55:47.578420 systemd-networkd[746]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:55:47.573528 ignition[682]: reading system config file "/usr/lib/ignition/user.ign" Aug 12 23:55:47.578427 systemd-networkd[746]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:55:47.573542 ignition[682]: no config at "/usr/lib/ignition/user.ign" Aug 12 23:55:47.579994 systemd-networkd[746]: eth0: Link UP Aug 12 23:55:47.573553 ignition[682]: failed to fetch config: resource requires networking Aug 12 23:55:47.580002 systemd-networkd[746]: eth0: Gained carrier Aug 12 23:55:47.573873 ignition[682]: Ignition finished successfully Aug 12 23:55:47.580014 systemd-networkd[746]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:55:47.661577 ignition[756]: Ignition 2.20.0 Aug 12 23:55:47.581049 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 12 23:55:47.661610 ignition[756]: Stage: fetch Aug 12 23:55:47.593696 systemd-networkd[746]: eth0: DHCPv4 address 10.128.0.55/32, gateway 10.128.0.1 acquired from 169.254.169.254 Aug 12 23:55:47.661798 ignition[756]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:55:47.603562 systemd[1]: Reached target network.target - Network. Aug 12 23:55:47.661811 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Aug 12 23:55:47.641808 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 12 23:55:47.661918 ignition[756]: parsed url from cmdline: "" Aug 12 23:55:47.670786 unknown[756]: fetched base config from "system" Aug 12 23:55:47.661925 ignition[756]: no config URL provided Aug 12 23:55:47.670799 unknown[756]: fetched base config from "system" Aug 12 23:55:47.661932 ignition[756]: reading system config file "/usr/lib/ignition/user.ign" Aug 12 23:55:47.670809 unknown[756]: fetched user config from "gcp" Aug 12 23:55:47.661942 ignition[756]: no config at "/usr/lib/ignition/user.ign" Aug 12 23:55:47.703371 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 12 23:55:47.661967 ignition[756]: GET http://169.254.169.254/computeMetadata/v1/instance/attributes/user-data: attempt #1 Aug 12 23:55:47.734836 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 12 23:55:47.666094 ignition[756]: GET result: OK Aug 12 23:55:47.781031 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 12 23:55:47.666176 ignition[756]: parsing config with SHA512: 795d1bd6b9f00efd88c069fcdf4c668f03d48844ef1b818c377274b4094e07546e2fe5351a1c1d27501f8d32026eced0904cd56597f12b10bbb8ca3dfda07eb3 Aug 12 23:55:47.802795 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 12 23:55:47.671210 ignition[756]: fetch: fetch complete Aug 12 23:55:47.848148 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 12 23:55:47.671242 ignition[756]: fetch: fetch passed Aug 12 23:55:47.866468 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 12 23:55:47.671321 ignition[756]: Ignition finished successfully Aug 12 23:55:47.886791 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 12 23:55:47.778543 ignition[762]: Ignition 2.20.0 Aug 12 23:55:47.904801 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 12 23:55:47.778553 ignition[762]: Stage: kargs Aug 12 23:55:47.919770 systemd[1]: Reached target sysinit.target - System Initialization. Aug 12 23:55:47.778825 ignition[762]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:55:47.933806 systemd[1]: Reached target basic.target - Basic System. Aug 12 23:55:47.778840 ignition[762]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Aug 12 23:55:47.955799 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 12 23:55:47.779751 ignition[762]: kargs: kargs passed Aug 12 23:55:47.779810 ignition[762]: Ignition finished successfully Aug 12 23:55:47.829878 ignition[767]: Ignition 2.20.0 Aug 12 23:55:47.829888 ignition[767]: Stage: disks Aug 12 23:55:47.830092 ignition[767]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:55:47.830105 ignition[767]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Aug 12 23:55:47.830970 ignition[767]: disks: disks passed Aug 12 23:55:47.831051 ignition[767]: Ignition finished successfully Aug 12 23:55:48.004913 systemd-fsck[776]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Aug 12 23:55:48.168574 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 12 23:55:48.201787 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 12 23:55:48.319627 kernel: EXT4-fs (sda9): mounted filesystem 27db109b-2440-48a3-909e-fd8973275523 r/w with ordered data mode. Quota mode: none. Aug 12 23:55:48.320311 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 12 23:55:48.321198 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 12 23:55:48.355900 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 12 23:55:48.371734 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 12 23:55:48.373651 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 12 23:55:48.454812 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (784) Aug 12 23:55:48.454867 kernel: BTRFS info (device sda6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:55:48.454892 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:55:48.454916 kernel: BTRFS info (device sda6): using free space tree Aug 12 23:55:48.454935 kernel: BTRFS info (device sda6): enabling ssd optimizations Aug 12 23:55:48.454949 kernel: BTRFS info (device sda6): auto enabling async discard Aug 12 23:55:48.373733 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 12 23:55:48.373775 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 12 23:55:48.404506 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 12 23:55:48.464527 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 12 23:55:48.494788 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 12 23:55:48.626195 initrd-setup-root[808]: cut: /sysroot/etc/passwd: No such file or directory Aug 12 23:55:48.636765 initrd-setup-root[815]: cut: /sysroot/etc/group: No such file or directory Aug 12 23:55:48.646729 initrd-setup-root[822]: cut: /sysroot/etc/shadow: No such file or directory Aug 12 23:55:48.656714 initrd-setup-root[829]: cut: /sysroot/etc/gshadow: No such file or directory Aug 12 23:55:48.796827 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 12 23:55:48.801733 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 12 23:55:48.828203 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 12 23:55:48.850991 kernel: BTRFS info (device sda6): last unmount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:55:48.859931 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 12 23:55:48.891011 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 12 23:55:48.899879 ignition[896]: INFO : Ignition 2.20.0 Aug 12 23:55:48.899879 ignition[896]: INFO : Stage: mount Aug 12 23:55:48.899879 ignition[896]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:55:48.899879 ignition[896]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" Aug 12 23:55:48.899879 ignition[896]: INFO : mount: mount passed Aug 12 23:55:48.899879 ignition[896]: INFO : Ignition finished successfully Aug 12 23:55:48.910130 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 12 23:55:48.931752 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 12 23:55:49.326888 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 12 23:55:49.363662 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (908) Aug 12 23:55:49.381598 kernel: BTRFS info (device sda6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:55:49.381695 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:55:49.381739 kernel: BTRFS info (device sda6): using free space tree Aug 12 23:55:49.403691 kernel: BTRFS info (device sda6): enabling ssd optimizations Aug 12 23:55:49.403783 kernel: BTRFS info (device sda6): auto enabling async discard Aug 12 23:55:49.406943 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 12 23:55:49.446699 ignition[925]: INFO : Ignition 2.20.0 Aug 12 23:55:49.454748 ignition[925]: INFO : Stage: files Aug 12 23:55:49.454748 ignition[925]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:55:49.454748 ignition[925]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" Aug 12 23:55:49.454748 ignition[925]: DEBUG : files: compiled without relabeling support, skipping Aug 12 23:55:49.454748 ignition[925]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 12 23:55:49.454748 ignition[925]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 12 23:55:49.518766 ignition[925]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 12 23:55:49.518766 ignition[925]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 12 23:55:49.518766 ignition[925]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Aug 12 23:55:49.518766 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-x86-64.raw: attempt #1 Aug 12 23:55:49.462496 unknown[925]: wrote ssh authorized keys file for user: core Aug 12 23:55:49.557789 systemd-networkd[746]: eth0: Gained IPv6LL Aug 12 23:55:49.915769 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Aug 12 23:55:50.392050 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Aug 12 23:55:50.410758 ignition[925]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 12 23:55:50.410758 ignition[925]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 12 23:55:50.410758 ignition[925]: INFO : files: files passed Aug 12 23:55:50.410758 ignition[925]: INFO : Ignition finished successfully Aug 12 23:55:50.393931 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 12 23:55:50.427824 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 12 23:55:50.471827 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 12 23:55:50.487277 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 12 23:55:50.558771 initrd-setup-root-after-ignition[953]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:55:50.558771 initrd-setup-root-after-ignition[953]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:55:50.487445 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 12 23:55:50.606780 initrd-setup-root-after-ignition[957]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:55:50.514688 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 12 23:55:50.530458 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 12 23:55:50.556816 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 12 23:55:50.642429 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 12 23:55:50.642559 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 12 23:55:50.666338 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 12 23:55:50.683881 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 12 23:55:50.695046 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 12 23:55:50.701949 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 12 23:55:50.775301 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 12 23:55:50.781846 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 12 23:55:50.836046 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:55:50.836366 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:55:50.867026 systemd[1]: Stopped target timers.target - Timer Units. Aug 12 23:55:50.884963 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 12 23:55:50.885182 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 12 23:55:50.911984 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 12 23:55:50.932905 systemd[1]: Stopped target basic.target - Basic System. Aug 12 23:55:50.950990 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 12 23:55:50.968959 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 12 23:55:50.989984 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 12 23:55:51.011016 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 12 23:55:51.031052 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 12 23:55:51.053064 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 12 23:55:51.073938 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 12 23:55:51.094104 systemd[1]: Stopped target swap.target - Swaps. Aug 12 23:55:51.111883 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 12 23:55:51.112147 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 12 23:55:51.136995 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:55:51.156932 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:55:51.177917 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 12 23:55:51.178088 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:55:51.199850 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 12 23:55:51.200091 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 12 23:55:51.229978 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 12 23:55:51.230222 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 12 23:55:51.249036 systemd[1]: ignition-files.service: Deactivated successfully. Aug 12 23:55:51.249230 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 12 23:55:51.315776 ignition[978]: INFO : Ignition 2.20.0 Aug 12 23:55:51.315776 ignition[978]: INFO : Stage: umount Aug 12 23:55:51.315776 ignition[978]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:55:51.315776 ignition[978]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" Aug 12 23:55:51.315776 ignition[978]: INFO : umount: umount passed Aug 12 23:55:51.315776 ignition[978]: INFO : Ignition finished successfully Aug 12 23:55:51.276856 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 12 23:55:51.313856 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 12 23:55:51.338727 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 12 23:55:51.338964 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:55:51.366077 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 12 23:55:51.366269 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 12 23:55:51.393741 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 12 23:55:51.395148 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 12 23:55:51.395273 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 12 23:55:51.410493 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 12 23:55:51.410632 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 12 23:55:51.432316 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 12 23:55:51.432443 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 12 23:55:51.453183 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 12 23:55:51.453260 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 12 23:55:51.471856 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 12 23:55:51.471952 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 12 23:55:51.491866 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 12 23:55:51.491952 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 12 23:55:51.511877 systemd[1]: Stopped target network.target - Network. Aug 12 23:55:51.528767 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 12 23:55:51.528901 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 12 23:55:51.547856 systemd[1]: Stopped target paths.target - Path Units. Aug 12 23:55:51.563737 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 12 23:55:51.567681 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:55:51.582738 systemd[1]: Stopped target slices.target - Slice Units. Aug 12 23:55:51.597740 systemd[1]: Stopped target sockets.target - Socket Units. Aug 12 23:55:51.612834 systemd[1]: iscsid.socket: Deactivated successfully. Aug 12 23:55:51.612920 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 12 23:55:51.633887 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 12 23:55:51.633969 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 12 23:55:51.651807 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 12 23:55:51.651914 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 12 23:55:51.669866 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 12 23:55:51.669956 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 12 23:55:51.687875 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 12 23:55:51.687985 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 12 23:55:51.706023 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 12 23:55:51.723915 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 12 23:55:51.742312 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 12 23:55:51.742453 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 12 23:55:51.753274 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 12 23:55:51.753542 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 12 23:55:51.753708 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 12 23:55:51.779475 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 12 23:55:51.781019 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 12 23:55:51.781099 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:55:51.794722 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 12 23:55:51.804898 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 12 23:55:51.804984 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 12 23:55:51.835015 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 12 23:55:51.835090 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:55:51.853171 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 12 23:55:51.853255 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 12 23:55:52.320701 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). Aug 12 23:55:51.879961 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 12 23:55:51.880052 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:55:51.899103 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:55:51.908411 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 12 23:55:51.908501 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:55:51.913061 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 12 23:55:51.913301 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:55:51.936139 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 12 23:55:51.936283 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 12 23:55:51.956822 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 12 23:55:51.956888 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:55:51.974799 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 12 23:55:51.974919 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 12 23:55:52.002984 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 12 23:55:52.003087 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 12 23:55:52.032786 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 12 23:55:52.032918 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:55:52.069822 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 12 23:55:52.091730 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 12 23:55:52.091860 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:55:52.113013 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 12 23:55:52.113087 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 12 23:55:52.134823 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 12 23:55:52.134915 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:55:52.153844 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:55:52.153972 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:55:52.176192 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Aug 12 23:55:52.176304 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:55:52.176904 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 12 23:55:52.177029 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 12 23:55:52.196248 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 12 23:55:52.196368 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 12 23:55:52.216055 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 12 23:55:52.239863 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 12 23:55:52.294118 systemd[1]: Switching root. Aug 12 23:55:52.680750 systemd-journald[184]: Journal stopped Aug 12 23:55:55.129497 kernel: SELinux: policy capability network_peer_controls=1 Aug 12 23:55:55.129565 kernel: SELinux: policy capability open_perms=1 Aug 12 23:55:55.129604 kernel: SELinux: policy capability extended_socket_class=1 Aug 12 23:55:55.129622 kernel: SELinux: policy capability always_check_network=0 Aug 12 23:55:55.129641 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 12 23:55:55.129659 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 12 23:55:55.129680 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 12 23:55:55.129699 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 12 23:55:55.129723 kernel: audit: type=1403 audit(1755042952.854:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 12 23:55:55.129746 systemd[1]: Successfully loaded SELinux policy in 92.805ms. Aug 12 23:55:55.129768 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.933ms. Aug 12 23:55:55.129792 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) Aug 12 23:55:55.129812 systemd[1]: Detected virtualization google. Aug 12 23:55:55.129833 systemd[1]: Detected architecture x86-64. Aug 12 23:55:55.129862 systemd[1]: Detected first boot. Aug 12 23:55:55.129885 systemd[1]: Initializing machine ID from random generator. Aug 12 23:55:55.129907 zram_generator::config[1021]: No configuration found. Aug 12 23:55:55.129929 kernel: Guest personality initialized and is inactive Aug 12 23:55:55.129949 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Aug 12 23:55:55.129979 kernel: Initialized host personality Aug 12 23:55:55.129999 kernel: NET: Registered PF_VSOCK protocol family Aug 12 23:55:55.130020 systemd[1]: Populated /etc with preset unit settings. Aug 12 23:55:55.130042 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 12 23:55:55.130063 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 12 23:55:55.130084 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 12 23:55:55.130105 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 12 23:55:55.130127 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 12 23:55:55.130148 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 12 23:55:55.130175 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 12 23:55:55.130197 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 12 23:55:55.130219 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 12 23:55:55.130241 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 12 23:55:55.130263 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 12 23:55:55.130284 systemd[1]: Created slice user.slice - User and Session Slice. Aug 12 23:55:55.130306 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:55:55.130335 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:55:55.130357 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 12 23:55:55.130378 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 12 23:55:55.130400 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 12 23:55:55.130423 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 12 23:55:55.130451 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 12 23:55:55.130473 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:55:55.130496 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 12 23:55:55.130522 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 12 23:55:55.130544 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 12 23:55:55.130567 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 12 23:55:55.131473 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:55:55.131513 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 12 23:55:55.131537 systemd[1]: Reached target slices.target - Slice Units. Aug 12 23:55:55.131562 systemd[1]: Reached target swap.target - Swaps. Aug 12 23:55:55.131734 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 12 23:55:55.131770 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 12 23:55:55.131795 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 12 23:55:55.131819 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:55:55.131844 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 12 23:55:55.131872 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:55:55.131895 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 12 23:55:55.131918 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 12 23:55:55.131940 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 12 23:55:55.131962 systemd[1]: Mounting media.mount - External Media Directory... Aug 12 23:55:55.131985 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:55:55.132008 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 12 23:55:55.132030 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 12 23:55:55.132057 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 12 23:55:55.132081 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). Aug 12 23:55:55.132104 systemd[1]: Reached target machines.target - Containers. Aug 12 23:55:55.132127 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 12 23:55:55.132150 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 12 23:55:55.132174 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 12 23:55:55.132199 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 12 23:55:55.132223 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 12 23:55:55.132247 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 12 23:55:55.132275 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 12 23:55:55.132301 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 12 23:55:55.132323 kernel: fuse: init (API version 7.39) Aug 12 23:55:55.132347 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 12 23:55:55.132370 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 12 23:55:55.132392 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 12 23:55:55.132414 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 12 23:55:55.132440 kernel: ACPI: bus type drm_connector registered Aug 12 23:55:55.132461 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 12 23:55:55.132483 systemd[1]: Stopped systemd-fsck-usr.service. Aug 12 23:55:55.132504 kernel: loop: module loaded Aug 12 23:55:55.132526 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). Aug 12 23:55:55.132549 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 12 23:55:55.132571 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 12 23:55:55.132623 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 12 23:55:55.132698 systemd-journald[1109]: Collecting audit messages is disabled. Aug 12 23:55:55.132755 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 12 23:55:55.132779 systemd-journald[1109]: Journal started Aug 12 23:55:55.132828 systemd-journald[1109]: Runtime Journal (/run/log/journal/4e9d9d8f8f8148bdbf12f8d85b9df248) is 8M, max 148.6M, 140.6M free. Aug 12 23:55:53.843122 systemd[1]: Queued start job for default target multi-user.target. Aug 12 23:55:53.857354 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Aug 12 23:55:53.858041 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 12 23:55:55.158633 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 12 23:55:55.191832 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 12 23:55:55.191940 systemd[1]: verity-setup.service: Deactivated successfully. Aug 12 23:55:55.198454 systemd[1]: Stopped verity-setup.service. Aug 12 23:55:55.230627 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:55:55.242726 systemd[1]: Started systemd-journald.service - Journal Service. Aug 12 23:55:55.254291 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 12 23:55:55.264038 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 12 23:55:55.274045 systemd[1]: Mounted media.mount - External Media Directory. Aug 12 23:55:55.284054 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 12 23:55:55.294018 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 12 23:55:55.304043 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 12 23:55:55.315525 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 12 23:55:55.327402 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:55:55.339301 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 12 23:55:55.339666 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 12 23:55:55.351170 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 12 23:55:55.351454 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 12 23:55:55.363137 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 12 23:55:55.363434 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 12 23:55:55.374121 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 12 23:55:55.374415 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 12 23:55:55.386135 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 12 23:55:55.386431 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 12 23:55:55.397118 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 12 23:55:55.397405 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 12 23:55:55.408228 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 12 23:55:55.418247 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 12 23:55:55.430236 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 12 23:55:55.442223 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 12 23:55:55.454204 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:55:55.478880 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 12 23:55:55.493748 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 12 23:55:55.519633 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 12 23:55:55.530760 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 12 23:55:55.531032 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 12 23:55:55.542230 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 12 23:55:55.565856 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 12 23:55:55.581874 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 12 23:55:55.591995 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 12 23:55:55.607088 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 12 23:55:55.624361 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 12 23:55:55.635834 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 12 23:55:55.639292 systemd-journald[1109]: Time spent on flushing to /var/log/journal/4e9d9d8f8f8148bdbf12f8d85b9df248 is 106.751ms for 926 entries. Aug 12 23:55:55.639292 systemd-journald[1109]: System Journal (/var/log/journal/4e9d9d8f8f8148bdbf12f8d85b9df248) is 8M, max 584.8M, 576.8M free. Aug 12 23:55:55.766644 systemd-journald[1109]: Received client request to flush runtime journal. Aug 12 23:55:55.766722 kernel: loop0: detected capacity change from 0 to 138176 Aug 12 23:55:55.648428 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 12 23:55:55.660940 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 12 23:55:55.675847 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 12 23:55:55.684733 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 12 23:55:55.706859 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 12 23:55:55.726676 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 12 23:55:55.745720 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 12 23:55:55.762977 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 12 23:55:55.774428 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 12 23:55:55.789306 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 12 23:55:55.802388 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 12 23:55:55.813175 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:55:55.836457 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 12 23:55:55.853023 systemd-tmpfiles[1146]: ACLs are not supported, ignoring. Aug 12 23:55:55.855082 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 12 23:55:55.855246 systemd-tmpfiles[1146]: ACLs are not supported, ignoring. Aug 12 23:55:55.872786 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 12 23:55:55.882865 udevadm[1148]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Aug 12 23:55:55.886373 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 12 23:55:55.910910 kernel: loop1: detected capacity change from 0 to 147912 Aug 12 23:55:55.917512 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 12 23:55:55.933369 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 12 23:55:55.937691 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 12 23:55:56.007062 kernel: loop2: detected capacity change from 0 to 224512 Aug 12 23:55:56.020841 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 12 23:55:56.039811 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 12 23:55:56.111081 systemd-tmpfiles[1167]: ACLs are not supported, ignoring. Aug 12 23:55:56.111118 systemd-tmpfiles[1167]: ACLs are not supported, ignoring. Aug 12 23:55:56.134573 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:55:56.174005 kernel: loop3: detected capacity change from 0 to 52152 Aug 12 23:55:56.257640 kernel: loop4: detected capacity change from 0 to 138176 Aug 12 23:55:56.312634 kernel: loop5: detected capacity change from 0 to 147912 Aug 12 23:55:56.378638 kernel: loop6: detected capacity change from 0 to 224512 Aug 12 23:55:56.431708 kernel: loop7: detected capacity change from 0 to 52152 Aug 12 23:55:56.463849 (sd-merge)[1172]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-gce'. Aug 12 23:55:56.466852 (sd-merge)[1172]: Merged extensions into '/usr'. Aug 12 23:55:56.476061 systemd[1]: Reload requested from client PID 1145 ('systemd-sysext') (unit systemd-sysext.service)... Aug 12 23:55:56.476083 systemd[1]: Reloading... Aug 12 23:55:56.638844 zram_generator::config[1199]: No configuration found. Aug 12 23:55:56.842612 ldconfig[1140]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 12 23:55:56.911001 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. Aug 12 23:55:57.061122 systemd[1]: Reloading finished in 583 ms. Aug 12 23:55:57.079319 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 12 23:55:57.089355 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 12 23:55:57.114884 systemd[1]: Starting ensure-sysext.service... Aug 12 23:55:57.133791 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 12 23:55:57.165070 systemd[1]: Reload requested from client PID 1240 ('systemctl') (unit ensure-sysext.service)... Aug 12 23:55:57.165099 systemd[1]: Reloading... Aug 12 23:55:57.197021 systemd-tmpfiles[1241]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 12 23:55:57.197536 systemd-tmpfiles[1241]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 12 23:55:57.198881 systemd-tmpfiles[1241]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 12 23:55:57.199456 systemd-tmpfiles[1241]: ACLs are not supported, ignoring. Aug 12 23:55:57.199652 systemd-tmpfiles[1241]: ACLs are not supported, ignoring. Aug 12 23:55:57.207009 systemd-tmpfiles[1241]: Detected autofs mount point /boot during canonicalization of boot. Aug 12 23:55:57.207036 systemd-tmpfiles[1241]: Skipping /boot Aug 12 23:55:57.244834 systemd-tmpfiles[1241]: Detected autofs mount point /boot during canonicalization of boot. Aug 12 23:55:57.244857 systemd-tmpfiles[1241]: Skipping /boot Aug 12 23:55:57.271618 zram_generator::config[1267]: No configuration found. Aug 12 23:55:57.439124 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. Aug 12 23:55:57.531505 systemd[1]: Reloading finished in 365 ms. Aug 12 23:55:57.547133 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 12 23:55:57.579672 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:55:57.604013 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 12 23:55:57.622387 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 12 23:55:57.643750 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 12 23:55:57.661692 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 12 23:55:57.680016 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:55:57.700071 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 12 23:55:57.721372 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:55:57.722644 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 12 23:55:57.737383 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 12 23:55:57.755350 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 12 23:55:57.759326 augenrules[1339]: No rules Aug 12 23:55:57.773775 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 12 23:55:57.782873 systemd-udevd[1329]: Using default interface naming scheme 'v255'. Aug 12 23:55:57.783885 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 12 23:55:57.784127 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). Aug 12 23:55:57.793744 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 12 23:55:57.803787 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:55:57.810265 systemd[1]: audit-rules.service: Deactivated successfully. Aug 12 23:55:57.810680 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 12 23:55:57.822055 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 12 23:55:57.834564 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 12 23:55:57.834887 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 12 23:55:57.846927 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 12 23:55:57.859251 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:55:57.871896 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 12 23:55:57.872412 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 12 23:55:57.884643 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 12 23:55:57.884955 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 12 23:55:57.895249 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 12 23:55:57.929508 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 12 23:55:57.980082 systemd[1]: Finished ensure-sysext.service. Aug 12 23:55:57.998632 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:55:58.006913 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 12 23:55:58.016009 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 12 23:55:58.022845 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 12 23:55:58.044850 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 12 23:55:58.066865 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 12 23:55:58.084846 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 12 23:55:58.103452 systemd[1]: Starting setup-oem.service - Setup OEM... Aug 12 23:55:58.112907 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 12 23:55:58.113733 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). Aug 12 23:55:58.123861 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 12 23:55:58.133815 systemd[1]: Reached target time-set.target - System Time Set. Aug 12 23:55:58.157895 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 12 23:55:58.167784 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). Aug 12 23:55:58.167840 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:55:58.169254 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 12 23:55:58.171288 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 12 23:55:58.184420 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 12 23:55:58.184893 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 12 23:55:58.191514 augenrules[1380]: /sbin/augenrules: No change Aug 12 23:55:58.196279 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 12 23:55:58.197944 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 12 23:55:58.209443 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 12 23:55:58.210664 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 12 23:55:58.227123 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (1368) Aug 12 23:55:58.229696 augenrules[1408]: No rules Aug 12 23:55:58.231303 systemd[1]: audit-rules.service: Deactivated successfully. Aug 12 23:55:58.231686 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 12 23:55:58.260533 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 12 23:55:58.261657 systemd-resolved[1325]: Positive Trust Anchors: Aug 12 23:55:58.261681 systemd-resolved[1325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 12 23:55:58.261756 systemd-resolved[1325]: 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 Aug 12 23:55:58.278841 systemd-resolved[1325]: Defaulting to hostname 'linux'. Aug 12 23:55:58.289680 systemd[1]: Finished setup-oem.service - Setup OEM. Aug 12 23:55:58.298817 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 12 23:55:58.324932 systemd[1]: Condition check resulted in dev-tpmrm0.device - /dev/tpmrm0 being skipped. Aug 12 23:55:58.327100 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 12 23:55:58.395843 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. Aug 12 23:55:58.403612 kernel: piix4_smbus 0000:00:01.3: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr Aug 12 23:55:58.415897 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:55:58.425625 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Aug 12 23:55:58.435231 systemd[1]: Reached target tpm2.target - Trusted Platform Module. Aug 12 23:55:58.453622 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Aug 12 23:55:58.465565 kernel: ACPI: button: Power Button [PWRF] Aug 12 23:55:58.477701 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input5 Aug 12 23:55:58.477819 kernel: ACPI: button: Sleep Button [SLPF] Aug 12 23:55:58.478347 systemd[1]: Starting oem-gce-enable-oslogin.service - Enable GCE OS Login... Aug 12 23:55:58.497645 kernel: EDAC MC: Ver: 3.0.0 Aug 12 23:55:58.500889 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 12 23:55:58.510162 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 12 23:55:58.510279 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 12 23:55:58.529252 systemd-networkd[1395]: lo: Link UP Aug 12 23:55:58.529274 systemd-networkd[1395]: lo: Gained carrier Aug 12 23:55:58.536219 systemd-networkd[1395]: Enumeration completed Aug 12 23:55:58.536382 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 12 23:55:58.538463 systemd-networkd[1395]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:55:58.538477 systemd-networkd[1395]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:55:58.542348 systemd-networkd[1395]: eth0: Link UP Aug 12 23:55:58.542366 systemd-networkd[1395]: eth0: Gained carrier Aug 12 23:55:58.542393 systemd-networkd[1395]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:55:58.547174 systemd[1]: Reached target network.target - Network. Aug 12 23:55:58.551687 systemd-networkd[1395]: eth0: DHCPv4 address 10.128.0.55/32, gateway 10.128.0.1 acquired from 169.254.169.254 Aug 12 23:55:58.563867 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 12 23:55:58.573842 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 12 23:55:58.598133 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 12 23:55:58.629920 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:55:58.641332 systemd[1]: Finished oem-gce-enable-oslogin.service - Enable GCE OS Login. Aug 12 23:55:58.661541 kernel: mousedev: PS/2 mouse device common for all mice Aug 12 23:55:58.669286 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 12 23:55:58.700869 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 12 23:55:58.705085 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 12 23:55:58.729737 lvm[1452]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 12 23:55:58.771472 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 12 23:55:58.772798 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:55:58.779890 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 12 23:55:58.792018 lvm[1455]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 12 23:55:58.802831 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:55:58.814064 systemd[1]: Reached target sysinit.target - System Initialization. Aug 12 23:55:58.823900 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 12 23:55:58.834803 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 12 23:55:58.846003 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 12 23:55:58.855977 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 12 23:55:58.866765 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 12 23:55:58.877823 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 12 23:55:58.877882 systemd[1]: Reached target paths.target - Path Units. Aug 12 23:55:58.886763 systemd[1]: Reached target timers.target - Timer Units. Aug 12 23:55:58.897995 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 12 23:55:58.909479 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 12 23:55:58.920338 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 12 23:55:58.932021 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 12 23:55:58.943818 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 12 23:55:58.962511 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 12 23:55:58.973361 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 12 23:55:58.985890 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 12 23:55:58.997035 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 12 23:55:59.007615 systemd[1]: Reached target sockets.target - Socket Units. Aug 12 23:55:59.017752 systemd[1]: Reached target basic.target - Basic System. Aug 12 23:55:59.025844 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 12 23:55:59.025902 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 12 23:55:59.031819 systemd[1]: Starting containerd.service - containerd container runtime... Aug 12 23:55:59.054382 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 12 23:55:59.075842 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 12 23:55:59.099978 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 12 23:55:59.119866 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 12 23:55:59.129759 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). Aug 12 23:55:59.136412 jq[1464]: false Aug 12 23:55:59.137831 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 12 23:55:59.156376 systemd[1]: Started ntpd.service - Network Time Service. Aug 12 23:55:59.177853 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 12 23:55:59.191735 extend-filesystems[1465]: Found loop4 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found loop5 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found loop6 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found loop7 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda1 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda2 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda3 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found usr Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda4 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda6 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda7 Aug 12 23:55:59.191735 extend-filesystems[1465]: Found sda9 Aug 12 23:55:59.191735 extend-filesystems[1465]: Checking size of /dev/sda9 Aug 12 23:55:59.420045 kernel: EXT4-fs (sda9): resizing filesystem from 1617920 to 2538491 blocks Aug 12 23:55:59.420104 kernel: EXT4-fs (sda9): resized filesystem to 2538491 Aug 12 23:55:59.420142 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (1367) Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.192 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/hostname: Attempt #1 Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.194 INFO Fetch successful Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.194 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/access-configs/0/external-ip: Attempt #1 Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.196 INFO Fetch successful Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.197 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/ip: Attempt #1 Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.198 INFO Fetch successful Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.201 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/machine-type: Attempt #1 Aug 12 23:55:59.420468 coreos-metadata[1462]: Aug 12 23:55:59.201 INFO Fetch successful Aug 12 23:55:59.421025 extend-filesystems[1465]: Resized partition /dev/sda9 Aug 12 23:55:59.251737 dbus-daemon[1463]: [system] SELinux support is enabled Aug 12 23:55:59.195334 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: ntpd 4.2.8p17@1.4004-o Tue Aug 12 20:59:43 UTC 2025 (1): Starting Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: ---------------------------------------------------- Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: ntp-4 is maintained by Network Time Foundation, Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: corporation. Support and training for ntp-4 are Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: available at https://www.nwtime.org/support Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: ---------------------------------------------------- Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: proto: precision = 0.087 usec (-23) Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: basedate set to 2025-07-31 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: gps base set to 2025-08-03 (week 2378) Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Listen and drop on 0 v6wildcard [::]:123 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Listen normally on 2 lo 127.0.0.1:123 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Listen normally on 3 eth0 10.128.0.55:123 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Listen normally on 4 lo [::1]:123 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: bind(21) AF_INET6 fe80::4001:aff:fe80:37%2#123 flags 0x11 failed: Cannot assign requested address Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: unable to create socket on eth0 (5) for fe80::4001:aff:fe80:37%2#123 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: failed to init interface for address fe80::4001:aff:fe80:37%2 Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: Listening on routing socket on fd #21 for interface updates Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 12 23:55:59.453094 ntpd[1470]: 12 Aug 23:55:59 ntpd[1470]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 12 23:55:59.454499 extend-filesystems[1489]: resize2fs 1.47.1 (20-May-2024) Aug 12 23:55:59.454499 extend-filesystems[1489]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Aug 12 23:55:59.454499 extend-filesystems[1489]: old_desc_blocks = 1, new_desc_blocks = 2 Aug 12 23:55:59.454499 extend-filesystems[1489]: The filesystem on /dev/sda9 is now 2538491 (4k) blocks long. Aug 12 23:55:59.257844 dbus-daemon[1463]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1395 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Aug 12 23:55:59.222925 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 12 23:55:59.500336 extend-filesystems[1465]: Resized filesystem in /dev/sda9 Aug 12 23:55:59.262188 ntpd[1470]: ntpd 4.2.8p17@1.4004-o Tue Aug 12 20:59:43 UTC 2025 (1): Starting Aug 12 23:55:59.235116 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionSecurity=!tpm2). Aug 12 23:55:59.510253 update_engine[1484]: I20250812 23:55:59.414827 1484 main.cc:92] Flatcar Update Engine starting Aug 12 23:55:59.510253 update_engine[1484]: I20250812 23:55:59.422945 1484 update_check_scheduler.cc:74] Next update check in 11m15s Aug 12 23:55:59.262223 ntpd[1470]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Aug 12 23:55:59.236142 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 12 23:55:59.262239 ntpd[1470]: ---------------------------------------------------- Aug 12 23:55:59.243849 systemd[1]: Starting update-engine.service - Update Engine... Aug 12 23:55:59.513311 jq[1491]: true Aug 12 23:55:59.262254 ntpd[1470]: ntp-4 is maintained by Network Time Foundation, Aug 12 23:55:59.280801 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 12 23:55:59.262270 ntpd[1470]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Aug 12 23:55:59.305527 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 12 23:55:59.262285 ntpd[1470]: corporation. Support and training for ntp-4 are Aug 12 23:55:59.335209 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 12 23:55:59.520719 jq[1497]: true Aug 12 23:55:59.262302 ntpd[1470]: available at https://www.nwtime.org/support Aug 12 23:55:59.336704 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 12 23:55:59.262319 ntpd[1470]: ---------------------------------------------------- Aug 12 23:55:59.337242 systemd[1]: motdgen.service: Deactivated successfully. Aug 12 23:55:59.272776 ntpd[1470]: proto: precision = 0.087 usec (-23) Aug 12 23:55:59.338864 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 12 23:55:59.278980 ntpd[1470]: basedate set to 2025-07-31 Aug 12 23:55:59.358308 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 12 23:55:59.279007 ntpd[1470]: gps base set to 2025-08-03 (week 2378) Aug 12 23:55:59.358705 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 12 23:55:59.294677 ntpd[1470]: Listen and drop on 0 v6wildcard [::]:123 Aug 12 23:55:59.397142 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 12 23:55:59.294755 ntpd[1470]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 12 23:55:59.399267 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 12 23:55:59.298580 ntpd[1470]: Listen normally on 2 lo 127.0.0.1:123 Aug 12 23:55:59.298705 ntpd[1470]: Listen normally on 3 eth0 10.128.0.55:123 Aug 12 23:55:59.298774 ntpd[1470]: Listen normally on 4 lo [::1]:123 Aug 12 23:55:59.298866 ntpd[1470]: bind(21) AF_INET6 fe80::4001:aff:fe80:37%2#123 flags 0x11 failed: Cannot assign requested address Aug 12 23:55:59.298900 ntpd[1470]: unable to create socket on eth0 (5) for fe80::4001:aff:fe80:37%2#123 Aug 12 23:55:59.298922 ntpd[1470]: failed to init interface for address fe80::4001:aff:fe80:37%2 Aug 12 23:55:59.298974 ntpd[1470]: Listening on routing socket on fd #21 for interface updates Aug 12 23:55:59.311069 ntpd[1470]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 12 23:55:59.311112 ntpd[1470]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 12 23:55:59.492367 dbus-daemon[1463]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 12 23:55:59.531267 (ntainerd)[1505]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 12 23:55:59.533201 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 12 23:55:59.563329 systemd[1]: Started update-engine.service - Update Engine. Aug 12 23:55:59.577731 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 12 23:55:59.583727 systemd-logind[1477]: Watching system buttons on /dev/input/event2 (Power Button) Aug 12 23:55:59.584206 systemd-logind[1477]: Watching system buttons on /dev/input/event3 (Sleep Button) Aug 12 23:55:59.584241 systemd-logind[1477]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 12 23:55:59.588534 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 12 23:55:59.588733 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). Aug 12 23:55:59.588779 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 12 23:55:59.589114 systemd-logind[1477]: New seat seat0. Aug 12 23:55:59.608332 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 12 23:55:59.618775 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). Aug 12 23:55:59.618828 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 12 23:55:59.644421 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 12 23:55:59.655098 systemd[1]: Started systemd-logind.service - User Login Management. Aug 12 23:55:59.690657 bash[1528]: Updated "/home/core/.ssh/authorized_keys" Aug 12 23:55:59.693873 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 12 23:55:59.723657 systemd[1]: Starting sshkeys.service... Aug 12 23:55:59.783738 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Aug 12 23:55:59.811117 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Aug 12 23:55:59.926701 systemd-networkd[1395]: eth0: Gained IPv6LL Aug 12 23:55:59.937566 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 12 23:55:59.950962 systemd[1]: Reached target network-online.target - Network is Online. Aug 12 23:55:59.971310 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 12 23:55:59.991529 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 12 23:56:00.001909 coreos-metadata[1532]: Aug 12 23:56:00.001 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/sshKeys: Attempt #1 Aug 12 23:56:00.008727 coreos-metadata[1532]: Aug 12 23:56:00.007 INFO Fetch failed with 404: resource not found Aug 12 23:56:00.008727 coreos-metadata[1532]: Aug 12 23:56:00.007 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/ssh-keys: Attempt #1 Aug 12 23:56:00.006076 systemd[1]: Starting oem-gce.service - GCE Linux Agent... Aug 12 23:56:00.010367 coreos-metadata[1532]: Aug 12 23:56:00.009 INFO Fetch successful Aug 12 23:56:00.010367 coreos-metadata[1532]: Aug 12 23:56:00.009 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/block-project-ssh-keys: Attempt #1 Aug 12 23:56:00.010843 coreos-metadata[1532]: Aug 12 23:56:00.010 INFO Fetch failed with 404: resource not found Aug 12 23:56:00.010843 coreos-metadata[1532]: Aug 12 23:56:00.010 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/sshKeys: Attempt #1 Aug 12 23:56:00.011736 coreos-metadata[1532]: Aug 12 23:56:00.011 INFO Fetch failed with 404: resource not found Aug 12 23:56:00.012200 coreos-metadata[1532]: Aug 12 23:56:00.011 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/ssh-keys: Attempt #1 Aug 12 23:56:00.013346 dbus-daemon[1463]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 12 23:56:00.014280 coreos-metadata[1532]: Aug 12 23:56:00.013 INFO Fetch successful Aug 12 23:56:00.022083 dbus-daemon[1463]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1521 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Aug 12 23:56:00.022897 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 12 23:56:00.023864 unknown[1532]: wrote ssh authorized keys file for user: core Aug 12 23:56:00.049888 init.sh[1545]: + '[' -e /etc/default/instance_configs.cfg.template ']' Aug 12 23:56:00.050283 init.sh[1545]: + echo -e '[InstanceSetup]\nset_host_keys = false' Aug 12 23:56:00.050283 init.sh[1545]: + /usr/bin/google_instance_setup Aug 12 23:56:00.064115 systemd[1]: Starting polkit.service - Authorization Manager... Aug 12 23:56:00.138724 update-ssh-keys[1547]: Updated "/home/core/.ssh/authorized_keys" Aug 12 23:56:00.142412 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Aug 12 23:56:00.165771 systemd[1]: Finished sshkeys.service. Aug 12 23:56:00.193244 locksmithd[1529]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 12 23:56:00.194532 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 12 23:56:00.201863 polkitd[1552]: Started polkitd version 121 Aug 12 23:56:00.219934 polkitd[1552]: Loading rules from directory /etc/polkit-1/rules.d Aug 12 23:56:00.220032 polkitd[1552]: Loading rules from directory /usr/share/polkit-1/rules.d Aug 12 23:56:00.221648 polkitd[1552]: Finished loading, compiling and executing 2 rules Aug 12 23:56:00.238900 dbus-daemon[1463]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 12 23:56:00.240025 systemd[1]: Started polkit.service - Authorization Manager. Aug 12 23:56:00.242050 polkitd[1552]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Aug 12 23:56:00.285798 systemd-hostnamed[1521]: Hostname set to (transient) Aug 12 23:56:00.287763 systemd-resolved[1325]: System hostname changed to 'ci-4230-2-2-c28fd753853072bed103.c.flatcar-212911.internal'. Aug 12 23:56:00.320461 containerd[1505]: time="2025-08-12T23:56:00.320309929Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Aug 12 23:56:00.415621 containerd[1505]: time="2025-08-12T23:56:00.412875684Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:56:00.416840 containerd[1505]: time="2025-08-12T23:56:00.416764093Z" 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.100-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:56:00.416840 containerd[1505]: time="2025-08-12T23:56:00.416834329Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 12 23:56:00.416996 containerd[1505]: time="2025-08-12T23:56:00.416863297Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 12 23:56:00.417250 containerd[1505]: time="2025-08-12T23:56:00.417218335Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 12 23:56:00.417341 containerd[1505]: time="2025-08-12T23:56:00.417258104Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 12 23:56:00.417388 containerd[1505]: time="2025-08-12T23:56:00.417352488Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:56:00.417388 containerd[1505]: time="2025-08-12T23:56:00.417377672Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:56:00.417781 containerd[1505]: time="2025-08-12T23:56:00.417748023Z" 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 Aug 12 23:56:00.417854 containerd[1505]: time="2025-08-12T23:56:00.417783750Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 12 23:56:00.417854 containerd[1505]: time="2025-08-12T23:56:00.417805973Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:56:00.417854 containerd[1505]: time="2025-08-12T23:56:00.417822278Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 12 23:56:00.419336 containerd[1505]: time="2025-08-12T23:56:00.418079815Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:56:00.419336 containerd[1505]: time="2025-08-12T23:56:00.418418322Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:56:00.419336 containerd[1505]: time="2025-08-12T23:56:00.418755328Z" 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 Aug 12 23:56:00.419336 containerd[1505]: time="2025-08-12T23:56:00.418780864Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 12 23:56:00.419336 containerd[1505]: time="2025-08-12T23:56:00.418909592Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 12 23:56:00.419336 containerd[1505]: time="2025-08-12T23:56:00.418973654Z" level=info msg="metadata content store policy set" policy=shared Aug 12 23:56:00.430799 containerd[1505]: time="2025-08-12T23:56:00.430712044Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 12 23:56:00.431014 containerd[1505]: time="2025-08-12T23:56:00.430989803Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 12 23:56:00.431736 containerd[1505]: time="2025-08-12T23:56:00.431150979Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 12 23:56:00.431736 containerd[1505]: time="2025-08-12T23:56:00.431196743Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 12 23:56:00.431736 containerd[1505]: time="2025-08-12T23:56:00.431223102Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 12 23:56:00.431736 containerd[1505]: time="2025-08-12T23:56:00.431426473Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 12 23:56:00.432238 containerd[1505]: time="2025-08-12T23:56:00.432209087Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 12 23:56:00.432511 containerd[1505]: time="2025-08-12T23:56:00.432486798Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 12 23:56:00.432635 containerd[1505]: time="2025-08-12T23:56:00.432615976Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434646312Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434692006Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434719877Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434742663Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434767965Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434796314Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434821984Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434844881Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434864657Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434898908Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434920302Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434941762Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434965580Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.435635 containerd[1505]: time="2025-08-12T23:56:00.434989091Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435012898Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435034231Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435058983Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435083103Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435111700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435134249Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435154997Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435192089Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435218486Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435257129Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435282514Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435311818Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435392750Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 12 23:56:00.436329 containerd[1505]: time="2025-08-12T23:56:00.435421830Z" 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 Aug 12 23:56:00.436935 containerd[1505]: time="2025-08-12T23:56:00.435442553Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 12 23:56:00.436935 containerd[1505]: time="2025-08-12T23:56:00.435464110Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 12 23:56:00.436935 containerd[1505]: time="2025-08-12T23:56:00.435482711Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.436935 containerd[1505]: time="2025-08-12T23:56:00.435505850Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 12 23:56:00.436935 containerd[1505]: time="2025-08-12T23:56:00.435524276Z" level=info msg="NRI interface is disabled by configuration." Aug 12 23:56:00.436935 containerd[1505]: time="2025-08-12T23:56:00.435544188Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 12 23:56:00.440771 containerd[1505]: time="2025-08-12T23:56:00.437795436Z" 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}" Aug 12 23:56:00.440771 containerd[1505]: time="2025-08-12T23:56:00.437885824Z" level=info msg="Connect containerd service" Aug 12 23:56:00.440771 containerd[1505]: time="2025-08-12T23:56:00.437942006Z" level=info msg="using legacy CRI server" Aug 12 23:56:00.440771 containerd[1505]: time="2025-08-12T23:56:00.437955004Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 12 23:56:00.440771 containerd[1505]: time="2025-08-12T23:56:00.438139216Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 12 23:56:00.444358 containerd[1505]: time="2025-08-12T23:56:00.441635638Z" 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" Aug 12 23:56:00.444358 containerd[1505]: time="2025-08-12T23:56:00.443654614Z" level=info msg="Start subscribing containerd event" Aug 12 23:56:00.444358 containerd[1505]: time="2025-08-12T23:56:00.443719260Z" level=info msg="Start recovering state" Aug 12 23:56:00.444358 containerd[1505]: time="2025-08-12T23:56:00.443821355Z" level=info msg="Start event monitor" Aug 12 23:56:00.444358 containerd[1505]: time="2025-08-12T23:56:00.443848273Z" level=info msg="Start snapshots syncer" Aug 12 23:56:00.444358 containerd[1505]: time="2025-08-12T23:56:00.443862611Z" level=info msg="Start cni network conf syncer for default" Aug 12 23:56:00.444358 containerd[1505]: time="2025-08-12T23:56:00.443874854Z" level=info msg="Start streaming server" Aug 12 23:56:00.447615 containerd[1505]: time="2025-08-12T23:56:00.446386489Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 12 23:56:00.447615 containerd[1505]: time="2025-08-12T23:56:00.446564634Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 12 23:56:00.449778 systemd[1]: Started containerd.service - containerd container runtime. Aug 12 23:56:00.450731 containerd[1505]: time="2025-08-12T23:56:00.449820096Z" level=info msg="containerd successfully booted in 0.131237s" Aug 12 23:56:00.485182 sshd_keygen[1488]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 12 23:56:00.524506 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 12 23:56:00.548011 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 12 23:56:00.566840 systemd[1]: Started sshd@0-10.128.0.55:22-147.75.109.163:43522.service - OpenSSH per-connection server daemon (147.75.109.163:43522). Aug 12 23:56:00.585930 systemd[1]: issuegen.service: Deactivated successfully. Aug 12 23:56:00.586283 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 12 23:56:00.611710 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 12 23:56:00.655138 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 12 23:56:00.676195 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 12 23:56:00.695194 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 12 23:56:00.706085 systemd[1]: Reached target getty.target - Login Prompts. Aug 12 23:56:00.931064 instance-setup[1550]: INFO Running google_set_multiqueue. Aug 12 23:56:00.951116 instance-setup[1550]: INFO Set channels for eth0 to 2. Aug 12 23:56:00.956192 instance-setup[1550]: INFO Setting /proc/irq/31/smp_affinity_list to 0 for device virtio1. Aug 12 23:56:00.958780 instance-setup[1550]: INFO /proc/irq/31/smp_affinity_list: real affinity 0 Aug 12 23:56:00.958843 instance-setup[1550]: INFO Setting /proc/irq/32/smp_affinity_list to 0 for device virtio1. Aug 12 23:56:00.960828 instance-setup[1550]: INFO /proc/irq/32/smp_affinity_list: real affinity 0 Aug 12 23:56:00.960917 instance-setup[1550]: INFO Setting /proc/irq/33/smp_affinity_list to 1 for device virtio1. Aug 12 23:56:00.962800 instance-setup[1550]: INFO /proc/irq/33/smp_affinity_list: real affinity 1 Aug 12 23:56:00.964739 instance-setup[1550]: INFO Setting /proc/irq/34/smp_affinity_list to 1 for device virtio1. Aug 12 23:56:00.966702 instance-setup[1550]: INFO /proc/irq/34/smp_affinity_list: real affinity 1 Aug 12 23:56:00.973820 sshd[1584]: Accepted publickey for core from 147.75.109.163 port 43522 ssh2: RSA SHA256:9aXzO+MEIo3XxFgnuuLVUvTEVyloQ3bi2zf7RUsM6ZM Aug 12 23:56:00.976799 sshd-session[1584]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 12 23:56:00.979993 instance-setup[1550]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type Aug 12 23:56:00.990803 instance-setup[1550]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type Aug 12 23:56:00.993350 instance-setup[1550]: INFO Queue 0 XPS=1 for /sys/class/net/eth0/queues/tx-0/xps_cpus Aug 12 23:56:00.993622 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 12 23:56:00.994041 instance-setup[1550]: INFO Queue 1 XPS=2 for /sys/class/net/eth0/queues/tx-1/xps_cpus Aug 12 23:56:01.018762 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 12 23:56:01.022705 init.sh[1545]: + /usr/bin/google_metadata_script_runner --script-type startup Aug 12 23:56:01.049579 systemd-logind[1477]: New session 1 of user core. Aug 12 23:56:01.071201 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 12 23:56:01.097017 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 12 23:56:01.144408 (systemd)[1626]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 12 23:56:01.154499 systemd-logind[1477]: New session c1 of user core. Aug 12 23:56:01.247430 startup-script[1624]: INFO Starting startup scripts. Aug 12 23:56:01.253470 startup-script[1624]: INFO No startup scripts found in metadata. Aug 12 23:56:01.253558 startup-script[1624]: INFO Finished running startup scripts. Aug 12 23:56:01.281132 init.sh[1545]: + trap 'stopping=1 ; kill "${daemon_pids[@]}" || :' SIGTERM Aug 12 23:56:01.287735 init.sh[1545]: + daemon_pids=() Aug 12 23:56:01.287735 init.sh[1545]: + for d in accounts clock_skew network Aug 12 23:56:01.287735 init.sh[1545]: + daemon_pids+=($!) Aug 12 23:56:01.287863 init.sh[1634]: + /usr/bin/google_accounts_daemon Aug 12 23:56:01.288151 init.sh[1545]: + for d in accounts clock_skew network Aug 12 23:56:01.288151 init.sh[1545]: + daemon_pids+=($!) Aug 12 23:56:01.288151 init.sh[1545]: + for d in accounts clock_skew network Aug 12 23:56:01.288151 init.sh[1545]: + daemon_pids+=($!) Aug 12 23:56:01.288151 init.sh[1545]: + NOTIFY_SOCKET=/run/systemd/notify Aug 12 23:56:01.288151 init.sh[1545]: + /usr/bin/systemd-notify --ready Aug 12 23:56:01.289681 init.sh[1635]: + /usr/bin/google_clock_skew_daemon Aug 12 23:56:01.292608 init.sh[1636]: + /usr/bin/google_network_daemon Aug 12 23:56:01.312236 systemd[1]: Started oem-gce.service - GCE Linux Agent. Aug 12 23:56:01.333619 init.sh[1545]: + wait -n 1634 1635 1636 Aug 12 23:56:01.492548 systemd[1626]: Queued start job for default target default.target. Aug 12 23:56:01.500173 systemd[1626]: Created slice app.slice - User Application Slice. Aug 12 23:56:01.500219 systemd[1626]: Reached target paths.target - Paths. Aug 12 23:56:01.500928 systemd[1626]: Reached target timers.target - Timers. Aug 12 23:56:01.510641 systemd[1626]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 12 23:56:01.543205 systemd[1626]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 12 23:56:01.544508 systemd[1626]: Reached target sockets.target - Sockets. Aug 12 23:56:01.544628 systemd[1626]: Reached target basic.target - Basic System. Aug 12 23:56:01.544714 systemd[1626]: Reached target default.target - Main User Target. Aug 12 23:56:01.544767 systemd[1626]: Startup finished in 374ms. Aug 12 23:56:01.545689 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 12 23:56:01.561846 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 12 23:56:01.823043 systemd[1]: Started sshd@1-10.128.0.55:22-147.75.109.163:43530.service - OpenSSH per-connection server daemon (147.75.109.163:43530). Aug 12 23:56:01.868519 google-clock-skew[1635]: INFO Starting Google Clock Skew daemon. Aug 12 23:56:01.887376 google-networking[1636]: INFO Starting Google Networking daemon. Aug 12 23:56:01.888545 google-clock-skew[1635]: INFO Clock drift token has changed: 0. Aug 12 23:56:01.947440 groupadd[1653]: group added to /etc/group: name=google-sudoers, GID=1000 Aug 12 23:56:01.951202 groupadd[1653]: group added to /etc/gshadow: name=google-sudoers Aug 12 23:56:02.006794 groupadd[1653]: new group: name=google-sudoers, GID=1000 Aug 12 23:56:02.036746 google-accounts[1634]: INFO Starting Google Accounts daemon. Aug 12 23:56:02.050298 google-accounts[1634]: WARNING OS Login not installed. Aug 12 23:56:02.051798 google-accounts[1634]: INFO Creating a new user account for 0. Aug 12 23:56:02.058362 init.sh[1661]: useradd: invalid user name '0': use --badname to ignore Aug 12 23:56:02.058720 google-accounts[1634]: WARNING Could not create user 0. Command '['useradd', '-m', '-s', '/bin/bash', '-p', '*', '0']' returned non-zero exit status 3.. Aug 12 23:56:02.161374 sshd[1649]: Accepted publickey for core from 147.75.109.163 port 43530 ssh2: RSA SHA256:9aXzO+MEIo3XxFgnuuLVUvTEVyloQ3bi2zf7RUsM6ZM Aug 12 23:56:02.163979 sshd-session[1649]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 12 23:56:02.172794 systemd-logind[1477]: New session 2 of user core. Aug 12 23:56:02.176836 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 12 23:56:02.262843 ntpd[1470]: Listen normally on 6 eth0 [fe80::4001:aff:fe80:37%2]:123 Aug 12 23:56:02.263526 ntpd[1470]: 12 Aug 23:56:02 ntpd[1470]: Listen normally on 6 eth0 [fe80::4001:aff:fe80:37%2]:123 Aug 12 23:56:02.310265 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 12 23:56:02.322563 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 12 23:56:02.328463 (kubelet)[1669]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 12 23:56:02.333752 systemd[1]: Startup finished in 1.063s (kernel) + 9.071s (initrd) + 9.561s (userspace) = 19.696s. Aug 12 23:56:02.380174 sshd[1663]: Connection closed by 147.75.109.163 port 43530 Aug 12 23:56:02.380839 sshd-session[1649]: pam_unix(sshd:session): session closed for user core Aug 12 23:56:02.387069 systemd-logind[1477]: Session 2 logged out. Waiting for processes to exit. Aug 12 23:56:02.387262 systemd[1]: sshd@1-10.128.0.55:22-147.75.109.163:43530.service: Deactivated successfully. Aug 12 23:56:02.390752 systemd[1]: session-2.scope: Deactivated successfully. Aug 12 23:56:02.392376 systemd-logind[1477]: Removed session 2. Aug 12 23:56:02.441191 systemd[1]: Started sshd@2-10.128.0.55:22-147.75.109.163:43546.service - OpenSSH per-connection server daemon (147.75.109.163:43546). Aug 12 23:56:03.000207 systemd-resolved[1325]: Clock change detected. Flushing caches. Aug 12 23:56:03.000993 google-clock-skew[1635]: INFO Synced system time with hardware clock. Aug 12 23:56:03.163710 sshd[1679]: Accepted publickey for core from 147.75.109.163 port 43546 ssh2: RSA SHA256:9aXzO+MEIo3XxFgnuuLVUvTEVyloQ3bi2zf7RUsM6ZM Aug 12 23:56:03.165787 sshd-session[1679]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 12 23:56:03.173096 systemd-logind[1477]: New session 3 of user core. Aug 12 23:56:03.182117 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 12 23:56:03.373858 sshd[1685]: Connection closed by 147.75.109.163 port 43546 Aug 12 23:56:03.374757 sshd-session[1679]: pam_unix(sshd:session): session closed for user core Aug 12 23:56:03.380332 systemd[1]: sshd@2-10.128.0.55:22-147.75.109.163:43546.service: Deactivated successfully. Aug 12 23:56:03.383314 systemd[1]: session-3.scope: Deactivated successfully. Aug 12 23:56:03.385880 systemd-logind[1477]: Session 3 logged out. Waiting for processes to exit. Aug 12 23:56:03.387390 systemd-logind[1477]: Removed session 3. Aug 12 23:56:03.438274 systemd[1]: Started sshd@3-10.128.0.55:22-147.75.109.163:43556.service - OpenSSH per-connection server daemon (147.75.109.163:43556). Aug 12 23:56:03.614454 kubelet[1669]: E0812 23:56:03.614378 1669 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" Aug 12 23:56:03.617392 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 12 23:56:03.617663 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 12 23:56:03.618253 systemd[1]: kubelet.service: Consumed 1.296s CPU time, 266.1M memory peak. Aug 12 23:56:03.731946 sshd[1692]: Accepted publickey for core from 147.75.109.163 port 43556 ssh2: RSA SHA256:9aXzO+MEIo3XxFgnuuLVUvTEVyloQ3bi2zf7RUsM6ZM Aug 12 23:56:03.733459 sshd-session[1692]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 12 23:56:03.739569 systemd-logind[1477]: New session 4 of user core. Aug 12 23:56:03.749754 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 12 23:56:03.943933 sshd[1696]: Connection closed by 147.75.109.163 port 43556 Aug 12 23:56:03.944795 sshd-session[1692]: pam_unix(sshd:session): session closed for user core Aug 12 23:56:03.950146 systemd[1]: sshd@3-10.128.0.55:22-147.75.109.163:43556.service: Deactivated successfully. Aug 12 23:56:03.952478 systemd[1]: session-4.scope: Deactivated successfully. Aug 12 23:56:03.953635 systemd-logind[1477]: Session 4 logged out. Waiting for processes to exit. Aug 12 23:56:03.955311 systemd-logind[1477]: Removed session 4. Aug 12 23:56:04.002219 systemd[1]: Started sshd@4-10.128.0.55:22-147.75.109.163:43566.service - OpenSSH per-connection server daemon (147.75.109.163:43566). Aug 12 23:56:04.289259 sshd[1702]: Accepted publickey for core from 147.75.109.163 port 43566 ssh2: RSA SHA256:9aXzO+MEIo3XxFgnuuLVUvTEVyloQ3bi2zf7RUsM6ZM Aug 12 23:56:04.290976 sshd-session[1702]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 12 23:56:04.296794 systemd-logind[1477]: New session 5 of user core. Aug 12 23:56:04.303049 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 12 23:56:04.483026 sudo[1705]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 12 23:56:04.483554 sudo[1705]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 12 23:56:05.361689 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 12 23:56:05.362287 systemd[1]: kubelet.service: Consumed 1.296s CPU time, 266.1M memory peak. Aug 12 23:56:05.369509 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 12 23:56:05.424706 systemd[1]: Reload requested from client PID 1739 ('systemctl') (unit session-5.scope)... Aug 12 23:56:05.424730 systemd[1]: Reloading... Aug 12 23:56:05.589852 zram_generator::config[1784]: No configuration found. Aug 12 23:56:05.753896 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. Aug 12 23:56:05.898141 systemd[1]: Reloading finished in 472 ms. Aug 12 23:56:05.961810 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 12 23:56:05.970016 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Aug 12 23:56:05.972146 systemd[1]: kubelet.service: Deactivated successfully. Aug 12 23:56:05.972477 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 12 23:56:05.972550 systemd[1]: kubelet.service: Consumed 161ms CPU time, 98.2M memory peak. Aug 12 23:56:05.978206 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 12 23:56:06.246875 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 12 23:56:06.258383 (kubelet)[1837]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 12 23:56:06.313268 kubelet[1837]: 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. Aug 12 23:56:06.313268 kubelet[1837]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Aug 12 23:56:06.313268 kubelet[1837]: 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. Aug 12 23:56:06.313884 kubelet[1837]: I0812 23:56:06.313367 1837 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 12 23:56:06.972576 kubelet[1837]: I0812 23:56:06.972513 1837 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Aug 12 23:56:06.972576 kubelet[1837]: I0812 23:56:06.972549 1837 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 12 23:56:06.973076 kubelet[1837]: I0812 23:56:06.973034 1837 server.go:954] "Client rotation is on, will bootstrap in background" Aug 12 23:56:07.009475 kubelet[1837]: I0812 23:56:07.008358 1837 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 12 23:56:07.028363 kubelet[1837]: E0812 23:56:07.027595 1837 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Aug 12 23:56:07.028363 kubelet[1837]: I0812 23:56:07.027643 1837 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Aug 12 23:56:07.031810 kubelet[1837]: I0812 23:56:07.031751 1837 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 12 23:56:07.035531 kubelet[1837]: I0812 23:56:07.035436 1837 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 12 23:56:07.036125 kubelet[1837]: I0812 23:56:07.035490 1837 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.128.0.55","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":"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},{"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}],"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} Aug 12 23:56:07.036125 kubelet[1837]: I0812 23:56:07.035811 1837 topology_manager.go:138] "Creating topology manager with none policy" Aug 12 23:56:07.036125 kubelet[1837]: I0812 23:56:07.035844 1837 container_manager_linux.go:304] "Creating device plugin manager" Aug 12 23:56:07.036125 kubelet[1837]: I0812 23:56:07.036041 1837 state_mem.go:36] "Initialized new in-memory state store" Aug 12 23:56:07.041155 kubelet[1837]: I0812 23:56:07.040964 1837 kubelet.go:446] "Attempting to sync node with API server" Aug 12 23:56:07.041155 kubelet[1837]: I0812 23:56:07.041010 1837 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 12 23:56:07.041155 kubelet[1837]: I0812 23:56:07.041040 1837 kubelet.go:352] "Adding apiserver pod source" Aug 12 23:56:07.041155 kubelet[1837]: I0812 23:56:07.041059 1837 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 12 23:56:07.044589 kubelet[1837]: E0812 23:56:07.044022 1837 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:07.044589 kubelet[1837]: E0812 23:56:07.044082 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:07.045194 kubelet[1837]: I0812 23:56:07.045167 1837 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Aug 12 23:56:07.045913 kubelet[1837]: I0812 23:56:07.045873 1837 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Aug 12 23:56:07.046968 kubelet[1837]: W0812 23:56:07.046930 1837 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 12 23:56:07.049835 kubelet[1837]: I0812 23:56:07.049585 1837 watchdog_linux.go:99] "Systemd watchdog is not enabled" Aug 12 23:56:07.049835 kubelet[1837]: I0812 23:56:07.049639 1837 server.go:1287] "Started kubelet" Aug 12 23:56:07.053756 kubelet[1837]: I0812 23:56:07.053691 1837 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Aug 12 23:56:07.055558 kubelet[1837]: I0812 23:56:07.055054 1837 server.go:479] "Adding debug handlers to kubelet server" Aug 12 23:56:07.057730 kubelet[1837]: I0812 23:56:07.057181 1837 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Aug 12 23:56:07.057730 kubelet[1837]: I0812 23:56:07.057548 1837 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 12 23:56:07.058417 kubelet[1837]: I0812 23:56:07.058385 1837 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 12 23:56:07.068583 kubelet[1837]: I0812 23:56:07.068491 1837 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Aug 12 23:56:07.072904 kubelet[1837]: E0812 23:56:07.072878 1837 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.128.0.55\" not found" Aug 12 23:56:07.073106 kubelet[1837]: I0812 23:56:07.073088 1837 volume_manager.go:297] "Starting Kubelet Volume Manager" Aug 12 23:56:07.073456 kubelet[1837]: I0812 23:56:07.073437 1837 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Aug 12 23:56:07.074244 kubelet[1837]: I0812 23:56:07.073582 1837 reconciler.go:26] "Reconciler: start to sync state" Aug 12 23:56:07.074464 kubelet[1837]: E0812 23:56:07.074442 1837 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 12 23:56:07.074996 kubelet[1837]: I0812 23:56:07.074961 1837 factory.go:221] Registration of the systemd container factory successfully Aug 12 23:56:07.075413 kubelet[1837]: I0812 23:56:07.075386 1837 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 Aug 12 23:56:07.078647 kubelet[1837]: I0812 23:56:07.078618 1837 factory.go:221] Registration of the containerd container factory successfully Aug 12 23:56:07.084837 kubelet[1837]: E0812 23:56:07.081373 1837 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.128.0.55\" not found" node="10.128.0.55" Aug 12 23:56:07.118628 kubelet[1837]: I0812 23:56:07.118057 1837 cpu_manager.go:221] "Starting CPU manager" policy="none" Aug 12 23:56:07.118628 kubelet[1837]: I0812 23:56:07.118083 1837 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Aug 12 23:56:07.118628 kubelet[1837]: I0812 23:56:07.118109 1837 state_mem.go:36] "Initialized new in-memory state store" Aug 12 23:56:07.121850 kubelet[1837]: I0812 23:56:07.121700 1837 policy_none.go:49] "None policy: Start" Aug 12 23:56:07.121850 kubelet[1837]: I0812 23:56:07.121731 1837 memory_manager.go:186] "Starting memorymanager" policy="None" Aug 12 23:56:07.121850 kubelet[1837]: I0812 23:56:07.121749 1837 state_mem.go:35] "Initializing new in-memory state store" Aug 12 23:56:07.132247 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Aug 12 23:56:07.156331 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Aug 12 23:56:07.163376 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Aug 12 23:56:07.173879 kubelet[1837]: I0812 23:56:07.173247 1837 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 12 23:56:07.173879 kubelet[1837]: I0812 23:56:07.173491 1837 eviction_manager.go:189] "Eviction manager: starting control loop" Aug 12 23:56:07.173879 kubelet[1837]: I0812 23:56:07.173505 1837 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Aug 12 23:56:07.175222 kubelet[1837]: I0812 23:56:07.175053 1837 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 12 23:56:07.177570 kubelet[1837]: E0812 23:56:07.177534 1837 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Aug 12 23:56:07.177694 kubelet[1837]: E0812 23:56:07.177611 1837 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.128.0.55\" not found" Aug 12 23:56:07.182464 kubelet[1837]: I0812 23:56:07.182414 1837 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 12 23:56:07.184509 kubelet[1837]: I0812 23:56:07.184474 1837 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 12 23:56:07.184509 kubelet[1837]: I0812 23:56:07.184510 1837 status_manager.go:227] "Starting to sync pod status with apiserver" Aug 12 23:56:07.184678 kubelet[1837]: I0812 23:56:07.184537 1837 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Aug 12 23:56:07.184678 kubelet[1837]: I0812 23:56:07.184548 1837 kubelet.go:2382] "Starting kubelet main sync loop" Aug 12 23:56:07.184678 kubelet[1837]: E0812 23:56:07.184615 1837 kubelet.go:2406] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Aug 12 23:56:07.276178 kubelet[1837]: I0812 23:56:07.276085 1837 kubelet_node_status.go:75] "Attempting to register node" node="10.128.0.55" Aug 12 23:56:07.281735 kubelet[1837]: I0812 23:56:07.281667 1837 kubelet_node_status.go:78] "Successfully registered node" node="10.128.0.55" Aug 12 23:56:07.299350 kubelet[1837]: I0812 23:56:07.299310 1837 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Aug 12 23:56:07.300144 containerd[1505]: time="2025-08-12T23:56:07.299992565Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Aug 12 23:56:07.301112 kubelet[1837]: I0812 23:56:07.301077 1837 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Aug 12 23:56:07.321225 sudo[1705]: pam_unix(sudo:session): session closed for user root Aug 12 23:56:07.363751 sshd[1704]: Connection closed by 147.75.109.163 port 43566 Aug 12 23:56:07.364626 sshd-session[1702]: pam_unix(sshd:session): session closed for user core Aug 12 23:56:07.370581 systemd[1]: sshd@4-10.128.0.55:22-147.75.109.163:43566.service: Deactivated successfully. Aug 12 23:56:07.373397 systemd[1]: session-5.scope: Deactivated successfully. Aug 12 23:56:07.373735 systemd[1]: session-5.scope: Consumed 616ms CPU time, 75.5M memory peak. Aug 12 23:56:07.375366 systemd-logind[1477]: Session 5 logged out. Waiting for processes to exit. Aug 12 23:56:07.377234 systemd-logind[1477]: Removed session 5. Aug 12 23:56:07.977070 kubelet[1837]: I0812 23:56:07.977015 1837 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Aug 12 23:56:07.977690 kubelet[1837]: W0812 23:56:07.977287 1837 reflector.go:492] 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 Aug 12 23:56:07.977690 kubelet[1837]: W0812 23:56:07.977621 1837 reflector.go:492] 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 Aug 12 23:56:07.977690 kubelet[1837]: W0812 23:56:07.977663 1837 reflector.go:492] 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 Aug 12 23:56:08.044903 kubelet[1837]: I0812 23:56:08.044852 1837 apiserver.go:52] "Watching apiserver" Aug 12 23:56:08.045147 kubelet[1837]: E0812 23:56:08.044864 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:08.061756 systemd[1]: Created slice kubepods-besteffort-pod3d4eabef_c353_46db_a7cc_654147153961.slice - libcontainer container kubepods-besteffort-pod3d4eabef_c353_46db_a7cc_654147153961.slice. Aug 12 23:56:08.073871 kubelet[1837]: I0812 23:56:08.073840 1837 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Aug 12 23:56:08.073941 systemd[1]: Created slice kubepods-burstable-podf5fc020c_7c04_4843_8ca2_55a6e70db893.slice - libcontainer container kubepods-burstable-podf5fc020c_7c04_4843_8ca2_55a6e70db893.slice. Aug 12 23:56:08.079611 kubelet[1837]: I0812 23:56:08.079553 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f5fc020c-7c04-4843-8ca2-55a6e70db893-run\") pod \"kube-flannel-ds-mt5v7\" (UID: \"f5fc020c-7c04-4843-8ca2-55a6e70db893\") " pod="kube-flannel/kube-flannel-ds-mt5v7" Aug 12 23:56:08.079611 kubelet[1837]: I0812 23:56:08.079602 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f5fc020c-7c04-4843-8ca2-55a6e70db893-cni-plugin\") pod \"kube-flannel-ds-mt5v7\" (UID: \"f5fc020c-7c04-4843-8ca2-55a6e70db893\") " pod="kube-flannel/kube-flannel-ds-mt5v7" Aug 12 23:56:08.079859 kubelet[1837]: I0812 23:56:08.079632 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f5fc020c-7c04-4843-8ca2-55a6e70db893-cni\") pod \"kube-flannel-ds-mt5v7\" (UID: \"f5fc020c-7c04-4843-8ca2-55a6e70db893\") " pod="kube-flannel/kube-flannel-ds-mt5v7" Aug 12 23:56:08.079859 kubelet[1837]: I0812 23:56:08.079656 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f5fc020c-7c04-4843-8ca2-55a6e70db893-xtables-lock\") pod \"kube-flannel-ds-mt5v7\" (UID: \"f5fc020c-7c04-4843-8ca2-55a6e70db893\") " pod="kube-flannel/kube-flannel-ds-mt5v7" Aug 12 23:56:08.079859 kubelet[1837]: I0812 23:56:08.079683 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-swlmv\" (UniqueName: \"kubernetes.io/projected/f5fc020c-7c04-4843-8ca2-55a6e70db893-kube-api-access-swlmv\") pod \"kube-flannel-ds-mt5v7\" (UID: \"f5fc020c-7c04-4843-8ca2-55a6e70db893\") " pod="kube-flannel/kube-flannel-ds-mt5v7" Aug 12 23:56:08.079859 kubelet[1837]: I0812 23:56:08.079712 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3d4eabef-c353-46db-a7cc-654147153961-xtables-lock\") pod \"kube-proxy-892m9\" (UID: \"3d4eabef-c353-46db-a7cc-654147153961\") " pod="kube-system/kube-proxy-892m9" Aug 12 23:56:08.079859 kubelet[1837]: I0812 23:56:08.079737 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vfvkk\" (UniqueName: \"kubernetes.io/projected/3d4eabef-c353-46db-a7cc-654147153961-kube-api-access-vfvkk\") pod \"kube-proxy-892m9\" (UID: \"3d4eabef-c353-46db-a7cc-654147153961\") " pod="kube-system/kube-proxy-892m9" Aug 12 23:56:08.080273 kubelet[1837]: I0812 23:56:08.079762 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f5fc020c-7c04-4843-8ca2-55a6e70db893-flannel-cfg\") pod \"kube-flannel-ds-mt5v7\" (UID: \"f5fc020c-7c04-4843-8ca2-55a6e70db893\") " pod="kube-flannel/kube-flannel-ds-mt5v7" Aug 12 23:56:08.080273 kubelet[1837]: I0812 23:56:08.079846 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/3d4eabef-c353-46db-a7cc-654147153961-kube-proxy\") pod \"kube-proxy-892m9\" (UID: \"3d4eabef-c353-46db-a7cc-654147153961\") " pod="kube-system/kube-proxy-892m9" Aug 12 23:56:08.080273 kubelet[1837]: I0812 23:56:08.079878 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/3d4eabef-c353-46db-a7cc-654147153961-lib-modules\") pod \"kube-proxy-892m9\" (UID: \"3d4eabef-c353-46db-a7cc-654147153961\") " pod="kube-system/kube-proxy-892m9" Aug 12 23:56:08.372827 containerd[1505]: time="2025-08-12T23:56:08.372771602Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-892m9,Uid:3d4eabef-c353-46db-a7cc-654147153961,Namespace:kube-system,Attempt:0,}" Aug 12 23:56:08.384549 containerd[1505]: time="2025-08-12T23:56:08.384473644Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-mt5v7,Uid:f5fc020c-7c04-4843-8ca2-55a6e70db893,Namespace:kube-flannel,Attempt:0,}" Aug 12 23:56:08.825102 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount561937764.mount: Deactivated successfully. Aug 12 23:56:08.834450 containerd[1505]: time="2025-08-12T23:56:08.834380642Z" 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\"}" Aug 12 23:56:08.836644 containerd[1505]: time="2025-08-12T23:56:08.836587110Z" 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\"}" Aug 12 23:56:08.838033 containerd[1505]: time="2025-08-12T23:56:08.837891567Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=313954" Aug 12 23:56:08.840423 containerd[1505]: time="2025-08-12T23:56:08.840373559Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 12 23:56:08.841003 containerd[1505]: time="2025-08-12T23:56:08.840938789Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 12 23:56:08.843073 containerd[1505]: time="2025-08-12T23:56:08.843023875Z" 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\"}" Aug 12 23:56:08.846313 containerd[1505]: time="2025-08-12T23:56:08.845686624Z" 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 472.725027ms" Aug 12 23:56:08.847730 containerd[1505]: time="2025-08-12T23:56:08.847675613Z" 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 463.07058ms" Aug 12 23:56:09.021762 containerd[1505]: time="2025-08-12T23:56:09.019239577Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 12 23:56:09.022134 containerd[1505]: time="2025-08-12T23:56:09.021669938Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 12 23:56:09.022615 containerd[1505]: time="2025-08-12T23:56:09.022214406Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:09.023012 containerd[1505]: time="2025-08-12T23:56:09.022776999Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:09.026301 containerd[1505]: time="2025-08-12T23:56:09.026203820Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 12 23:56:09.026456 containerd[1505]: time="2025-08-12T23:56:09.026290800Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 12 23:56:09.026456 containerd[1505]: time="2025-08-12T23:56:09.026312222Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:09.027154 containerd[1505]: time="2025-08-12T23:56:09.026445198Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:09.045603 kubelet[1837]: E0812 23:56:09.045293 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:09.142035 systemd[1]: Started cri-containerd-700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad.scope - libcontainer container 700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad. Aug 12 23:56:09.148848 systemd[1]: Started cri-containerd-fedd84ea09eb9b9bf2e28c14e6a1850f35dbb0d9ce0927eb6437d551ad6cad2c.scope - libcontainer container fedd84ea09eb9b9bf2e28c14e6a1850f35dbb0d9ce0927eb6437d551ad6cad2c. Aug 12 23:56:09.197057 containerd[1505]: time="2025-08-12T23:56:09.196393499Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-892m9,Uid:3d4eabef-c353-46db-a7cc-654147153961,Namespace:kube-system,Attempt:0,} returns sandbox id \"fedd84ea09eb9b9bf2e28c14e6a1850f35dbb0d9ce0927eb6437d551ad6cad2c\"" Aug 12 23:56:09.202550 containerd[1505]: time="2025-08-12T23:56:09.202228797Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.7\"" Aug 12 23:56:09.237540 containerd[1505]: time="2025-08-12T23:56:09.237465407Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-mt5v7,Uid:f5fc020c-7c04-4843-8ca2-55a6e70db893,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad\"" Aug 12 23:56:10.045620 kubelet[1837]: E0812 23:56:10.045554 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:10.526268 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount773367167.mount: Deactivated successfully. Aug 12 23:56:11.046732 kubelet[1837]: E0812 23:56:11.046670 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:11.181714 containerd[1505]: time="2025-08-12T23:56:11.181643169Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:11.183061 containerd[1505]: time="2025-08-12T23:56:11.182990087Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.7: active requests=0, bytes read=30897275" Aug 12 23:56:11.184522 containerd[1505]: time="2025-08-12T23:56:11.184450070Z" level=info msg="ImageCreate event name:\"sha256:d5bc66d8682fdab0735e869a3f77730df378af7fd2505c1f4d6374ad3dbd181c\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:11.189594 containerd[1505]: time="2025-08-12T23:56:11.188185737Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:8d589a18b5424f77a784ef2f00feffac0ef210414100822f1c120f0d7221def3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:11.189594 containerd[1505]: time="2025-08-12T23:56:11.189363040Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.7\" with image id \"sha256:d5bc66d8682fdab0735e869a3f77730df378af7fd2505c1f4d6374ad3dbd181c\", repo tag \"registry.k8s.io/kube-proxy:v1.32.7\", repo digest \"registry.k8s.io/kube-proxy@sha256:8d589a18b5424f77a784ef2f00feffac0ef210414100822f1c120f0d7221def3\", size \"30894399\" in 1.987082168s" Aug 12 23:56:11.189594 containerd[1505]: time="2025-08-12T23:56:11.189426552Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.7\" returns image reference \"sha256:d5bc66d8682fdab0735e869a3f77730df378af7fd2505c1f4d6374ad3dbd181c\"" Aug 12 23:56:11.191252 containerd[1505]: time="2025-08-12T23:56:11.191219587Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Aug 12 23:56:11.193649 containerd[1505]: time="2025-08-12T23:56:11.193594717Z" level=info msg="CreateContainer within sandbox \"fedd84ea09eb9b9bf2e28c14e6a1850f35dbb0d9ce0927eb6437d551ad6cad2c\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Aug 12 23:56:11.221342 containerd[1505]: time="2025-08-12T23:56:11.221175005Z" level=info msg="CreateContainer within sandbox \"fedd84ea09eb9b9bf2e28c14e6a1850f35dbb0d9ce0927eb6437d551ad6cad2c\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"e8261f98be094d16b21d492d938099f7a0c71ad5bad1b700c785acd5983a04f2\"" Aug 12 23:56:11.222203 containerd[1505]: time="2025-08-12T23:56:11.222092385Z" level=info msg="StartContainer for \"e8261f98be094d16b21d492d938099f7a0c71ad5bad1b700c785acd5983a04f2\"" Aug 12 23:56:11.266051 systemd[1]: Started cri-containerd-e8261f98be094d16b21d492d938099f7a0c71ad5bad1b700c785acd5983a04f2.scope - libcontainer container e8261f98be094d16b21d492d938099f7a0c71ad5bad1b700c785acd5983a04f2. Aug 12 23:56:11.306704 containerd[1505]: time="2025-08-12T23:56:11.306383406Z" level=info msg="StartContainer for \"e8261f98be094d16b21d492d938099f7a0c71ad5bad1b700c785acd5983a04f2\" returns successfully" Aug 12 23:56:12.047606 kubelet[1837]: E0812 23:56:12.047547 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:12.506572 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3091715537.mount: Deactivated successfully. Aug 12 23:56:12.560586 containerd[1505]: time="2025-08-12T23:56:12.560520376Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:12.561900 containerd[1505]: time="2025-08-12T23:56:12.561810708Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Aug 12 23:56:12.562855 containerd[1505]: time="2025-08-12T23:56:12.562795630Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:12.566679 containerd[1505]: time="2025-08-12T23:56:12.566597015Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:12.568426 containerd[1505]: time="2025-08-12T23:56:12.567913332Z" 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 1.376648723s" Aug 12 23:56:12.568426 containerd[1505]: time="2025-08-12T23:56:12.567967890Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Aug 12 23:56:12.570893 containerd[1505]: time="2025-08-12T23:56:12.570659026Z" level=info msg="CreateContainer within sandbox \"700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Aug 12 23:56:12.588640 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3284523645.mount: Deactivated successfully. Aug 12 23:56:12.590984 containerd[1505]: time="2025-08-12T23:56:12.590927527Z" level=info msg="CreateContainer within sandbox \"700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19\"" Aug 12 23:56:12.591629 containerd[1505]: time="2025-08-12T23:56:12.591529257Z" level=info msg="StartContainer for \"deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19\"" Aug 12 23:56:12.633048 systemd[1]: Started cri-containerd-deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19.scope - libcontainer container deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19. Aug 12 23:56:12.668416 systemd[1]: cri-containerd-deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19.scope: Deactivated successfully. Aug 12 23:56:12.669812 containerd[1505]: time="2025-08-12T23:56:12.669745633Z" level=info msg="StartContainer for \"deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19\" returns successfully" Aug 12 23:56:13.048123 kubelet[1837]: E0812 23:56:13.048059 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:13.069843 containerd[1505]: time="2025-08-12T23:56:13.069756117Z" level=info msg="shim disconnected" id=deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19 namespace=k8s.io Aug 12 23:56:13.069843 containerd[1505]: time="2025-08-12T23:56:13.069840555Z" level=warning msg="cleaning up after shim disconnected" id=deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19 namespace=k8s.io Aug 12 23:56:13.069843 containerd[1505]: time="2025-08-12T23:56:13.069854986Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 12 23:56:13.208497 containerd[1505]: time="2025-08-12T23:56:13.208200219Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Aug 12 23:56:13.221388 kubelet[1837]: I0812 23:56:13.221263 1837 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-892m9" podStartSLOduration=4.231505342 podStartE2EDuration="6.22124638s" podCreationTimestamp="2025-08-12 23:56:07 +0000 UTC" firstStartedPulling="2025-08-12 23:56:09.201249102 +0000 UTC m=+2.935807931" lastFinishedPulling="2025-08-12 23:56:11.190990141 +0000 UTC m=+4.925548969" observedRunningTime="2025-08-12 23:56:12.218332645 +0000 UTC m=+5.952891489" watchObservedRunningTime="2025-08-12 23:56:13.22124638 +0000 UTC m=+6.955805223" Aug 12 23:56:13.386078 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-deb14de8bfcf229fc77c82ae2b19ce4fa24804f63971d974d5a6d1c0bb580f19-rootfs.mount: Deactivated successfully. Aug 12 23:56:14.048839 kubelet[1837]: E0812 23:56:14.048756 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:14.495661 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2313142591.mount: Deactivated successfully. Aug 12 23:56:15.049490 kubelet[1837]: E0812 23:56:15.049447 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:15.402472 containerd[1505]: time="2025-08-12T23:56:15.402160569Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:15.403910 containerd[1505]: time="2025-08-12T23:56:15.403842224Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866357" Aug 12 23:56:15.405465 containerd[1505]: time="2025-08-12T23:56:15.405396150Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:15.409161 containerd[1505]: time="2025-08-12T23:56:15.409083575Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:15.410597 containerd[1505]: time="2025-08-12T23:56:15.410423677Z" 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 2.202172829s" Aug 12 23:56:15.410597 containerd[1505]: time="2025-08-12T23:56:15.410471251Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Aug 12 23:56:15.413677 containerd[1505]: time="2025-08-12T23:56:15.413638269Z" level=info msg="CreateContainer within sandbox \"700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Aug 12 23:56:15.433059 containerd[1505]: time="2025-08-12T23:56:15.432980306Z" level=info msg="CreateContainer within sandbox \"700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb\"" Aug 12 23:56:15.433837 containerd[1505]: time="2025-08-12T23:56:15.433728495Z" level=info msg="StartContainer for \"17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb\"" Aug 12 23:56:15.483089 systemd[1]: Started cri-containerd-17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb.scope - libcontainer container 17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb. Aug 12 23:56:15.515442 systemd[1]: cri-containerd-17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb.scope: Deactivated successfully. Aug 12 23:56:15.519640 containerd[1505]: time="2025-08-12T23:56:15.519048913Z" level=info msg="StartContainer for \"17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb\" returns successfully" Aug 12 23:56:15.544723 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb-rootfs.mount: Deactivated successfully. Aug 12 23:56:15.567139 kubelet[1837]: I0812 23:56:15.566864 1837 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Aug 12 23:56:15.767062 containerd[1505]: time="2025-08-12T23:56:15.766952094Z" level=info msg="shim disconnected" id=17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb namespace=k8s.io Aug 12 23:56:15.767366 containerd[1505]: time="2025-08-12T23:56:15.767083619Z" level=warning msg="cleaning up after shim disconnected" id=17bec1d681edef978ae42a88e66c1bdcdad1bcd8cd6f1541d8e1e4e5be7e32bb namespace=k8s.io Aug 12 23:56:15.767366 containerd[1505]: time="2025-08-12T23:56:15.767134515Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 12 23:56:16.050979 kubelet[1837]: E0812 23:56:16.050791 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:16.217664 containerd[1505]: time="2025-08-12T23:56:16.217557315Z" level=info msg="CreateContainer within sandbox \"700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Aug 12 23:56:16.234273 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3876114290.mount: Deactivated successfully. Aug 12 23:56:16.234975 containerd[1505]: time="2025-08-12T23:56:16.234500662Z" level=info msg="CreateContainer within sandbox \"700af7c50f1c07e02274fac4fc39d12d6c28499bb48b802e972c87694ae7e4ad\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"df9133df8579c90883c13da1598597aab2fe7a7db97e181c4d5b9a784e3537e6\"" Aug 12 23:56:16.236862 containerd[1505]: time="2025-08-12T23:56:16.236169612Z" level=info msg="StartContainer for \"df9133df8579c90883c13da1598597aab2fe7a7db97e181c4d5b9a784e3537e6\"" Aug 12 23:56:16.277050 systemd[1]: Started cri-containerd-df9133df8579c90883c13da1598597aab2fe7a7db97e181c4d5b9a784e3537e6.scope - libcontainer container df9133df8579c90883c13da1598597aab2fe7a7db97e181c4d5b9a784e3537e6. Aug 12 23:56:16.309446 containerd[1505]: time="2025-08-12T23:56:16.309201957Z" level=info msg="StartContainer for \"df9133df8579c90883c13da1598597aab2fe7a7db97e181c4d5b9a784e3537e6\" returns successfully" Aug 12 23:56:17.051844 kubelet[1837]: E0812 23:56:17.051769 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:17.384107 systemd-networkd[1395]: flannel.1: Link UP Aug 12 23:56:17.384145 systemd-networkd[1395]: flannel.1: Gained carrier Aug 12 23:56:18.052663 kubelet[1837]: E0812 23:56:18.052591 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:19.053536 kubelet[1837]: E0812 23:56:19.053460 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:19.296136 systemd-networkd[1395]: flannel.1: Gained IPv6LL Aug 12 23:56:20.004363 kubelet[1837]: I0812 23:56:20.004287 1837 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-mt5v7" podStartSLOduration=6.831417195 podStartE2EDuration="13.004242668s" podCreationTimestamp="2025-08-12 23:56:07 +0000 UTC" firstStartedPulling="2025-08-12 23:56:09.239155078 +0000 UTC m=+2.973713909" lastFinishedPulling="2025-08-12 23:56:15.411980562 +0000 UTC m=+9.146539382" observedRunningTime="2025-08-12 23:56:17.238331535 +0000 UTC m=+10.972890375" watchObservedRunningTime="2025-08-12 23:56:20.004242668 +0000 UTC m=+13.738801504" Aug 12 23:56:20.012676 systemd[1]: Created slice kubepods-besteffort-pod21241290_451a_4477_a4e7_f27ff9039f47.slice - libcontainer container kubepods-besteffort-pod21241290_451a_4477_a4e7_f27ff9039f47.slice. Aug 12 23:56:20.053453 kubelet[1837]: I0812 23:56:20.053376 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-klk7n\" (UniqueName: \"kubernetes.io/projected/21241290-451a-4477-a4e7-f27ff9039f47-kube-api-access-klk7n\") pod \"nginx-deployment-7fcdb87857-ssvr4\" (UID: \"21241290-451a-4477-a4e7-f27ff9039f47\") " pod="default/nginx-deployment-7fcdb87857-ssvr4" Aug 12 23:56:20.054487 kubelet[1837]: E0812 23:56:20.054434 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:20.317564 containerd[1505]: time="2025-08-12T23:56:20.317046449Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-ssvr4,Uid:21241290-451a-4477-a4e7-f27ff9039f47,Namespace:default,Attempt:0,}" Aug 12 23:56:20.354984 systemd-networkd[1395]: cni0: Link UP Aug 12 23:56:20.355025 systemd-networkd[1395]: cni0: Gained carrier Aug 12 23:56:20.362792 systemd-networkd[1395]: cni0: Lost carrier Aug 12 23:56:20.368761 systemd-networkd[1395]: veth4fdd98ef: Link UP Aug 12 23:56:20.388878 kernel: cni0: port 1(veth4fdd98ef) entered blocking state Aug 12 23:56:20.389000 kernel: cni0: port 1(veth4fdd98ef) entered disabled state Aug 12 23:56:20.389039 kernel: veth4fdd98ef: entered allmulticast mode Aug 12 23:56:20.389075 kernel: veth4fdd98ef: entered promiscuous mode Aug 12 23:56:20.392921 kernel: cni0: port 1(veth4fdd98ef) entered blocking state Aug 12 23:56:20.403674 kernel: cni0: port 1(veth4fdd98ef) entered forwarding state Aug 12 23:56:20.411892 kernel: cni0: port 1(veth4fdd98ef) entered disabled state Aug 12 23:56:20.435210 kernel: cni0: port 1(veth4fdd98ef) entered blocking state Aug 12 23:56:20.435326 kernel: cni0: port 1(veth4fdd98ef) entered forwarding state Aug 12 23:56:20.436597 systemd-networkd[1395]: veth4fdd98ef: Gained carrier Aug 12 23:56:20.437040 systemd-networkd[1395]: cni0: Gained carrier Aug 12 23:56:20.475200 containerd[1505]: 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)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Aug 12 23:56:20.475200 containerd[1505]: delegateAdd: netconf sent to delegate plugin: Aug 12 23:56:20.508807 containerd[1505]: {"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":1410,"name":"cbr0","type":"bridge"}time="2025-08-12T23:56:20.508501888Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 12 23:56:20.508807 containerd[1505]: time="2025-08-12T23:56:20.508596549Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 12 23:56:20.508807 containerd[1505]: time="2025-08-12T23:56:20.508616086Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:20.508807 containerd[1505]: time="2025-08-12T23:56:20.508746566Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:20.543059 systemd[1]: Started cri-containerd-d2dfedd568895b809a87182f68450107995cd6d15492b93f477911e8085dbdfc.scope - libcontainer container d2dfedd568895b809a87182f68450107995cd6d15492b93f477911e8085dbdfc. Aug 12 23:56:20.603152 containerd[1505]: time="2025-08-12T23:56:20.602917429Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-ssvr4,Uid:21241290-451a-4477-a4e7-f27ff9039f47,Namespace:default,Attempt:0,} returns sandbox id \"d2dfedd568895b809a87182f68450107995cd6d15492b93f477911e8085dbdfc\"" Aug 12 23:56:20.604860 containerd[1505]: time="2025-08-12T23:56:20.604569071Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 12 23:56:21.054988 kubelet[1837]: E0812 23:56:21.054885 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:21.729450 systemd-networkd[1395]: veth4fdd98ef: Gained IPv6LL Aug 12 23:56:22.047984 systemd-networkd[1395]: cni0: Gained IPv6LL Aug 12 23:56:22.055708 kubelet[1837]: E0812 23:56:22.055623 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:23.056472 kubelet[1837]: E0812 23:56:23.056406 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:23.174661 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount893117314.mount: Deactivated successfully. Aug 12 23:56:24.056782 kubelet[1837]: E0812 23:56:24.056536 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:24.669433 containerd[1505]: time="2025-08-12T23:56:24.669363941Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:24.670962 containerd[1505]: time="2025-08-12T23:56:24.670896106Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73303204" Aug 12 23:56:24.672110 containerd[1505]: time="2025-08-12T23:56:24.672031055Z" level=info msg="ImageCreate event name:\"sha256:f36b8965af58ac17c6fcb27d986b37161ceb26b3d41d3cd53f232b0e16761305\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:24.675407 containerd[1505]: time="2025-08-12T23:56:24.675327114Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:24.676803 containerd[1505]: time="2025-08-12T23:56:24.676632873Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f36b8965af58ac17c6fcb27d986b37161ceb26b3d41d3cd53f232b0e16761305\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\", size \"73303082\" in 4.072015498s" Aug 12 23:56:24.676803 containerd[1505]: time="2025-08-12T23:56:24.676678110Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f36b8965af58ac17c6fcb27d986b37161ceb26b3d41d3cd53f232b0e16761305\"" Aug 12 23:56:24.679922 containerd[1505]: time="2025-08-12T23:56:24.679724434Z" level=info msg="CreateContainer within sandbox \"d2dfedd568895b809a87182f68450107995cd6d15492b93f477911e8085dbdfc\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Aug 12 23:56:24.690153 ntpd[1470]: Listen normally on 7 flannel.1 192.168.1.0:123 Aug 12 23:56:24.692746 ntpd[1470]: 12 Aug 23:56:24 ntpd[1470]: Listen normally on 7 flannel.1 192.168.1.0:123 Aug 12 23:56:24.692746 ntpd[1470]: 12 Aug 23:56:24 ntpd[1470]: Listen normally on 8 cni0 192.168.1.1:123 Aug 12 23:56:24.692746 ntpd[1470]: 12 Aug 23:56:24 ntpd[1470]: Listen normally on 9 flannel.1 [fe80::4c3c:7cff:fef0:7fd1%3]:123 Aug 12 23:56:24.692746 ntpd[1470]: 12 Aug 23:56:24 ntpd[1470]: Listen normally on 10 cni0 [fe80::807c:89ff:fedc:18e7%4]:123 Aug 12 23:56:24.692746 ntpd[1470]: 12 Aug 23:56:24 ntpd[1470]: Listen normally on 11 veth4fdd98ef [fe80::fc4d:f2ff:fe8f:dd97%5]:123 Aug 12 23:56:24.690717 ntpd[1470]: Listen normally on 8 cni0 192.168.1.1:123 Aug 12 23:56:24.690808 ntpd[1470]: Listen normally on 9 flannel.1 [fe80::4c3c:7cff:fef0:7fd1%3]:123 Aug 12 23:56:24.690916 ntpd[1470]: Listen normally on 10 cni0 [fe80::807c:89ff:fedc:18e7%4]:123 Aug 12 23:56:24.690977 ntpd[1470]: Listen normally on 11 veth4fdd98ef [fe80::fc4d:f2ff:fe8f:dd97%5]:123 Aug 12 23:56:24.698241 containerd[1505]: time="2025-08-12T23:56:24.698182756Z" level=info msg="CreateContainer within sandbox \"d2dfedd568895b809a87182f68450107995cd6d15492b93f477911e8085dbdfc\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"226c748a08eaab5f7e91fa4898d23d84420abdf25ebd3ee2d16bdf7d13407de5\"" Aug 12 23:56:24.699146 containerd[1505]: time="2025-08-12T23:56:24.699038269Z" level=info msg="StartContainer for \"226c748a08eaab5f7e91fa4898d23d84420abdf25ebd3ee2d16bdf7d13407de5\"" Aug 12 23:56:24.742512 systemd[1]: run-containerd-runc-k8s.io-226c748a08eaab5f7e91fa4898d23d84420abdf25ebd3ee2d16bdf7d13407de5-runc.GJ6sIn.mount: Deactivated successfully. Aug 12 23:56:24.753110 systemd[1]: Started cri-containerd-226c748a08eaab5f7e91fa4898d23d84420abdf25ebd3ee2d16bdf7d13407de5.scope - libcontainer container 226c748a08eaab5f7e91fa4898d23d84420abdf25ebd3ee2d16bdf7d13407de5. Aug 12 23:56:24.786983 containerd[1505]: time="2025-08-12T23:56:24.786715263Z" level=info msg="StartContainer for \"226c748a08eaab5f7e91fa4898d23d84420abdf25ebd3ee2d16bdf7d13407de5\" returns successfully" Aug 12 23:56:25.057537 kubelet[1837]: E0812 23:56:25.057461 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:25.249348 kubelet[1837]: I0812 23:56:25.249272 1837 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-ssvr4" podStartSLOduration=2.175058105 podStartE2EDuration="6.249250994s" podCreationTimestamp="2025-08-12 23:56:19 +0000 UTC" firstStartedPulling="2025-08-12 23:56:20.603929604 +0000 UTC m=+14.338488441" lastFinishedPulling="2025-08-12 23:56:24.678122481 +0000 UTC m=+18.412681330" observedRunningTime="2025-08-12 23:56:25.249117444 +0000 UTC m=+18.983676289" watchObservedRunningTime="2025-08-12 23:56:25.249250994 +0000 UTC m=+18.983809837" Aug 12 23:56:26.058461 kubelet[1837]: E0812 23:56:26.058385 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:27.042011 kubelet[1837]: E0812 23:56:27.041933 1837 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:27.058641 kubelet[1837]: E0812 23:56:27.058557 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:28.059070 kubelet[1837]: E0812 23:56:28.058998 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:28.367509 systemd[1]: Created slice kubepods-besteffort-podc5f0a611_486c_43d8_89f4_68143304a87d.slice - libcontainer container kubepods-besteffort-podc5f0a611_486c_43d8_89f4_68143304a87d.slice. Aug 12 23:56:28.402859 kubelet[1837]: I0812 23:56:28.402738 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-j25tp\" (UniqueName: \"kubernetes.io/projected/c5f0a611-486c-43d8-89f4-68143304a87d-kube-api-access-j25tp\") pod \"nfs-server-provisioner-0\" (UID: \"c5f0a611-486c-43d8-89f4-68143304a87d\") " pod="default/nfs-server-provisioner-0" Aug 12 23:56:28.402859 kubelet[1837]: I0812 23:56:28.402805 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/c5f0a611-486c-43d8-89f4-68143304a87d-data\") pod \"nfs-server-provisioner-0\" (UID: \"c5f0a611-486c-43d8-89f4-68143304a87d\") " pod="default/nfs-server-provisioner-0" Aug 12 23:56:28.673254 containerd[1505]: time="2025-08-12T23:56:28.673049507Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c5f0a611-486c-43d8-89f4-68143304a87d,Namespace:default,Attempt:0,}" Aug 12 23:56:28.706442 systemd-networkd[1395]: vethe45bccc0: Link UP Aug 12 23:56:28.724027 kernel: cni0: port 2(vethe45bccc0) entered blocking state Aug 12 23:56:28.724149 kernel: cni0: port 2(vethe45bccc0) entered disabled state Aug 12 23:56:28.724188 kernel: vethe45bccc0: entered allmulticast mode Aug 12 23:56:28.732282 kernel: vethe45bccc0: entered promiscuous mode Aug 12 23:56:28.750882 kernel: cni0: port 2(vethe45bccc0) entered blocking state Aug 12 23:56:28.751203 kernel: cni0: port 2(vethe45bccc0) entered forwarding state Aug 12 23:56:28.750987 systemd-networkd[1395]: vethe45bccc0: Gained carrier Aug 12 23:56:28.754277 containerd[1505]: 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"} Aug 12 23:56:28.754277 containerd[1505]: delegateAdd: netconf sent to delegate plugin: Aug 12 23:56:28.787333 containerd[1505]: {"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":1410,"name":"cbr0","type":"bridge"}time="2025-08-12T23:56:28.786971786Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 12 23:56:28.787333 containerd[1505]: time="2025-08-12T23:56:28.787064878Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 12 23:56:28.787333 containerd[1505]: time="2025-08-12T23:56:28.787086312Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:28.787621 containerd[1505]: time="2025-08-12T23:56:28.787233753Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:28.819210 systemd[1]: run-containerd-runc-k8s.io-12ac7aa47b4b0d8f37d42bbcb4b09b3369623d0e46a2c4cda7ebdb9aa231da64-runc.kdUhCF.mount: Deactivated successfully. Aug 12 23:56:28.830130 systemd[1]: Started cri-containerd-12ac7aa47b4b0d8f37d42bbcb4b09b3369623d0e46a2c4cda7ebdb9aa231da64.scope - libcontainer container 12ac7aa47b4b0d8f37d42bbcb4b09b3369623d0e46a2c4cda7ebdb9aa231da64. Aug 12 23:56:28.883874 containerd[1505]: time="2025-08-12T23:56:28.883726476Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c5f0a611-486c-43d8-89f4-68143304a87d,Namespace:default,Attempt:0,} returns sandbox id \"12ac7aa47b4b0d8f37d42bbcb4b09b3369623d0e46a2c4cda7ebdb9aa231da64\"" Aug 12 23:56:28.886171 containerd[1505]: time="2025-08-12T23:56:28.886124514Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Aug 12 23:56:29.060187 kubelet[1837]: E0812 23:56:29.060112 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:30.060376 kubelet[1837]: E0812 23:56:30.060324 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:30.496178 systemd-networkd[1395]: vethe45bccc0: Gained IPv6LL Aug 12 23:56:30.749983 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 12 23:56:31.061185 kubelet[1837]: E0812 23:56:31.060716 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:31.436949 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3123198095.mount: Deactivated successfully. Aug 12 23:56:32.061450 kubelet[1837]: E0812 23:56:32.061396 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:32.689223 ntpd[1470]: Listen normally on 12 vethe45bccc0 [fe80::843e:d9ff:fe59:6052%6]:123 Aug 12 23:56:32.690658 ntpd[1470]: 12 Aug 23:56:32 ntpd[1470]: Listen normally on 12 vethe45bccc0 [fe80::843e:d9ff:fe59:6052%6]:123 Aug 12 23:56:33.062545 kubelet[1837]: E0812 23:56:33.062496 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:33.843841 containerd[1505]: time="2025-08-12T23:56:33.843749464Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:33.845446 containerd[1505]: time="2025-08-12T23:56:33.845372221Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91045236" Aug 12 23:56:33.846575 containerd[1505]: time="2025-08-12T23:56:33.846532317Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:33.850913 containerd[1505]: time="2025-08-12T23:56:33.850842617Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:33.852724 containerd[1505]: time="2025-08-12T23:56:33.852546973Z" 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 4.966188307s" Aug 12 23:56:33.852724 containerd[1505]: time="2025-08-12T23:56:33.852596757Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Aug 12 23:56:33.856194 containerd[1505]: time="2025-08-12T23:56:33.856140768Z" level=info msg="CreateContainer within sandbox \"12ac7aa47b4b0d8f37d42bbcb4b09b3369623d0e46a2c4cda7ebdb9aa231da64\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Aug 12 23:56:33.874639 containerd[1505]: time="2025-08-12T23:56:33.874555234Z" level=info msg="CreateContainer within sandbox \"12ac7aa47b4b0d8f37d42bbcb4b09b3369623d0e46a2c4cda7ebdb9aa231da64\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"d1e2245c0e0b2fc45e8f454008741c325978122cb42072dafa7a8ea819ca0783\"" Aug 12 23:56:33.875238 containerd[1505]: time="2025-08-12T23:56:33.875205149Z" level=info msg="StartContainer for \"d1e2245c0e0b2fc45e8f454008741c325978122cb42072dafa7a8ea819ca0783\"" Aug 12 23:56:33.920094 systemd[1]: Started cri-containerd-d1e2245c0e0b2fc45e8f454008741c325978122cb42072dafa7a8ea819ca0783.scope - libcontainer container d1e2245c0e0b2fc45e8f454008741c325978122cb42072dafa7a8ea819ca0783. Aug 12 23:56:33.955300 containerd[1505]: time="2025-08-12T23:56:33.955198619Z" level=info msg="StartContainer for \"d1e2245c0e0b2fc45e8f454008741c325978122cb42072dafa7a8ea819ca0783\" returns successfully" Aug 12 23:56:34.064000 kubelet[1837]: E0812 23:56:34.063945 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:34.278879 kubelet[1837]: I0812 23:56:34.278718 1837 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.310260302 podStartE2EDuration="6.278697104s" podCreationTimestamp="2025-08-12 23:56:28 +0000 UTC" firstStartedPulling="2025-08-12 23:56:28.885566303 +0000 UTC m=+22.620125139" lastFinishedPulling="2025-08-12 23:56:33.854003119 +0000 UTC m=+27.588561941" observedRunningTime="2025-08-12 23:56:34.278459418 +0000 UTC m=+28.013018253" watchObservedRunningTime="2025-08-12 23:56:34.278697104 +0000 UTC m=+28.013255949" Aug 12 23:56:35.064193 kubelet[1837]: E0812 23:56:35.064117 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:36.064381 kubelet[1837]: E0812 23:56:36.064304 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:37.065418 kubelet[1837]: E0812 23:56:37.065340 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:38.066268 kubelet[1837]: E0812 23:56:38.066188 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:39.066858 kubelet[1837]: E0812 23:56:39.066755 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:40.067854 kubelet[1837]: E0812 23:56:40.067753 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:41.068543 kubelet[1837]: E0812 23:56:41.068466 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:42.069267 kubelet[1837]: E0812 23:56:42.069176 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:43.070057 kubelet[1837]: E0812 23:56:43.069976 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:43.700702 systemd[1]: Created slice kubepods-besteffort-pod991c2f54_71cd_4877_a747_2870284e32b0.slice - libcontainer container kubepods-besteffort-pod991c2f54_71cd_4877_a747_2870284e32b0.slice. Aug 12 23:56:43.807126 kubelet[1837]: I0812 23:56:43.807060 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-db35c93c-07db-4b7d-a10c-9cc65f89047c\" (UniqueName: \"kubernetes.io/nfs/991c2f54-71cd-4877-a747-2870284e32b0-pvc-db35c93c-07db-4b7d-a10c-9cc65f89047c\") pod \"test-pod-1\" (UID: \"991c2f54-71cd-4877-a747-2870284e32b0\") " pod="default/test-pod-1" Aug 12 23:56:43.807126 kubelet[1837]: I0812 23:56:43.807127 1837 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h2vnr\" (UniqueName: \"kubernetes.io/projected/991c2f54-71cd-4877-a747-2870284e32b0-kube-api-access-h2vnr\") pod \"test-pod-1\" (UID: \"991c2f54-71cd-4877-a747-2870284e32b0\") " pod="default/test-pod-1" Aug 12 23:56:43.949900 kernel: FS-Cache: Loaded Aug 12 23:56:44.037054 kernel: RPC: Registered named UNIX socket transport module. Aug 12 23:56:44.037241 kernel: RPC: Registered udp transport module. Aug 12 23:56:44.037283 kernel: RPC: Registered tcp transport module. Aug 12 23:56:44.041749 kernel: RPC: Registered tcp-with-tls transport module. Aug 12 23:56:44.047410 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 12 23:56:44.071052 kubelet[1837]: E0812 23:56:44.070960 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:44.325736 kernel: NFS: Registering the id_resolver key type Aug 12 23:56:44.325931 kernel: Key type id_resolver registered Aug 12 23:56:44.325981 kernel: Key type id_legacy registered Aug 12 23:56:44.388084 nfsidmap[2880]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal' Aug 12 23:56:44.401112 nfsidmap[2881]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal' Aug 12 23:56:44.605847 containerd[1505]: time="2025-08-12T23:56:44.605749288Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:991c2f54-71cd-4877-a747-2870284e32b0,Namespace:default,Attempt:0,}" Aug 12 23:56:44.639901 systemd-networkd[1395]: vethe4582c8b: Link UP Aug 12 23:56:44.654281 kernel: cni0: port 3(vethe4582c8b) entered blocking state Aug 12 23:56:44.654402 kernel: cni0: port 3(vethe4582c8b) entered disabled state Aug 12 23:56:44.660983 kernel: vethe4582c8b: entered allmulticast mode Aug 12 23:56:44.661103 kernel: vethe4582c8b: entered promiscuous mode Aug 12 23:56:44.683673 kernel: cni0: port 3(vethe4582c8b) entered blocking state Aug 12 23:56:44.683807 kernel: cni0: port 3(vethe4582c8b) entered forwarding state Aug 12 23:56:44.683644 systemd-networkd[1395]: vethe4582c8b: Gained carrier Aug 12 23:56:44.686649 containerd[1505]: 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)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Aug 12 23:56:44.686649 containerd[1505]: delegateAdd: netconf sent to delegate plugin: Aug 12 23:56:44.718624 containerd[1505]: {"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":1410,"name":"cbr0","type":"bridge"}time="2025-08-12T23:56:44.718217071Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 12 23:56:44.718624 containerd[1505]: time="2025-08-12T23:56:44.718317489Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 12 23:56:44.718624 containerd[1505]: time="2025-08-12T23:56:44.718345614Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:44.719586 containerd[1505]: time="2025-08-12T23:56:44.719393496Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 12 23:56:44.746072 systemd[1]: Started cri-containerd-29ff89b870800730ab04e815b6b117726770b8f93c27641d4191cb6738aa650b.scope - libcontainer container 29ff89b870800730ab04e815b6b117726770b8f93c27641d4191cb6738aa650b. Aug 12 23:56:44.799063 containerd[1505]: time="2025-08-12T23:56:44.799007153Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:991c2f54-71cd-4877-a747-2870284e32b0,Namespace:default,Attempt:0,} returns sandbox id \"29ff89b870800730ab04e815b6b117726770b8f93c27641d4191cb6738aa650b\"" Aug 12 23:56:44.800617 containerd[1505]: time="2025-08-12T23:56:44.800583762Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 12 23:56:45.008129 containerd[1505]: time="2025-08-12T23:56:45.008056055Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 12 23:56:45.009250 containerd[1505]: time="2025-08-12T23:56:45.009168070Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Aug 12 23:56:45.012861 containerd[1505]: time="2025-08-12T23:56:45.012788516Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f36b8965af58ac17c6fcb27d986b37161ceb26b3d41d3cd53f232b0e16761305\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\", size \"73303082\" in 212.10619ms" Aug 12 23:56:45.013054 containerd[1505]: time="2025-08-12T23:56:45.013006902Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f36b8965af58ac17c6fcb27d986b37161ceb26b3d41d3cd53f232b0e16761305\"" Aug 12 23:56:45.015985 containerd[1505]: time="2025-08-12T23:56:45.015936958Z" level=info msg="CreateContainer within sandbox \"29ff89b870800730ab04e815b6b117726770b8f93c27641d4191cb6738aa650b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Aug 12 23:56:45.035002 containerd[1505]: time="2025-08-12T23:56:45.034948760Z" level=info msg="CreateContainer within sandbox \"29ff89b870800730ab04e815b6b117726770b8f93c27641d4191cb6738aa650b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"69f220a183d2270039f0341a32984fbf817d16d97cc78aa786bfecb521b57197\"" Aug 12 23:56:45.035832 containerd[1505]: time="2025-08-12T23:56:45.035774954Z" level=info msg="StartContainer for \"69f220a183d2270039f0341a32984fbf817d16d97cc78aa786bfecb521b57197\"" Aug 12 23:56:45.064978 update_engine[1484]: I20250812 23:56:45.064886 1484 update_attempter.cc:509] Updating boot flags... Aug 12 23:56:45.071243 kubelet[1837]: E0812 23:56:45.071200 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:45.090079 systemd[1]: Started cri-containerd-69f220a183d2270039f0341a32984fbf817d16d97cc78aa786bfecb521b57197.scope - libcontainer container 69f220a183d2270039f0341a32984fbf817d16d97cc78aa786bfecb521b57197. Aug 12 23:56:45.164996 containerd[1505]: time="2025-08-12T23:56:45.164775428Z" level=info msg="StartContainer for \"69f220a183d2270039f0341a32984fbf817d16d97cc78aa786bfecb521b57197\" returns successfully" Aug 12 23:56:45.215386 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (2876) Aug 12 23:56:45.385993 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (2999) Aug 12 23:56:45.534853 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (2999) Aug 12 23:56:45.923616 systemd[1]: run-containerd-runc-k8s.io-69f220a183d2270039f0341a32984fbf817d16d97cc78aa786bfecb521b57197-runc.H3OLA2.mount: Deactivated successfully. Aug 12 23:56:46.072234 kubelet[1837]: E0812 23:56:46.072150 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:46.624388 systemd-networkd[1395]: vethe4582c8b: Gained IPv6LL Aug 12 23:56:47.041729 kubelet[1837]: E0812 23:56:47.041643 1837 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:47.072802 kubelet[1837]: E0812 23:56:47.072715 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:48.073620 kubelet[1837]: E0812 23:56:48.073541 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:48.689240 ntpd[1470]: Listen normally on 13 vethe4582c8b [fe80::40b2:f6ff:fe70:921a%7]:123 Aug 12 23:56:48.689722 ntpd[1470]: 12 Aug 23:56:48 ntpd[1470]: Listen normally on 13 vethe4582c8b [fe80::40b2:f6ff:fe70:921a%7]:123 Aug 12 23:56:49.074718 kubelet[1837]: E0812 23:56:49.074631 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:50.075093 kubelet[1837]: E0812 23:56:50.075008 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 12 23:56:51.075974 kubelet[1837]: E0812 23:56:51.075904 1837 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"