Jul 6 23:30:55.091421 kernel: Linux version 6.6.95-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Sun Jul 6 21:53:45 -00 2025 Jul 6 23:30:55.091458 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:30:55.091473 kernel: BIOS-provided physical RAM map: Jul 6 23:30:55.091484 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 6 23:30:55.091494 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 6 23:30:55.091505 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 6 23:30:55.091518 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 6 23:30:55.091530 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 6 23:30:55.091544 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 6 23:30:55.091555 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 6 23:30:55.091566 kernel: BIOS-e820: [mem 0x0000000100000000-0x00000002bfffffff] usable Jul 6 23:30:55.091578 kernel: printk: bootconsole [earlyser0] enabled Jul 6 23:30:55.091589 kernel: NX (Execute Disable) protection: active Jul 6 23:30:55.091600 kernel: APIC: Static calls initialized Jul 6 23:30:55.091617 kernel: efi: EFI v2.7 by Microsoft Jul 6 23:30:55.091630 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5c1a98 RNG=0x3ffd1018 Jul 6 23:30:55.091642 kernel: random: crng init done Jul 6 23:30:55.091655 kernel: secureboot: Secure boot disabled Jul 6 23:30:55.091667 kernel: SMBIOS 3.1.0 present. Jul 6 23:30:55.091680 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 6 23:30:55.091692 kernel: Hypervisor detected: Microsoft Hyper-V Jul 6 23:30:55.091705 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x64e24, misc 0xbed7b2 Jul 6 23:30:55.091717 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 6 23:30:55.091729 kernel: Hyper-V: Nested features: 0x1e0101 Jul 6 23:30:55.091744 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 6 23:30:55.091756 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 6 23:30:55.091769 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 6 23:30:55.091782 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 6 23:30:55.091795 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 6 23:30:55.091807 kernel: tsc: Detected 2593.905 MHz processor Jul 6 23:30:55.091820 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 6 23:30:55.091833 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 6 23:30:55.091845 kernel: last_pfn = 0x2c0000 max_arch_pfn = 0x400000000 Jul 6 23:30:55.091861 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Jul 6 23:30:55.091873 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 6 23:30:55.091886 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 6 23:30:55.091898 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 6 23:30:55.091910 kernel: Using GB pages for direct mapping Jul 6 23:30:55.091922 kernel: ACPI: Early table checksum verification disabled Jul 6 23:30:55.091935 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 6 23:30:55.091953 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.091969 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.091983 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 6 23:30:55.091996 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 6 23:30:55.092009 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.092023 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.092036 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.092052 kernel: ACPI: APIC 0x000000003FFD5000 000058 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.092066 kernel: ACPI: SRAT 0x000000003FFD4000 0002D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.092079 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.092092 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:30:55.092106 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 6 23:30:55.093077 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 6 23:30:55.093096 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 6 23:30:55.093110 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 6 23:30:55.093136 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 6 23:30:55.093155 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 6 23:30:55.093168 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5057] Jul 6 23:30:55.093181 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd42cf] Jul 6 23:30:55.093195 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 6 23:30:55.093208 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 6 23:30:55.093222 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Jul 6 23:30:55.093235 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Jul 6 23:30:55.093248 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 6 23:30:55.093262 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x2bfffffff] hotplug Jul 6 23:30:55.093278 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x2c0000000-0xfdfffffff] hotplug Jul 6 23:30:55.093291 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Jul 6 23:30:55.093305 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Jul 6 23:30:55.093318 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Jul 6 23:30:55.093331 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Jul 6 23:30:55.093345 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Jul 6 23:30:55.093358 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Jul 6 23:30:55.093372 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Jul 6 23:30:55.093388 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Jul 6 23:30:55.093401 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Jul 6 23:30:55.093415 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000000-0x1ffffffffffff] hotplug Jul 6 23:30:55.093427 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x2000000000000-0x3ffffffffffff] hotplug Jul 6 23:30:55.093441 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4000000000000-0x7ffffffffffff] hotplug Jul 6 23:30:55.093454 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x8000000000000-0xfffffffffffff] hotplug Jul 6 23:30:55.093468 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x2bfffffff] -> [mem 0x00000000-0x2bfffffff] Jul 6 23:30:55.093481 kernel: NODE_DATA(0) allocated [mem 0x2bfffa000-0x2bfffffff] Jul 6 23:30:55.093494 kernel: Zone ranges: Jul 6 23:30:55.093510 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 6 23:30:55.093524 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 6 23:30:55.093537 kernel: Normal [mem 0x0000000100000000-0x00000002bfffffff] Jul 6 23:30:55.093550 kernel: Movable zone start for each node Jul 6 23:30:55.093563 kernel: Early memory node ranges Jul 6 23:30:55.093577 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 6 23:30:55.093590 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 6 23:30:55.093603 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 6 23:30:55.093616 kernel: node 0: [mem 0x0000000100000000-0x00000002bfffffff] Jul 6 23:30:55.093632 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x00000002bfffffff] Jul 6 23:30:55.093646 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 6 23:30:55.093659 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 6 23:30:55.093673 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 6 23:30:55.093686 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 6 23:30:55.093699 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 6 23:30:55.093712 kernel: IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23 Jul 6 23:30:55.093725 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 6 23:30:55.093739 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 6 23:30:55.093755 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 6 23:30:55.093769 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Jul 6 23:30:55.093782 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 6 23:30:55.093795 kernel: Booting paravirtualized kernel on Hyper-V Jul 6 23:30:55.093809 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 6 23:30:55.093822 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Jul 6 23:30:55.093836 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u1048576 Jul 6 23:30:55.093849 kernel: pcpu-alloc: s197096 r8192 d32280 u1048576 alloc=1*2097152 Jul 6 23:30:55.093862 kernel: pcpu-alloc: [0] 0 1 Jul 6 23:30:55.093878 kernel: Hyper-V: PV spinlocks enabled Jul 6 23:30:55.093891 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 6 23:30:55.093906 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:30:55.093921 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 6 23:30:55.093934 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Jul 6 23:30:55.093946 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 6 23:30:55.093957 kernel: Fallback order for Node 0: 0 Jul 6 23:30:55.093969 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2062618 Jul 6 23:30:55.093985 kernel: Policy zone: Normal Jul 6 23:30:55.094009 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 6 23:30:55.094023 kernel: software IO TLB: area num 2. Jul 6 23:30:55.094040 kernel: Memory: 8075040K/8387460K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43492K init, 1584K bss, 312164K reserved, 0K cma-reserved) Jul 6 23:30:55.094054 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jul 6 23:30:55.094068 kernel: ftrace: allocating 37940 entries in 149 pages Jul 6 23:30:55.094082 kernel: ftrace: allocated 149 pages with 4 groups Jul 6 23:30:55.094096 kernel: Dynamic Preempt: voluntary Jul 6 23:30:55.094109 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 6 23:30:55.094133 kernel: rcu: RCU event tracing is enabled. Jul 6 23:30:55.094148 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Jul 6 23:30:55.094165 kernel: Trampoline variant of Tasks RCU enabled. Jul 6 23:30:55.094180 kernel: Rude variant of Tasks RCU enabled. Jul 6 23:30:55.094193 kernel: Tracing variant of Tasks RCU enabled. Jul 6 23:30:55.094207 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 6 23:30:55.094222 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jul 6 23:30:55.094235 kernel: Using NULL legacy PIC Jul 6 23:30:55.094252 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 0 Jul 6 23:30:55.094266 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 6 23:30:55.094280 kernel: Console: colour dummy device 80x25 Jul 6 23:30:55.094294 kernel: printk: console [tty1] enabled Jul 6 23:30:55.094308 kernel: printk: console [ttyS0] enabled Jul 6 23:30:55.094322 kernel: printk: bootconsole [earlyser0] disabled Jul 6 23:30:55.094336 kernel: ACPI: Core revision 20230628 Jul 6 23:30:55.094350 kernel: Failed to register legacy timer interrupt Jul 6 23:30:55.094364 kernel: APIC: Switch to symmetric I/O mode setup Jul 6 23:30:55.094381 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 6 23:30:55.094394 kernel: Hyper-V: Using IPI hypercalls Jul 6 23:30:55.094408 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 6 23:30:55.094423 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 6 23:30:55.094436 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 6 23:30:55.094451 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 6 23:30:55.094465 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 6 23:30:55.094478 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 6 23:30:55.094493 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.81 BogoMIPS (lpj=2593905) Jul 6 23:30:55.094510 kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 Jul 6 23:30:55.094524 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 6 23:30:55.094538 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 6 23:30:55.094551 kernel: Spectre V2 : Mitigation: Retpolines Jul 6 23:30:55.094565 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 6 23:30:55.094579 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Jul 6 23:30:55.094593 kernel: RETBleed: Vulnerable Jul 6 23:30:55.094606 kernel: Speculative Store Bypass: Vulnerable Jul 6 23:30:55.094620 kernel: TAA: Vulnerable: Clear CPU buffers attempted, no microcode Jul 6 23:30:55.094634 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 6 23:30:55.094649 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 6 23:30:55.094663 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 6 23:30:55.094678 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 6 23:30:55.094691 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 6 23:30:55.094706 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Jul 6 23:30:55.094720 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Jul 6 23:30:55.094732 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Jul 6 23:30:55.094745 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 6 23:30:55.094759 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Jul 6 23:30:55.094771 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Jul 6 23:30:55.094783 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Jul 6 23:30:55.094798 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Jul 6 23:30:55.094811 kernel: Freeing SMP alternatives memory: 32K Jul 6 23:30:55.094824 kernel: pid_max: default: 32768 minimum: 301 Jul 6 23:30:55.094837 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jul 6 23:30:55.094850 kernel: landlock: Up and running. Jul 6 23:30:55.094863 kernel: SELinux: Initializing. Jul 6 23:30:55.094877 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jul 6 23:30:55.094890 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Jul 6 23:30:55.094903 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8272CL CPU @ 2.60GHz (family: 0x6, model: 0x55, stepping: 0x7) Jul 6 23:30:55.094916 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 6 23:30:55.094931 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 6 23:30:55.094948 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 6 23:30:55.094962 kernel: Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. Jul 6 23:30:55.094976 kernel: signal: max sigframe size: 3632 Jul 6 23:30:55.094990 kernel: rcu: Hierarchical SRCU implementation. Jul 6 23:30:55.095004 kernel: rcu: Max phase no-delay instances is 400. Jul 6 23:30:55.095018 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 6 23:30:55.095032 kernel: smp: Bringing up secondary CPUs ... Jul 6 23:30:55.095046 kernel: smpboot: x86: Booting SMP configuration: Jul 6 23:30:55.095060 kernel: .... node #0, CPUs: #1 Jul 6 23:30:55.095078 kernel: TAA CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/tsx_async_abort.html for more details. Jul 6 23:30:55.095093 kernel: 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. Jul 6 23:30:55.095107 kernel: smp: Brought up 1 node, 2 CPUs Jul 6 23:30:55.095138 kernel: smpboot: Max logical packages: 1 Jul 6 23:30:55.095153 kernel: smpboot: Total of 2 processors activated (10375.62 BogoMIPS) Jul 6 23:30:55.095166 kernel: devtmpfs: initialized Jul 6 23:30:55.095180 kernel: x86/mm: Memory block size: 128MB Jul 6 23:30:55.095194 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 6 23:30:55.095212 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 6 23:30:55.095226 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jul 6 23:30:55.095240 kernel: pinctrl core: initialized pinctrl subsystem Jul 6 23:30:55.095255 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 6 23:30:55.095270 kernel: audit: initializing netlink subsys (disabled) Jul 6 23:30:55.095284 kernel: audit: type=2000 audit(1751844653.030:1): state=initialized audit_enabled=0 res=1 Jul 6 23:30:55.095299 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 6 23:30:55.095313 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 6 23:30:55.095328 kernel: cpuidle: using governor menu Jul 6 23:30:55.095345 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 6 23:30:55.095360 kernel: dca service started, version 1.12.1 Jul 6 23:30:55.095374 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 6 23:30:55.095387 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 6 23:30:55.095401 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 6 23:30:55.095415 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 6 23:30:55.095429 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 6 23:30:55.095443 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 6 23:30:55.095456 kernel: ACPI: Added _OSI(Module Device) Jul 6 23:30:55.095473 kernel: ACPI: Added _OSI(Processor Device) Jul 6 23:30:55.095487 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 6 23:30:55.095502 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 6 23:30:55.095516 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jul 6 23:30:55.095530 kernel: ACPI: Interpreter enabled Jul 6 23:30:55.095543 kernel: ACPI: PM: (supports S0 S5) Jul 6 23:30:55.095558 kernel: ACPI: Using IOAPIC for interrupt routing Jul 6 23:30:55.095571 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 6 23:30:55.095585 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 6 23:30:55.095602 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 6 23:30:55.095615 kernel: iommu: Default domain type: Translated Jul 6 23:30:55.095628 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 6 23:30:55.095641 kernel: efivars: Registered efivars operations Jul 6 23:30:55.095662 kernel: PCI: Using ACPI for IRQ routing Jul 6 23:30:55.095677 kernel: PCI: System does not support PCI Jul 6 23:30:55.095690 kernel: vgaarb: loaded Jul 6 23:30:55.095704 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 6 23:30:55.095718 kernel: VFS: Disk quotas dquot_6.6.0 Jul 6 23:30:55.095735 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 6 23:30:55.095748 kernel: pnp: PnP ACPI init Jul 6 23:30:55.095762 kernel: pnp: PnP ACPI: found 3 devices Jul 6 23:30:55.095776 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 6 23:30:55.095789 kernel: NET: Registered PF_INET protocol family Jul 6 23:30:55.095803 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 6 23:30:55.095817 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Jul 6 23:30:55.095831 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 6 23:30:55.095845 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 6 23:30:55.095861 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 6 23:30:55.095874 kernel: TCP: Hash tables configured (established 65536 bind 65536) Jul 6 23:30:55.095888 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Jul 6 23:30:55.095902 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Jul 6 23:30:55.095915 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 6 23:30:55.095929 kernel: NET: Registered PF_XDP protocol family Jul 6 23:30:55.095943 kernel: PCI: CLS 0 bytes, default 64 Jul 6 23:30:55.095956 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 6 23:30:55.095970 kernel: software IO TLB: mapped [mem 0x000000003b5c1000-0x000000003f5c1000] (64MB) Jul 6 23:30:55.095986 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 6 23:30:55.096000 kernel: Initialise system trusted keyrings Jul 6 23:30:55.096013 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 Jul 6 23:30:55.096027 kernel: Key type asymmetric registered Jul 6 23:30:55.096040 kernel: Asymmetric key parser 'x509' registered Jul 6 23:30:55.096053 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jul 6 23:30:55.096067 kernel: io scheduler mq-deadline registered Jul 6 23:30:55.096080 kernel: io scheduler kyber registered Jul 6 23:30:55.096093 kernel: io scheduler bfq registered Jul 6 23:30:55.096110 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 6 23:30:55.096138 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 6 23:30:55.096151 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 6 23:30:55.096165 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 6 23:30:55.096179 kernel: i8042: PNP: No PS/2 controller found. Jul 6 23:30:55.096360 kernel: rtc_cmos 00:02: registered as rtc0 Jul 6 23:30:55.096477 kernel: rtc_cmos 00:02: setting system clock to 2025-07-06T23:30:54 UTC (1751844654) Jul 6 23:30:55.096586 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 6 23:30:55.096607 kernel: intel_pstate: CPU model not supported Jul 6 23:30:55.096620 kernel: efifb: probing for efifb Jul 6 23:30:55.096634 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 6 23:30:55.096648 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 6 23:30:55.096662 kernel: efifb: scrolling: redraw Jul 6 23:30:55.096676 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 6 23:30:55.096689 kernel: Console: switching to colour frame buffer device 128x48 Jul 6 23:30:55.096703 kernel: fb0: EFI VGA frame buffer device Jul 6 23:30:55.096717 kernel: pstore: Using crash dump compression: deflate Jul 6 23:30:55.096734 kernel: pstore: Registered efi_pstore as persistent store backend Jul 6 23:30:55.096747 kernel: NET: Registered PF_INET6 protocol family Jul 6 23:30:55.096761 kernel: Segment Routing with IPv6 Jul 6 23:30:55.096774 kernel: In-situ OAM (IOAM) with IPv6 Jul 6 23:30:55.096788 kernel: NET: Registered PF_PACKET protocol family Jul 6 23:30:55.096801 kernel: Key type dns_resolver registered Jul 6 23:30:55.096814 kernel: IPI shorthand broadcast: enabled Jul 6 23:30:55.096828 kernel: sched_clock: Marking stable (885002900, 49085500)->(1151116600, -217028200) Jul 6 23:30:55.096841 kernel: registered taskstats version 1 Jul 6 23:30:55.096858 kernel: Loading compiled-in X.509 certificates Jul 6 23:30:55.096872 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.95-flatcar: f74b958d282931d4f0d8d911dd18abd0ec707734' Jul 6 23:30:55.096885 kernel: Key type .fscrypt registered Jul 6 23:30:55.096898 kernel: Key type fscrypt-provisioning registered Jul 6 23:30:55.096912 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 6 23:30:55.096925 kernel: ima: Allocated hash algorithm: sha1 Jul 6 23:30:55.096939 kernel: ima: No architecture policies found Jul 6 23:30:55.096952 kernel: clk: Disabling unused clocks Jul 6 23:30:55.096965 kernel: Freeing unused kernel image (initmem) memory: 43492K Jul 6 23:30:55.096982 kernel: Write protecting the kernel read-only data: 38912k Jul 6 23:30:55.096995 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Jul 6 23:30:55.097008 kernel: Run /init as init process Jul 6 23:30:55.097021 kernel: with arguments: Jul 6 23:30:55.097034 kernel: /init Jul 6 23:30:55.097048 kernel: with environment: Jul 6 23:30:55.097061 kernel: HOME=/ Jul 6 23:30:55.097075 kernel: TERM=linux Jul 6 23:30:55.097089 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 6 23:30:55.097109 systemd[1]: Successfully made /usr/ read-only. Jul 6 23:30:55.097140 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 6 23:30:55.097155 systemd[1]: Detected virtualization microsoft. Jul 6 23:30:55.097169 systemd[1]: Detected architecture x86-64. Jul 6 23:30:55.097184 systemd[1]: Running in initrd. Jul 6 23:30:55.097199 systemd[1]: No hostname configured, using default hostname. Jul 6 23:30:55.097215 systemd[1]: Hostname set to . Jul 6 23:30:55.097232 systemd[1]: Initializing machine ID from random generator. Jul 6 23:30:55.097246 systemd[1]: Queued start job for default target initrd.target. Jul 6 23:30:55.097259 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:30:55.097274 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:30:55.097290 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 6 23:30:55.097305 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:30:55.097320 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 6 23:30:55.097340 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 6 23:30:55.097356 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 6 23:30:55.097371 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 6 23:30:55.097386 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:30:55.097401 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:30:55.097416 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:30:55.097430 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:30:55.097445 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:30:55.097466 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:30:55.097482 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:30:55.097508 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:30:55.097530 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 6 23:30:55.097546 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 6 23:30:55.097561 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:30:55.097577 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:30:55.097593 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:30:55.097608 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:30:55.097628 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 6 23:30:55.097643 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:30:55.097660 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 6 23:30:55.097675 systemd[1]: Starting systemd-fsck-usr.service... Jul 6 23:30:55.097691 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:30:55.097707 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:30:55.097750 systemd-journald[177]: Collecting audit messages is disabled. Jul 6 23:30:55.097788 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:30:55.097804 systemd-journald[177]: Journal started Jul 6 23:30:55.097837 systemd-journald[177]: Runtime Journal (/run/log/journal/8a6ddb05ba3541eeaf81b088c877e866) is 8M, max 158.8M, 150.8M free. Jul 6 23:30:55.108704 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:30:55.109452 systemd-modules-load[179]: Inserted module 'overlay' Jul 6 23:30:55.113403 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 6 23:30:55.118906 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:30:55.127612 systemd[1]: Finished systemd-fsck-usr.service. Jul 6 23:30:55.143576 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 6 23:30:55.152159 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:30:55.160016 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:30:55.164343 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:30:55.182135 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 6 23:30:55.185100 systemd-modules-load[179]: Inserted module 'br_netfilter' Jul 6 23:30:55.187472 kernel: Bridge firewalling registered Jul 6 23:30:55.187526 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:30:55.197323 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:30:55.203943 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:30:55.214707 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:30:55.221944 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:30:55.235338 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 6 23:30:55.241597 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:30:55.245449 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:30:55.259435 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:30:55.266867 dracut-cmdline[208]: dracut-dracut-053 Jul 6 23:30:55.275218 dracut-cmdline[208]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:30:55.270598 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:30:55.340848 systemd-resolved[221]: Positive Trust Anchors: Jul 6 23:30:55.343511 systemd-resolved[221]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:30:55.343569 systemd-resolved[221]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 6 23:30:55.379405 kernel: SCSI subsystem initialized Jul 6 23:30:55.371852 systemd-resolved[221]: Defaulting to hostname 'linux'. Jul 6 23:30:55.373156 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:30:55.386021 kernel: Loading iSCSI transport class v2.0-870. Jul 6 23:30:55.376007 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:30:55.398142 kernel: iscsi: registered transport (tcp) Jul 6 23:30:55.420244 kernel: iscsi: registered transport (qla4xxx) Jul 6 23:30:55.420354 kernel: QLogic iSCSI HBA Driver Jul 6 23:30:55.457233 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 6 23:30:55.466310 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 6 23:30:55.496169 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 6 23:30:55.496289 kernel: device-mapper: uevent: version 1.0.3 Jul 6 23:30:55.499559 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jul 6 23:30:55.541147 kernel: raid6: avx512x4 gen() 18284 MB/s Jul 6 23:30:55.560130 kernel: raid6: avx512x2 gen() 18092 MB/s Jul 6 23:30:55.579128 kernel: raid6: avx512x1 gen() 18322 MB/s Jul 6 23:30:55.598131 kernel: raid6: avx2x4 gen() 18029 MB/s Jul 6 23:30:55.617125 kernel: raid6: avx2x2 gen() 18024 MB/s Jul 6 23:30:55.637006 kernel: raid6: avx2x1 gen() 13699 MB/s Jul 6 23:30:55.637063 kernel: raid6: using algorithm avx512x1 gen() 18322 MB/s Jul 6 23:30:55.658571 kernel: raid6: .... xor() 25564 MB/s, rmw enabled Jul 6 23:30:55.658608 kernel: raid6: using avx512x2 recovery algorithm Jul 6 23:30:55.681142 kernel: xor: automatically using best checksumming function avx Jul 6 23:30:55.823144 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 6 23:30:55.832776 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:30:55.842288 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:30:55.860587 systemd-udevd[397]: Using default interface naming scheme 'v255'. Jul 6 23:30:55.865955 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:30:55.880294 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 6 23:30:55.893405 dracut-pre-trigger[411]: rd.md=0: removing MD RAID activation Jul 6 23:30:55.920953 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:30:55.929299 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:30:55.973957 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:30:55.989384 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 6 23:30:56.025137 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 6 23:30:56.032090 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:30:56.039001 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:30:56.045170 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:30:56.057322 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 6 23:30:56.069564 kernel: cryptd: max_cpu_qlen set to 1000 Jul 6 23:30:56.092471 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:30:56.097483 kernel: AVX2 version of gcm_enc/dec engaged. Jul 6 23:30:56.106009 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 6 23:30:56.114711 kernel: AES CTR mode by8 optimization enabled Jul 6 23:30:56.114738 kernel: hv_vmbus: Vmbus version:5.2 Jul 6 23:30:56.106330 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:30:56.114025 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:30:56.119980 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:30:56.120346 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:30:56.133371 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:30:56.144407 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 6 23:30:56.151142 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Jul 6 23:30:56.152531 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:30:56.181748 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 6 23:30:56.181820 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 6 23:30:56.186130 kernel: hv_vmbus: registering driver hv_storvsc Jul 6 23:30:56.187326 kernel: scsi host0: storvsc_host_t Jul 6 23:30:56.188252 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 6 23:30:56.190346 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 6 23:30:56.190742 kernel: scsi host1: storvsc_host_t Jul 6 23:30:56.191863 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 6 23:30:56.211412 kernel: hv_vmbus: registering driver hid_hyperv Jul 6 23:30:56.211473 kernel: PTP clock support registered Jul 6 23:30:56.211490 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 6 23:30:56.211507 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 6 23:30:56.218171 kernel: hv_utils: Registering HyperV Utility Driver Jul 6 23:30:56.218231 kernel: hv_vmbus: registering driver hv_utils Jul 6 23:30:56.235179 kernel: hv_utils: Heartbeat IC version 3.0 Jul 6 23:30:56.235243 kernel: hv_utils: Shutdown IC version 3.2 Jul 6 23:30:56.466401 kernel: hv_utils: TimeSync IC version 4.0 Jul 6 23:30:56.466257 systemd-resolved[221]: Clock change detected. Flushing caches. Jul 6 23:30:56.476717 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:30:56.487668 kernel: hv_vmbus: registering driver hv_netvsc Jul 6 23:30:56.487701 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 6 23:30:56.488013 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 6 23:30:56.488035 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 6 23:30:56.498712 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:30:56.528395 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 6 23:30:56.528791 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jul 6 23:30:56.531457 kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 6 23:30:56.531705 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jul 6 23:30:56.531892 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 6 23:30:56.539474 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:30:56.545454 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 6 23:30:56.549321 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:30:56.693468 kernel: hv_netvsc 6045bdde-ea86-6045-bdde-ea866045bdde eth0: VF slot 1 added Jul 6 23:30:56.708398 kernel: hv_vmbus: registering driver hv_pci Jul 6 23:30:56.716491 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (444) Jul 6 23:30:56.720992 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 6 23:30:56.724423 kernel: hv_pci 2a46175a-b869-47d6-af42-deab4c12ae75: PCI VMBus probing: Using version 0x10004 Jul 6 23:30:56.730497 kernel: hv_pci 2a46175a-b869-47d6-af42-deab4c12ae75: PCI host bridge to bus b869:00 Jul 6 23:30:56.749475 kernel: pci_bus b869:00: root bus resource [mem 0xfe0000000-0xfe00fffff window] Jul 6 23:30:56.759459 kernel: BTRFS: device fsid 25bdfe43-d649-4808-8940-e1722efc7a2e devid 1 transid 37 /dev/sda3 scanned by (udev-worker) (451) Jul 6 23:30:56.764454 kernel: pci_bus b869:00: No busn resource found for root bus, will use [bus 00-ff] Jul 6 23:30:56.783735 kernel: pci b869:00:02.0: [15b3:1016] type 00 class 0x020000 Jul 6 23:30:56.783839 kernel: pci b869:00:02.0: reg 0x10: [mem 0xfe0000000-0xfe00fffff 64bit pref] Jul 6 23:30:56.783870 kernel: pci b869:00:02.0: enabling Extended Tags Jul 6 23:30:56.794877 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 6 23:30:56.830606 kernel: pci b869:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at b869:00:02.0 (capable of 63.008 Gb/s with 8.0 GT/s PCIe x8 link) Jul 6 23:30:56.830823 kernel: pci_bus b869:00: busn_res: [bus 00-ff] end is updated to 00 Jul 6 23:30:56.831299 kernel: pci b869:00:02.0: BAR 0: assigned [mem 0xfe0000000-0xfe00fffff 64bit pref] Jul 6 23:30:56.834818 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 6 23:30:56.858299 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 6 23:30:56.874139 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 6 23:30:56.892630 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 6 23:30:57.147291 kernel: mlx5_core b869:00:02.0: enabling device (0000 -> 0002) Jul 6 23:30:57.158914 kernel: mlx5_core b869:00:02.0: firmware version: 14.30.5000 Jul 6 23:30:57.386975 kernel: hv_netvsc 6045bdde-ea86-6045-bdde-ea866045bdde eth0: VF registering: eth1 Jul 6 23:30:57.387355 kernel: mlx5_core b869:00:02.0 eth1: joined to eth0 Jul 6 23:30:57.392492 kernel: mlx5_core b869:00:02.0: MLX5E: StrdRq(0) RqSz(1024) StrdSz(256) RxCqeCmprss(0 basic) Jul 6 23:30:57.403462 kernel: mlx5_core b869:00:02.0 enP47209s1: renamed from eth1 Jul 6 23:30:57.925461 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:30:57.925529 disk-uuid[590]: The operation has completed successfully. Jul 6 23:30:58.020420 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 6 23:30:58.020574 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 6 23:30:58.085830 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 6 23:30:58.096417 sh[689]: Success Jul 6 23:30:58.120574 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Jul 6 23:30:58.210980 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 6 23:30:58.230577 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 6 23:30:58.236313 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 6 23:30:58.263582 kernel: BTRFS info (device dm-0): first mount of filesystem 25bdfe43-d649-4808-8940-e1722efc7a2e Jul 6 23:30:58.266073 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:30:58.274931 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jul 6 23:30:58.281227 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jul 6 23:30:58.283891 kernel: BTRFS info (device dm-0): using free space tree Jul 6 23:30:58.338786 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 6 23:30:58.344165 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 6 23:30:58.354735 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 6 23:30:58.360620 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 6 23:30:58.395639 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:30:58.395742 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:30:58.398346 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:30:58.416461 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:30:58.423486 kernel: BTRFS info (device sda6): last unmount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:30:58.427480 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 6 23:30:58.438614 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 6 23:30:58.467644 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:30:58.478561 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:30:58.507641 systemd-networkd[870]: lo: Link UP Jul 6 23:30:58.507651 systemd-networkd[870]: lo: Gained carrier Jul 6 23:30:58.509911 systemd-networkd[870]: Enumeration completed Jul 6 23:30:58.510144 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:30:58.513622 systemd[1]: Reached target network.target - Network. Jul 6 23:30:58.515600 systemd-networkd[870]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:30:58.515606 systemd-networkd[870]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:30:58.584458 kernel: mlx5_core b869:00:02.0 enP47209s1: Link up Jul 6 23:30:58.616177 kernel: hv_netvsc 6045bdde-ea86-6045-bdde-ea866045bdde eth0: Data path switched to VF: enP47209s1 Jul 6 23:30:58.615712 systemd-networkd[870]: enP47209s1: Link UP Jul 6 23:30:58.615829 systemd-networkd[870]: eth0: Link UP Jul 6 23:30:58.616031 systemd-networkd[870]: eth0: Gained carrier Jul 6 23:30:58.616046 systemd-networkd[870]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:30:58.627750 systemd-networkd[870]: enP47209s1: Gained carrier Jul 6 23:30:58.665520 systemd-networkd[870]: eth0: DHCPv4 address 10.200.8.24/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 6 23:30:58.725789 ignition[824]: Ignition 2.20.0 Jul 6 23:30:58.725802 ignition[824]: Stage: fetch-offline Jul 6 23:30:58.725845 ignition[824]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:30:58.728305 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:30:58.725856 ignition[824]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:30:58.725960 ignition[824]: parsed url from cmdline: "" Jul 6 23:30:58.725965 ignition[824]: no config URL provided Jul 6 23:30:58.725972 ignition[824]: reading system config file "/usr/lib/ignition/user.ign" Jul 6 23:30:58.725981 ignition[824]: no config at "/usr/lib/ignition/user.ign" Jul 6 23:30:58.725989 ignition[824]: failed to fetch config: resource requires networking Jul 6 23:30:58.748121 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 6 23:30:58.726246 ignition[824]: Ignition finished successfully Jul 6 23:30:58.761741 ignition[879]: Ignition 2.20.0 Jul 6 23:30:58.761750 ignition[879]: Stage: fetch Jul 6 23:30:58.761990 ignition[879]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:30:58.762005 ignition[879]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:30:58.762110 ignition[879]: parsed url from cmdline: "" Jul 6 23:30:58.762115 ignition[879]: no config URL provided Jul 6 23:30:58.762120 ignition[879]: reading system config file "/usr/lib/ignition/user.ign" Jul 6 23:30:58.762128 ignition[879]: no config at "/usr/lib/ignition/user.ign" Jul 6 23:30:58.762162 ignition[879]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 6 23:30:58.851030 ignition[879]: GET result: OK Jul 6 23:30:58.851131 ignition[879]: config has been read from IMDS userdata Jul 6 23:30:58.853848 unknown[879]: fetched base config from "system" Jul 6 23:30:58.851151 ignition[879]: parsing config with SHA512: 4d7212a97e9ec09889dc9ef55a3753f04ffd8919c88cc0f9443785fac329ad880b74a47ec12853c621701aba8f296a8bc74080ad24e2e062bd35a6e0a8eb475b Jul 6 23:30:58.853855 unknown[879]: fetched base config from "system" Jul 6 23:30:58.854067 ignition[879]: fetch: fetch complete Jul 6 23:30:58.853860 unknown[879]: fetched user config from "azure" Jul 6 23:30:58.854071 ignition[879]: fetch: fetch passed Jul 6 23:30:58.856057 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 6 23:30:58.854106 ignition[879]: Ignition finished successfully Jul 6 23:30:58.883639 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 6 23:30:58.901947 ignition[886]: Ignition 2.20.0 Jul 6 23:30:58.901959 ignition[886]: Stage: kargs Jul 6 23:30:58.902175 ignition[886]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:30:58.902189 ignition[886]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:30:58.905898 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 6 23:30:58.902907 ignition[886]: kargs: kargs passed Jul 6 23:30:58.902955 ignition[886]: Ignition finished successfully Jul 6 23:30:58.932383 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 6 23:30:58.955522 ignition[892]: Ignition 2.20.0 Jul 6 23:30:58.955535 ignition[892]: Stage: disks Jul 6 23:30:58.959121 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 6 23:30:58.955797 ignition[892]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:30:58.963769 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 6 23:30:58.955814 ignition[892]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:30:58.968103 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 6 23:30:58.958224 ignition[892]: disks: disks passed Jul 6 23:30:58.981836 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:30:58.958273 ignition[892]: Ignition finished successfully Jul 6 23:30:58.988448 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:30:58.992599 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:30:59.016845 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 6 23:30:59.045322 systemd-fsck[900]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Jul 6 23:30:59.051651 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 6 23:30:59.063828 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 6 23:30:59.169524 kernel: EXT4-fs (sda9): mounted filesystem daab0c95-3783-44c0-bef8-9d61a5c53c14 r/w with ordered data mode. Quota mode: none. Jul 6 23:30:59.170423 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 6 23:30:59.176161 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 6 23:30:59.206602 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:30:59.215706 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 6 23:30:59.234455 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (912) Jul 6 23:30:59.241035 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 6 23:30:59.252803 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:30:59.252839 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:30:59.252859 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:30:59.256971 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 6 23:30:59.262731 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:30:59.274557 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:30:59.273413 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:30:59.278698 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 6 23:30:59.285620 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 6 23:30:59.449470 coreos-metadata[914]: Jul 06 23:30:59.449 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 6 23:30:59.456217 coreos-metadata[914]: Jul 06 23:30:59.456 INFO Fetch successful Jul 6 23:30:59.459617 coreos-metadata[914]: Jul 06 23:30:59.456 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 6 23:30:59.466675 coreos-metadata[914]: Jul 06 23:30:59.465 INFO Fetch successful Jul 6 23:30:59.472040 coreos-metadata[914]: Jul 06 23:30:59.471 INFO wrote hostname ci-4230.2.1-a-ea411ff846 to /sysroot/etc/hostname Jul 6 23:30:59.473803 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 6 23:30:59.483175 initrd-setup-root[942]: cut: /sysroot/etc/passwd: No such file or directory Jul 6 23:30:59.498471 initrd-setup-root[950]: cut: /sysroot/etc/group: No such file or directory Jul 6 23:30:59.503799 initrd-setup-root[957]: cut: /sysroot/etc/shadow: No such file or directory Jul 6 23:30:59.514127 initrd-setup-root[964]: cut: /sysroot/etc/gshadow: No such file or directory Jul 6 23:30:59.775597 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 6 23:30:59.788665 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 6 23:30:59.795322 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 6 23:30:59.809335 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 6 23:30:59.816350 kernel: BTRFS info (device sda6): last unmount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:30:59.835460 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 6 23:30:59.846166 ignition[1032]: INFO : Ignition 2.20.0 Jul 6 23:30:59.846166 ignition[1032]: INFO : Stage: mount Jul 6 23:30:59.853340 ignition[1032]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:30:59.853340 ignition[1032]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:30:59.853340 ignition[1032]: INFO : mount: mount passed Jul 6 23:30:59.853340 ignition[1032]: INFO : Ignition finished successfully Jul 6 23:30:59.848121 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 6 23:30:59.863009 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 6 23:30:59.871630 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:30:59.886598 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1043) Jul 6 23:30:59.886647 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:30:59.891656 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:30:59.896566 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:30:59.903462 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:30:59.905410 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:30:59.935630 ignition[1059]: INFO : Ignition 2.20.0 Jul 6 23:30:59.935630 ignition[1059]: INFO : Stage: files Jul 6 23:30:59.942837 ignition[1059]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:30:59.942837 ignition[1059]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:30:59.942837 ignition[1059]: DEBUG : files: compiled without relabeling support, skipping Jul 6 23:30:59.942837 ignition[1059]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 6 23:30:59.942837 ignition[1059]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 6 23:30:59.962059 ignition[1059]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 6 23:30:59.965749 ignition[1059]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 6 23:30:59.969417 ignition[1059]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 6 23:30:59.966215 unknown[1059]: wrote ssh authorized keys file for user: core Jul 6 23:30:59.975137 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 6 23:30:59.979884 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 6 23:30:59.979884 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:30:59.979884 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:30:59.979884 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Jul 6 23:30:59.979884 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Jul 6 23:30:59.979884 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Jul 6 23:30:59.979884 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-x86-64.raw: attempt #1 Jul 6 23:30:59.992637 systemd-networkd[870]: enP47209s1: Gained IPv6LL Jul 6 23:31:00.053656 systemd-networkd[870]: eth0: Gained IPv6LL Jul 6 23:31:00.671695 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 6 23:31:01.011074 ignition[1059]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Jul 6 23:31:01.019934 ignition[1059]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:31:01.019934 ignition[1059]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:31:01.019934 ignition[1059]: INFO : files: files passed Jul 6 23:31:01.019934 ignition[1059]: INFO : Ignition finished successfully Jul 6 23:31:01.017767 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 6 23:31:01.042938 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 6 23:31:01.054847 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 6 23:31:01.065212 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 6 23:31:01.065352 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 6 23:31:01.087767 initrd-setup-root-after-ignition[1088]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:31:01.096338 initrd-setup-root-after-ignition[1092]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:31:01.095918 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:31:01.111807 initrd-setup-root-after-ignition[1088]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:31:01.102785 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 6 23:31:01.120910 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 6 23:31:01.159100 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 6 23:31:01.159238 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 6 23:31:01.166406 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 6 23:31:01.175358 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 6 23:31:01.181693 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 6 23:31:01.189726 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 6 23:31:01.207656 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:31:01.220756 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 6 23:31:01.239313 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:31:01.240634 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:31:01.246397 systemd[1]: Stopped target timers.target - Timer Units. Jul 6 23:31:01.247567 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 6 23:31:01.247708 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:31:01.248240 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 6 23:31:01.248989 systemd[1]: Stopped target basic.target - Basic System. Jul 6 23:31:01.249593 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 6 23:31:01.249903 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:31:01.250341 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 6 23:31:01.254896 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 6 23:31:01.256036 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:31:01.256403 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 6 23:31:01.257167 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 6 23:31:01.257944 systemd[1]: Stopped target swap.target - Swaps. Jul 6 23:31:01.258356 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 6 23:31:01.258540 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:31:01.259216 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:31:01.260034 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:31:01.260347 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 6 23:31:01.295735 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:31:01.310480 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 6 23:31:01.408109 ignition[1112]: INFO : Ignition 2.20.0 Jul 6 23:31:01.408109 ignition[1112]: INFO : Stage: umount Jul 6 23:31:01.408109 ignition[1112]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:31:01.408109 ignition[1112]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:31:01.310616 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 6 23:31:01.420836 ignition[1112]: INFO : umount: umount passed Jul 6 23:31:01.420836 ignition[1112]: INFO : Ignition finished successfully Jul 6 23:31:01.323787 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 6 23:31:01.343688 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:31:01.349743 systemd[1]: ignition-files.service: Deactivated successfully. Jul 6 23:31:01.349906 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 6 23:31:01.354861 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 6 23:31:01.355100 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 6 23:31:01.387946 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 6 23:31:01.396517 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 6 23:31:01.396777 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:31:01.420612 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 6 23:31:01.431285 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 6 23:31:01.431601 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:31:01.438702 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 6 23:31:01.438857 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:31:01.454251 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 6 23:31:01.455776 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 6 23:31:01.455927 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 6 23:31:01.480192 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 6 23:31:01.484557 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 6 23:31:01.511695 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 6 23:31:01.511831 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 6 23:31:01.521066 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 6 23:31:01.521162 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 6 23:31:01.529947 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 6 23:31:01.530070 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 6 23:31:01.534939 systemd[1]: Stopped target network.target - Network. Jul 6 23:31:01.539678 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 6 23:31:01.539778 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:31:01.545167 systemd[1]: Stopped target paths.target - Path Units. Jul 6 23:31:01.550240 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 6 23:31:01.558005 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:31:01.565214 systemd[1]: Stopped target slices.target - Slice Units. Jul 6 23:31:01.567409 systemd[1]: Stopped target sockets.target - Socket Units. Jul 6 23:31:01.578578 systemd[1]: iscsid.socket: Deactivated successfully. Jul 6 23:31:01.578637 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:31:01.585200 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 6 23:31:01.585258 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:31:01.590312 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 6 23:31:01.590381 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 6 23:31:01.595080 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 6 23:31:01.597467 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 6 23:31:01.609683 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 6 23:31:01.612170 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 6 23:31:01.624932 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 6 23:31:01.625053 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 6 23:31:01.631341 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 6 23:31:01.631612 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 6 23:31:01.631711 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 6 23:31:01.639886 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 6 23:31:01.641158 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 6 23:31:01.641238 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:31:01.661560 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 6 23:31:01.663797 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 6 23:31:01.663865 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:31:01.665254 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 6 23:31:01.665306 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:31:01.672096 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 6 23:31:01.672171 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 6 23:31:01.683240 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 6 23:31:01.686224 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:31:01.706376 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:31:01.711122 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 6 23:31:01.711199 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:31:01.736139 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 6 23:31:01.736320 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:31:01.742185 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 6 23:31:01.742235 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 6 23:31:01.747790 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 6 23:31:01.747837 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:31:01.760219 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 6 23:31:01.760315 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:31:01.768638 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 6 23:31:01.768730 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 6 23:31:01.782994 kernel: hv_netvsc 6045bdde-ea86-6045-bdde-ea866045bdde eth0: Data path switched from VF: enP47209s1 Jul 6 23:31:01.778969 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 6 23:31:01.779044 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:31:01.793723 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 6 23:31:01.799577 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 6 23:31:01.799675 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:31:01.806091 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:31:01.806173 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:31:01.826544 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 6 23:31:01.826624 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:31:01.826988 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 6 23:31:01.827072 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 6 23:31:01.832691 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 6 23:31:01.832836 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 6 23:31:01.838677 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 6 23:31:01.838776 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 6 23:31:01.847352 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 6 23:31:01.850637 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 6 23:31:01.850735 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 6 23:31:01.877699 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 6 23:31:01.898543 systemd[1]: Switching root. Jul 6 23:31:01.940441 systemd-journald[177]: Received SIGTERM from PID 1 (systemd). Jul 6 23:31:01.940521 systemd-journald[177]: Journal stopped Jul 6 23:31:04.213800 kernel: SELinux: policy capability network_peer_controls=1 Jul 6 23:31:04.213832 kernel: SELinux: policy capability open_perms=1 Jul 6 23:31:04.213843 kernel: SELinux: policy capability extended_socket_class=1 Jul 6 23:31:04.213855 kernel: SELinux: policy capability always_check_network=0 Jul 6 23:31:04.213864 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 6 23:31:04.213875 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 6 23:31:04.213884 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 6 23:31:04.213896 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 6 23:31:04.213908 kernel: audit: type=1403 audit(1751844662.630:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 6 23:31:04.213921 systemd[1]: Successfully loaded SELinux policy in 64.083ms. Jul 6 23:31:04.213934 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 13.067ms. Jul 6 23:31:04.213950 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 6 23:31:04.213967 systemd[1]: Detected virtualization microsoft. Jul 6 23:31:04.213982 systemd[1]: Detected architecture x86-64. Jul 6 23:31:04.214003 systemd[1]: Detected first boot. Jul 6 23:31:04.214027 systemd[1]: Hostname set to . Jul 6 23:31:04.214047 systemd[1]: Initializing machine ID from random generator. Jul 6 23:31:04.214065 zram_generator::config[1156]: No configuration found. Jul 6 23:31:04.214084 kernel: Guest personality initialized and is inactive Jul 6 23:31:04.214110 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 6 23:31:04.214130 kernel: Initialized host personality Jul 6 23:31:04.214146 kernel: NET: Registered PF_VSOCK protocol family Jul 6 23:31:04.214165 systemd[1]: Populated /etc with preset unit settings. Jul 6 23:31:04.214187 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 6 23:31:04.214208 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 6 23:31:04.214229 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 6 23:31:04.214249 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 6 23:31:04.214270 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 6 23:31:04.214294 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 6 23:31:04.214313 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 6 23:31:04.214332 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 6 23:31:04.214353 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 6 23:31:04.214378 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 6 23:31:04.214403 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 6 23:31:04.214421 systemd[1]: Created slice user.slice - User and Session Slice. Jul 6 23:31:04.214456 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:31:04.214481 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:31:04.214502 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 6 23:31:04.214525 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 6 23:31:04.214547 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 6 23:31:04.214582 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:31:04.214607 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 6 23:31:04.214629 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:31:04.214657 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 6 23:31:04.214677 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 6 23:31:04.214695 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 6 23:31:04.214716 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 6 23:31:04.214739 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:31:04.214762 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:31:04.214779 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:31:04.214807 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:31:04.214825 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 6 23:31:04.214839 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 6 23:31:04.214856 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 6 23:31:04.214875 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:31:04.214898 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:31:04.214916 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:31:04.214935 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 6 23:31:04.214953 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 6 23:31:04.214971 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 6 23:31:04.214990 systemd[1]: Mounting media.mount - External Media Directory... Jul 6 23:31:04.215007 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:31:04.215025 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 6 23:31:04.215045 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 6 23:31:04.215062 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 6 23:31:04.215080 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 6 23:31:04.215095 systemd[1]: Reached target machines.target - Containers. Jul 6 23:31:04.215111 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 6 23:31:04.215129 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:31:04.215146 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:31:04.215163 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 6 23:31:04.215179 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:31:04.215199 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:31:04.215215 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:31:04.215233 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 6 23:31:04.215249 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:31:04.215265 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 6 23:31:04.215283 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 6 23:31:04.215302 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 6 23:31:04.215320 kernel: fuse: init (API version 7.39) Jul 6 23:31:04.215342 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 6 23:31:04.215360 systemd[1]: Stopped systemd-fsck-usr.service. Jul 6 23:31:04.215377 kernel: loop: module loaded Jul 6 23:31:04.215395 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 6 23:31:04.215412 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:31:04.215429 kernel: ACPI: bus type drm_connector registered Jul 6 23:31:04.215469 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:31:04.215485 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 6 23:31:04.215504 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 6 23:31:04.215562 systemd-journald[1250]: Collecting audit messages is disabled. Jul 6 23:31:04.215599 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 6 23:31:04.215619 systemd-journald[1250]: Journal started Jul 6 23:31:04.215652 systemd-journald[1250]: Runtime Journal (/run/log/journal/09f7aa66c38d4afd8c1e2f5f57e675ee) is 8M, max 158.8M, 150.8M free. Jul 6 23:31:03.625354 systemd[1]: Queued start job for default target multi-user.target. Jul 6 23:31:03.639482 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jul 6 23:31:03.639912 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 6 23:31:04.235512 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:31:04.245596 systemd[1]: verity-setup.service: Deactivated successfully. Jul 6 23:31:04.245693 systemd[1]: Stopped verity-setup.service. Jul 6 23:31:04.258030 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:31:04.264056 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:31:04.266604 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 6 23:31:04.269902 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 6 23:31:04.272841 systemd[1]: Mounted media.mount - External Media Directory. Jul 6 23:31:04.277747 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 6 23:31:04.284919 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 6 23:31:04.288778 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 6 23:31:04.292251 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 6 23:31:04.296096 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:31:04.300134 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 6 23:31:04.300718 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 6 23:31:04.304570 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:31:04.304886 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:31:04.308731 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:31:04.309104 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:31:04.312704 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:31:04.313089 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:31:04.317191 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 6 23:31:04.317718 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 6 23:31:04.321426 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:31:04.321883 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:31:04.325564 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:31:04.329224 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 6 23:31:04.333365 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 6 23:31:04.337311 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 6 23:31:04.349700 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:31:04.359760 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 6 23:31:04.370563 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 6 23:31:04.376773 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 6 23:31:04.379907 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 6 23:31:04.379971 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:31:04.383849 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 6 23:31:04.388089 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 6 23:31:04.396020 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 6 23:31:04.399380 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:31:04.409604 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 6 23:31:04.417646 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 6 23:31:04.421654 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:31:04.422958 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 6 23:31:04.425840 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:31:04.429583 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:31:04.435307 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 6 23:31:04.441648 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 6 23:31:04.457517 systemd-journald[1250]: Time spent on flushing to /var/log/journal/09f7aa66c38d4afd8c1e2f5f57e675ee is 27.293ms for 947 entries. Jul 6 23:31:04.457517 systemd-journald[1250]: System Journal (/var/log/journal/09f7aa66c38d4afd8c1e2f5f57e675ee) is 8M, max 2.6G, 2.6G free. Jul 6 23:31:04.547691 systemd-journald[1250]: Received client request to flush runtime journal. Jul 6 23:31:04.547751 kernel: loop0: detected capacity change from 0 to 229808 Jul 6 23:31:04.547774 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 6 23:31:04.448644 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jul 6 23:31:04.464992 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 6 23:31:04.469626 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 6 23:31:04.473517 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 6 23:31:04.476880 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 6 23:31:04.485755 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 6 23:31:04.502674 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 6 23:31:04.517652 udevadm[1301]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 6 23:31:04.557782 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 6 23:31:04.573449 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:31:04.600200 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 6 23:31:04.612661 kernel: loop1: detected capacity change from 0 to 138176 Jul 6 23:31:04.617934 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:31:04.621559 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 6 23:31:04.641897 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 6 23:31:04.666863 systemd-tmpfiles[1316]: ACLs are not supported, ignoring. Jul 6 23:31:04.666889 systemd-tmpfiles[1316]: ACLs are not supported, ignoring. Jul 6 23:31:04.672878 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:31:04.761499 kernel: loop2: detected capacity change from 0 to 28272 Jul 6 23:31:04.879503 kernel: loop3: detected capacity change from 0 to 147912 Jul 6 23:31:05.009529 kernel: loop4: detected capacity change from 0 to 229808 Jul 6 23:31:05.020690 kernel: loop5: detected capacity change from 0 to 138176 Jul 6 23:31:05.039459 kernel: loop6: detected capacity change from 0 to 28272 Jul 6 23:31:05.050472 kernel: loop7: detected capacity change from 0 to 147912 Jul 6 23:31:05.094279 (sd-merge)[1323]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-azure'. Jul 6 23:31:05.095043 (sd-merge)[1323]: Merged extensions into '/usr'. Jul 6 23:31:05.102583 systemd[1]: Reload requested from client PID 1299 ('systemd-sysext') (unit systemd-sysext.service)... Jul 6 23:31:05.102601 systemd[1]: Reloading... Jul 6 23:31:05.250576 zram_generator::config[1354]: No configuration found. Jul 6 23:31:05.465728 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:31:05.546033 systemd[1]: Reloading finished in 442 ms. Jul 6 23:31:05.564422 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 6 23:31:05.580664 systemd[1]: Starting ensure-sysext.service... Jul 6 23:31:05.588424 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:31:05.615650 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 6 23:31:05.634671 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:31:05.642276 systemd-tmpfiles[1410]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 6 23:31:05.642591 systemd-tmpfiles[1410]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 6 23:31:05.643374 systemd-tmpfiles[1410]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 6 23:31:05.643509 systemd[1]: Reload requested from client PID 1409 ('systemctl') (unit ensure-sysext.service)... Jul 6 23:31:05.643531 systemd[1]: Reloading... Jul 6 23:31:05.643775 systemd-tmpfiles[1410]: ACLs are not supported, ignoring. Jul 6 23:31:05.643860 systemd-tmpfiles[1410]: ACLs are not supported, ignoring. Jul 6 23:31:05.654305 systemd-tmpfiles[1410]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:31:05.654324 systemd-tmpfiles[1410]: Skipping /boot Jul 6 23:31:05.693063 systemd-tmpfiles[1410]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:31:05.693077 systemd-tmpfiles[1410]: Skipping /boot Jul 6 23:31:05.700069 systemd-udevd[1413]: Using default interface naming scheme 'v255'. Jul 6 23:31:05.763482 zram_generator::config[1437]: No configuration found. Jul 6 23:31:06.152967 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:31:06.162493 kernel: hv_vmbus: registering driver hv_balloon Jul 6 23:31:06.165463 kernel: hv_vmbus: registering driver hyperv_fb Jul 6 23:31:06.176660 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 6 23:31:06.176764 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 6 23:31:06.195572 kernel: mousedev: PS/2 mouse device common for all mice Jul 6 23:31:06.206457 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 6 23:31:06.222707 kernel: Console: switching to colour dummy device 80x25 Jul 6 23:31:06.239669 kernel: Console: switching to colour frame buffer device 128x48 Jul 6 23:31:06.403504 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1468) Jul 6 23:31:06.510963 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 6 23:31:06.512771 systemd[1]: Reloading finished in 868 ms. Jul 6 23:31:06.526347 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:31:06.534312 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:31:06.705617 kernel: kvm_intel: Using Hyper-V Enlightened VMCS Jul 6 23:31:06.699622 systemd[1]: Finished ensure-sysext.service. Jul 6 23:31:06.713679 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Jul 6 23:31:06.741298 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jul 6 23:31:06.755015 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 6 23:31:06.765372 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:31:06.776691 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 6 23:31:06.784651 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 6 23:31:06.788789 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:31:06.790868 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jul 6 23:31:06.794976 ldconfig[1294]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 6 23:31:06.795640 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:31:06.803614 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:31:06.811998 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:31:06.818148 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:31:06.821172 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:31:06.825737 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 6 23:31:06.828801 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 6 23:31:06.842699 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 6 23:31:06.854936 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:31:06.860494 lvm[1605]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 6 23:31:06.870615 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:31:06.877275 systemd[1]: Reached target time-set.target - System Time Set. Jul 6 23:31:06.888636 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 6 23:31:06.894301 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:31:06.897733 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:31:06.900501 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 6 23:31:06.912969 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jul 6 23:31:06.921052 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:31:06.921323 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:31:06.925023 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:31:06.925234 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:31:06.928369 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:31:06.928628 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:31:06.930003 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:31:06.930251 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:31:06.943154 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:31:06.968832 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jul 6 23:31:06.970178 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:31:06.970258 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:31:06.974671 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 6 23:31:06.978494 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 6 23:31:06.979841 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 6 23:31:06.991460 lvm[1639]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 6 23:31:07.018067 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 6 23:31:07.028655 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 6 23:31:07.034288 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jul 6 23:31:07.043292 augenrules[1649]: No rules Jul 6 23:31:07.045099 systemd[1]: audit-rules.service: Deactivated successfully. Jul 6 23:31:07.045608 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 6 23:31:07.066356 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 6 23:31:07.072173 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 6 23:31:07.135308 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 6 23:31:07.144224 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 6 23:31:07.204235 systemd-networkd[1617]: lo: Link UP Jul 6 23:31:07.205170 systemd-networkd[1617]: lo: Gained carrier Jul 6 23:31:07.209701 systemd-networkd[1617]: Enumeration completed Jul 6 23:31:07.210169 systemd-networkd[1617]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:31:07.210175 systemd-networkd[1617]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:31:07.210582 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:31:07.219642 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 6 23:31:07.226706 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 6 23:31:07.228884 systemd-resolved[1618]: Positive Trust Anchors: Jul 6 23:31:07.229176 systemd-resolved[1618]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:31:07.229262 systemd-resolved[1618]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 6 23:31:07.234112 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:31:07.234783 systemd-resolved[1618]: Using system hostname 'ci-4230.2.1-a-ea411ff846'. Jul 6 23:31:07.280457 kernel: mlx5_core b869:00:02.0 enP47209s1: Link up Jul 6 23:31:07.300615 kernel: hv_netvsc 6045bdde-ea86-6045-bdde-ea866045bdde eth0: Data path switched to VF: enP47209s1 Jul 6 23:31:07.302631 systemd-networkd[1617]: enP47209s1: Link UP Jul 6 23:31:07.302835 systemd-networkd[1617]: eth0: Link UP Jul 6 23:31:07.302842 systemd-networkd[1617]: eth0: Gained carrier Jul 6 23:31:07.302869 systemd-networkd[1617]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:31:07.304586 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:31:07.310284 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 6 23:31:07.311853 systemd-networkd[1617]: enP47209s1: Gained carrier Jul 6 23:31:07.314451 systemd[1]: Reached target network.target - Network. Jul 6 23:31:07.317029 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:31:07.320032 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:31:07.323266 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 6 23:31:07.326470 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 6 23:31:07.330829 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 6 23:31:07.334083 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 6 23:31:07.338035 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 6 23:31:07.340779 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 6 23:31:07.340826 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:31:07.342760 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:31:07.343511 systemd-networkd[1617]: eth0: DHCPv4 address 10.200.8.24/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 6 23:31:07.346115 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 6 23:31:07.350100 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 6 23:31:07.355370 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 6 23:31:07.358461 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 6 23:31:07.361177 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 6 23:31:07.375259 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 6 23:31:07.378220 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 6 23:31:07.381520 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 6 23:31:07.384012 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:31:07.386390 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:31:07.388394 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:31:07.388427 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:31:07.397557 systemd[1]: Starting chronyd.service - NTP client/server... Jul 6 23:31:07.402591 systemd[1]: Starting containerd.service - containerd container runtime... Jul 6 23:31:07.418826 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 6 23:31:07.424208 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 6 23:31:07.436460 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 6 23:31:07.441303 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 6 23:31:07.443808 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Jul 6 23:31:07.443875 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Jul 6 23:31:07.448605 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 6 23:31:07.451127 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 6 23:31:07.459730 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 6 23:31:07.461511 jq[1681]: false Jul 6 23:31:07.472726 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 6 23:31:07.481641 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 6 23:31:07.483015 KVP[1683]: KVP starting; pid is:1683 Jul 6 23:31:07.492669 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 6 23:31:07.497389 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 6 23:31:07.498175 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 6 23:31:07.499393 (chronyd)[1674]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 6 23:31:07.505907 systemd[1]: Starting update-engine.service - Update Engine... Jul 6 23:31:07.514469 kernel: hv_utils: KVP IC version 4.0 Jul 6 23:31:07.515473 KVP[1683]: KVP LIC Version: 3.1 Jul 6 23:31:07.519676 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 6 23:31:07.531141 chronyd[1692]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 6 23:31:07.536918 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 6 23:31:07.537668 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 6 23:31:07.538814 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 6 23:31:07.539073 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 6 23:31:07.554547 chronyd[1692]: Timezone right/UTC failed leap second check, ignoring Jul 6 23:31:07.554767 chronyd[1692]: Loaded seccomp filter (level 2) Jul 6 23:31:07.562893 systemd[1]: Started chronyd.service - NTP client/server. Jul 6 23:31:07.570059 extend-filesystems[1682]: Found loop4 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found loop5 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found loop6 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found loop7 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda1 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda2 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda3 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found usr Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda4 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda6 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda7 Jul 6 23:31:07.570059 extend-filesystems[1682]: Found sda9 Jul 6 23:31:07.570059 extend-filesystems[1682]: Checking size of /dev/sda9 Jul 6 23:31:07.640408 jq[1690]: true Jul 6 23:31:07.616065 (ntainerd)[1706]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 6 23:31:07.629993 dbus-daemon[1677]: [system] SELinux support is enabled Jul 6 23:31:07.630222 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 6 23:31:07.641476 jq[1704]: true Jul 6 23:31:07.655336 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Jul 6 23:31:07.655392 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 6 23:31:07.660692 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 6 23:31:07.660734 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 6 23:31:07.673878 systemd[1]: motdgen.service: Deactivated successfully. Jul 6 23:31:07.674138 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 6 23:31:07.696908 update_engine[1688]: I20250706 23:31:07.696794 1688 main.cc:92] Flatcar Update Engine starting Jul 6 23:31:07.699613 extend-filesystems[1682]: Old size kept for /dev/sda9 Jul 6 23:31:07.702614 extend-filesystems[1682]: Found sr0 Jul 6 23:31:07.712200 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 6 23:31:07.714637 update_engine[1688]: I20250706 23:31:07.713018 1688 update_check_scheduler.cc:74] Next update check in 8m4s Jul 6 23:31:07.713296 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 6 23:31:07.717826 systemd[1]: Started update-engine.service - Update Engine. Jul 6 23:31:07.728675 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 6 23:31:07.743885 systemd-logind[1687]: New seat seat0. Jul 6 23:31:07.752110 systemd-logind[1687]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 6 23:31:07.752339 systemd[1]: Started systemd-logind.service - User Login Management. Jul 6 23:31:07.817713 coreos-metadata[1676]: Jul 06 23:31:07.817 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 6 23:31:07.822390 coreos-metadata[1676]: Jul 06 23:31:07.821 INFO Fetch successful Jul 6 23:31:07.822390 coreos-metadata[1676]: Jul 06 23:31:07.822 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 6 23:31:07.840458 coreos-metadata[1676]: Jul 06 23:31:07.839 INFO Fetch successful Jul 6 23:31:07.841695 coreos-metadata[1676]: Jul 06 23:31:07.841 INFO Fetching http://168.63.129.16/machine/93a19bbe-e17c-4651-9047-2b949f5a5c7e/07b9dbb3%2Dd91a%2D48e8%2Da8c4%2Dab6f4c894240.%5Fci%2D4230.2.1%2Da%2Dea411ff846?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 6 23:31:07.849093 coreos-metadata[1676]: Jul 06 23:31:07.849 INFO Fetch successful Jul 6 23:31:07.849631 coreos-metadata[1676]: Jul 06 23:31:07.849 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 6 23:31:07.863460 bash[1737]: Updated "/home/core/.ssh/authorized_keys" Jul 6 23:31:07.865568 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 6 23:31:07.870810 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 6 23:31:07.871125 coreos-metadata[1676]: Jul 06 23:31:07.871 INFO Fetch successful Jul 6 23:31:07.887470 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1464) Jul 6 23:31:07.976073 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 6 23:31:07.986378 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 6 23:31:08.036676 locksmithd[1722]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 6 23:31:08.166866 sshd_keygen[1699]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 6 23:31:08.196998 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 6 23:31:08.204789 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 6 23:31:08.217356 systemd[1]: issuegen.service: Deactivated successfully. Jul 6 23:31:08.217642 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 6 23:31:08.230608 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 6 23:31:08.257295 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 6 23:31:08.269581 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 6 23:31:08.279466 containerd[1706]: time="2025-07-06T23:31:08.279018100Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jul 6 23:31:08.280962 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 6 23:31:08.284642 systemd[1]: Reached target getty.target - Login Prompts. Jul 6 23:31:08.305929 containerd[1706]: time="2025-07-06T23:31:08.305870000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:31:08.307678 containerd[1706]: time="2025-07-06T23:31:08.307633600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.95-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:31:08.307678 containerd[1706]: time="2025-07-06T23:31:08.307669000Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 6 23:31:08.307820 containerd[1706]: time="2025-07-06T23:31:08.307691700Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 6 23:31:08.307906 containerd[1706]: time="2025-07-06T23:31:08.307882400Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jul 6 23:31:08.307947 containerd[1706]: time="2025-07-06T23:31:08.307908900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308018 containerd[1706]: time="2025-07-06T23:31:08.307997100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308018 containerd[1706]: time="2025-07-06T23:31:08.308014900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308265 containerd[1706]: time="2025-07-06T23:31:08.308241000Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308265 containerd[1706]: time="2025-07-06T23:31:08.308260700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308349 containerd[1706]: time="2025-07-06T23:31:08.308280700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308349 containerd[1706]: time="2025-07-06T23:31:08.308295600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308428 containerd[1706]: time="2025-07-06T23:31:08.308405900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308662 containerd[1706]: time="2025-07-06T23:31:08.308634500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308833 containerd[1706]: time="2025-07-06T23:31:08.308809500Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:31:08.308833 containerd[1706]: time="2025-07-06T23:31:08.308828800Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 6 23:31:08.308969 containerd[1706]: time="2025-07-06T23:31:08.308947100Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 6 23:31:08.309031 containerd[1706]: time="2025-07-06T23:31:08.309014000Z" level=info msg="metadata content store policy set" policy=shared Jul 6 23:31:08.319921 containerd[1706]: time="2025-07-06T23:31:08.319865000Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 6 23:31:08.320058 containerd[1706]: time="2025-07-06T23:31:08.319940100Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 6 23:31:08.320058 containerd[1706]: time="2025-07-06T23:31:08.319962700Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jul 6 23:31:08.320058 containerd[1706]: time="2025-07-06T23:31:08.319982400Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jul 6 23:31:08.320058 containerd[1706]: time="2025-07-06T23:31:08.320000200Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 6 23:31:08.320199 containerd[1706]: time="2025-07-06T23:31:08.320187100Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 6 23:31:08.320546 containerd[1706]: time="2025-07-06T23:31:08.320514400Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 6 23:31:08.320701 containerd[1706]: time="2025-07-06T23:31:08.320678200Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jul 6 23:31:08.320701 containerd[1706]: time="2025-07-06T23:31:08.320701400Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jul 6 23:31:08.320804 containerd[1706]: time="2025-07-06T23:31:08.320721900Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jul 6 23:31:08.320804 containerd[1706]: time="2025-07-06T23:31:08.320741100Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320804 containerd[1706]: time="2025-07-06T23:31:08.320759300Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320804 containerd[1706]: time="2025-07-06T23:31:08.320776800Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320804 containerd[1706]: time="2025-07-06T23:31:08.320796700Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320816400Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320837500Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320855400Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320872400Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320901100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320922200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320940400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.320977 containerd[1706]: time="2025-07-06T23:31:08.320959800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.320977200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.320995900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321014000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321032200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321051700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321072600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321089600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321124400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321144700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321164400Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321192900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321231 containerd[1706]: time="2025-07-06T23:31:08.321219600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321238600Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321302300Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321327400Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321344000Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321358800Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321370700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321389900Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321404200Z" level=info msg="NRI interface is disabled by configuration." Jul 6 23:31:08.321795 containerd[1706]: time="2025-07-06T23:31:08.321419800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 6 23:31:08.322167 containerd[1706]: time="2025-07-06T23:31:08.321857800Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Jul 6 23:31:08.322167 containerd[1706]: time="2025-07-06T23:31:08.321926100Z" level=info msg="Connect containerd service" Jul 6 23:31:08.322167 containerd[1706]: time="2025-07-06T23:31:08.321982600Z" level=info msg="using legacy CRI server" Jul 6 23:31:08.322167 containerd[1706]: time="2025-07-06T23:31:08.321994100Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 6 23:31:08.322539 containerd[1706]: time="2025-07-06T23:31:08.322174000Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 6 23:31:08.322908 containerd[1706]: time="2025-07-06T23:31:08.322879600Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323035700Z" level=info msg="Start subscribing containerd event" Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323089800Z" level=info msg="Start recovering state" Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323174100Z" level=info msg="Start event monitor" Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323199700Z" level=info msg="Start snapshots syncer" Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323211500Z" level=info msg="Start cni network conf syncer for default" Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323220900Z" level=info msg="Start streaming server" Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323242900Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.323294700Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 6 23:31:08.324596 containerd[1706]: time="2025-07-06T23:31:08.324546400Z" level=info msg="containerd successfully booted in 0.046592s" Jul 6 23:31:08.323476 systemd[1]: Started containerd.service - containerd container runtime. Jul 6 23:31:08.565730 systemd-networkd[1617]: enP47209s1: Gained IPv6LL Jul 6 23:31:09.269852 systemd-networkd[1617]: eth0: Gained IPv6LL Jul 6 23:31:09.273427 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 6 23:31:09.277882 systemd[1]: Reached target network-online.target - Network is Online. Jul 6 23:31:09.292748 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:31:09.301318 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 6 23:31:09.309143 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 6 23:31:09.346388 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 6 23:31:09.374075 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 6 23:31:10.137343 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:10.143786 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 6 23:31:10.146773 (kubelet)[1847]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 6 23:31:10.148888 systemd[1]: Startup finished in 593ms (firmware) + 7.627s (loader) + 1.032s (kernel) + 7.630s (initrd) + 7.581s (userspace) = 24.465s. Jul 6 23:31:10.304455 waagent[1839]: 2025-07-06T23:31:10.302575Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Jul 6 23:31:10.304455 waagent[1839]: 2025-07-06T23:31:10.303860Z INFO Daemon Daemon OS: flatcar 4230.2.1 Jul 6 23:31:10.304847 waagent[1839]: 2025-07-06T23:31:10.304421Z INFO Daemon Daemon Python: 3.11.11 Jul 6 23:31:10.306877 waagent[1839]: 2025-07-06T23:31:10.306759Z INFO Daemon Daemon Run daemon Jul 6 23:31:10.308066 waagent[1839]: 2025-07-06T23:31:10.308023Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.1' Jul 6 23:31:10.308798 waagent[1839]: 2025-07-06T23:31:10.308757Z INFO Daemon Daemon Using waagent for provisioning Jul 6 23:31:10.309386 waagent[1839]: 2025-07-06T23:31:10.309348Z INFO Daemon Daemon Activate resource disk Jul 6 23:31:10.310099 waagent[1839]: 2025-07-06T23:31:10.310060Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 6 23:31:10.316043 waagent[1839]: 2025-07-06T23:31:10.315963Z INFO Daemon Daemon Found device: None Jul 6 23:31:10.316798 waagent[1839]: 2025-07-06T23:31:10.316750Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Jul 6 23:31:10.317199 waagent[1839]: 2025-07-06T23:31:10.317161Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Jul 6 23:31:10.318467 waagent[1839]: 2025-07-06T23:31:10.318407Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 6 23:31:10.319420 waagent[1839]: 2025-07-06T23:31:10.319382Z INFO Daemon Daemon Running default provisioning handler Jul 6 23:31:10.349380 waagent[1839]: 2025-07-06T23:31:10.349259Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Jul 6 23:31:10.358526 waagent[1839]: 2025-07-06T23:31:10.358243Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 6 23:31:10.361676 waagent[1839]: 2025-07-06T23:31:10.360249Z INFO Daemon Daemon cloud-init is enabled: False Jul 6 23:31:10.361676 waagent[1839]: 2025-07-06T23:31:10.361085Z INFO Daemon Daemon Copying ovf-env.xml Jul 6 23:31:10.409482 login[1818]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 6 23:31:10.411698 waagent[1839]: 2025-07-06T23:31:10.411594Z INFO Daemon Daemon Successfully mounted dvd Jul 6 23:31:10.411914 login[1819]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 6 23:31:10.432036 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 6 23:31:10.437791 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 6 23:31:10.443596 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 6 23:31:10.444794 waagent[1839]: 2025-07-06T23:31:10.444706Z INFO Daemon Daemon Detect protocol endpoint Jul 6 23:31:10.446583 waagent[1839]: 2025-07-06T23:31:10.446051Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 6 23:31:10.447123 waagent[1839]: 2025-07-06T23:31:10.447082Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 6 23:31:10.447530 waagent[1839]: 2025-07-06T23:31:10.447495Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 6 23:31:10.448245 waagent[1839]: 2025-07-06T23:31:10.448206Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 6 23:31:10.448946 waagent[1839]: 2025-07-06T23:31:10.448910Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 6 23:31:10.458450 systemd-logind[1687]: New session 1 of user core. Jul 6 23:31:10.473330 systemd-logind[1687]: New session 2 of user core. Jul 6 23:31:10.485780 waagent[1839]: 2025-07-06T23:31:10.482670Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 6 23:31:10.485780 waagent[1839]: 2025-07-06T23:31:10.485670Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 6 23:31:10.490785 waagent[1839]: 2025-07-06T23:31:10.487305Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 6 23:31:10.491990 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 6 23:31:10.501874 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 6 23:31:10.508358 (systemd)[1867]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 6 23:31:10.519019 systemd-logind[1687]: New session c1 of user core. Jul 6 23:31:10.659357 waagent[1839]: 2025-07-06T23:31:10.659243Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 6 23:31:10.666501 waagent[1839]: 2025-07-06T23:31:10.663975Z INFO Daemon Daemon Forcing an update of the goal state. Jul 6 23:31:10.673924 waagent[1839]: 2025-07-06T23:31:10.673845Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 6 23:31:10.693758 waagent[1839]: 2025-07-06T23:31:10.693686Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 6 23:31:10.697034 waagent[1839]: 2025-07-06T23:31:10.696965Z INFO Daemon Jul 6 23:31:10.698292 waagent[1839]: 2025-07-06T23:31:10.698236Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: df056119-75af-4680-a275-5856460fc6ed eTag: 3557810989614028067 source: Fabric] Jul 6 23:31:10.707177 waagent[1839]: 2025-07-06T23:31:10.707106Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 6 23:31:10.712448 waagent[1839]: 2025-07-06T23:31:10.711183Z INFO Daemon Jul 6 23:31:10.713012 waagent[1839]: 2025-07-06T23:31:10.712950Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 6 23:31:10.724452 waagent[1839]: 2025-07-06T23:31:10.721998Z INFO Daemon Daemon Downloading artifacts profile blob Jul 6 23:31:10.787259 systemd[1867]: Queued start job for default target default.target. Jul 6 23:31:10.791929 systemd[1867]: Created slice app.slice - User Application Slice. Jul 6 23:31:10.792113 systemd[1867]: Reached target paths.target - Paths. Jul 6 23:31:10.792327 systemd[1867]: Reached target timers.target - Timers. Jul 6 23:31:10.795552 systemd[1867]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 6 23:31:10.810824 systemd[1867]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 6 23:31:10.811154 systemd[1867]: Reached target sockets.target - Sockets. Jul 6 23:31:10.811481 systemd[1867]: Reached target basic.target - Basic System. Jul 6 23:31:10.811670 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 6 23:31:10.812390 systemd[1867]: Reached target default.target - Main User Target. Jul 6 23:31:10.812442 systemd[1867]: Startup finished in 279ms. Jul 6 23:31:10.820665 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 6 23:31:10.821986 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 6 23:31:10.896923 waagent[1839]: 2025-07-06T23:31:10.893148Z INFO Daemon Downloaded certificate {'thumbprint': 'B69B4F9E8B8A25C1FC5EF406F34613C947D420FB', 'hasPrivateKey': True} Jul 6 23:31:10.896923 waagent[1839]: 2025-07-06T23:31:10.895164Z INFO Daemon Fetch goal state completed Jul 6 23:31:10.929519 waagent[1839]: 2025-07-06T23:31:10.927258Z INFO Daemon Daemon Starting provisioning Jul 6 23:31:10.929519 waagent[1839]: 2025-07-06T23:31:10.928626Z INFO Daemon Daemon Handle ovf-env.xml. Jul 6 23:31:10.929937 waagent[1839]: 2025-07-06T23:31:10.929892Z INFO Daemon Daemon Set hostname [ci-4230.2.1-a-ea411ff846] Jul 6 23:31:10.932604 waagent[1839]: 2025-07-06T23:31:10.932554Z INFO Daemon Daemon Publish hostname [ci-4230.2.1-a-ea411ff846] Jul 6 23:31:10.933736 waagent[1839]: 2025-07-06T23:31:10.933689Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 6 23:31:10.934600 waagent[1839]: 2025-07-06T23:31:10.934561Z INFO Daemon Daemon Primary interface is [eth0] Jul 6 23:31:10.944238 systemd-networkd[1617]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:31:10.944790 systemd-networkd[1617]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:31:10.944852 systemd-networkd[1617]: eth0: DHCP lease lost Jul 6 23:31:10.945996 waagent[1839]: 2025-07-06T23:31:10.945896Z INFO Daemon Daemon Create user account if not exists Jul 6 23:31:10.949186 waagent[1839]: 2025-07-06T23:31:10.948944Z INFO Daemon Daemon User core already exists, skip useradd Jul 6 23:31:10.954450 waagent[1839]: 2025-07-06T23:31:10.952415Z INFO Daemon Daemon Configure sudoer Jul 6 23:31:10.954450 waagent[1839]: 2025-07-06T23:31:10.953486Z INFO Daemon Daemon Configure sshd Jul 6 23:31:10.954862 waagent[1839]: 2025-07-06T23:31:10.954813Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 6 23:31:10.955769 waagent[1839]: 2025-07-06T23:31:10.955729Z INFO Daemon Daemon Deploy ssh public key. Jul 6 23:31:11.004547 systemd-networkd[1617]: eth0: DHCPv4 address 10.200.8.24/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 6 23:31:11.084552 kubelet[1847]: E0706 23:31:11.084493 1847 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 6 23:31:11.087187 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 6 23:31:11.087387 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 6 23:31:11.087830 systemd[1]: kubelet.service: Consumed 1.016s CPU time, 271.7M memory peak. Jul 6 23:31:21.139867 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Jul 6 23:31:21.149746 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:31:21.276688 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:21.293909 (kubelet)[1919]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 6 23:31:21.951544 kubelet[1919]: E0706 23:31:21.951480 1919 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 6 23:31:21.955525 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 6 23:31:21.955723 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 6 23:31:21.956137 systemd[1]: kubelet.service: Consumed 168ms CPU time, 108.7M memory peak. Jul 6 23:31:31.369724 chronyd[1692]: Selected source PHC0 Jul 6 23:31:32.139916 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Jul 6 23:31:32.146149 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:31:32.515912 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:32.521019 (kubelet)[1934]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 6 23:31:32.964038 kubelet[1934]: E0706 23:31:32.963942 1934 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 6 23:31:32.967830 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 6 23:31:32.968055 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 6 23:31:32.968983 systemd[1]: kubelet.service: Consumed 181ms CPU time, 110M memory peak. Jul 6 23:31:41.031950 waagent[1839]: 2025-07-06T23:31:41.031880Z INFO Daemon Daemon Provisioning complete Jul 6 23:31:41.045638 waagent[1839]: 2025-07-06T23:31:41.045551Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 6 23:31:41.052530 waagent[1839]: 2025-07-06T23:31:41.046971Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 6 23:31:41.052530 waagent[1839]: 2025-07-06T23:31:41.047916Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Jul 6 23:31:41.185926 waagent[1941]: 2025-07-06T23:31:41.185808Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Jul 6 23:31:41.186371 waagent[1941]: 2025-07-06T23:31:41.185997Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.1 Jul 6 23:31:41.186371 waagent[1941]: 2025-07-06T23:31:41.186087Z INFO ExtHandler ExtHandler Python: 3.11.11 Jul 6 23:31:41.212657 waagent[1941]: 2025-07-06T23:31:41.212549Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Jul 6 23:31:41.212903 waagent[1941]: 2025-07-06T23:31:41.212850Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 6 23:31:41.213006 waagent[1941]: 2025-07-06T23:31:41.212964Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 6 23:31:41.220801 waagent[1941]: 2025-07-06T23:31:41.220723Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 6 23:31:41.231580 waagent[1941]: 2025-07-06T23:31:41.231509Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 6 23:31:41.232151 waagent[1941]: 2025-07-06T23:31:41.232096Z INFO ExtHandler Jul 6 23:31:41.232264 waagent[1941]: 2025-07-06T23:31:41.232195Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b026c8f6-3377-4610-81d2-4a177602f51d eTag: 3557810989614028067 source: Fabric] Jul 6 23:31:41.232600 waagent[1941]: 2025-07-06T23:31:41.232548Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 6 23:31:41.233179 waagent[1941]: 2025-07-06T23:31:41.233123Z INFO ExtHandler Jul 6 23:31:41.233248 waagent[1941]: 2025-07-06T23:31:41.233210Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 6 23:31:41.237152 waagent[1941]: 2025-07-06T23:31:41.237107Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 6 23:31:41.318805 waagent[1941]: 2025-07-06T23:31:41.318640Z INFO ExtHandler Downloaded certificate {'thumbprint': 'B69B4F9E8B8A25C1FC5EF406F34613C947D420FB', 'hasPrivateKey': True} Jul 6 23:31:41.319383 waagent[1941]: 2025-07-06T23:31:41.319324Z INFO ExtHandler Fetch goal state completed Jul 6 23:31:41.333271 waagent[1941]: 2025-07-06T23:31:41.333179Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1941 Jul 6 23:31:41.333496 waagent[1941]: 2025-07-06T23:31:41.333410Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 6 23:31:41.335176 waagent[1941]: 2025-07-06T23:31:41.335112Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.1', '', 'Flatcar Container Linux by Kinvolk'] Jul 6 23:31:41.335601 waagent[1941]: 2025-07-06T23:31:41.335548Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 6 23:31:41.343818 waagent[1941]: 2025-07-06T23:31:41.343767Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 6 23:31:41.344056 waagent[1941]: 2025-07-06T23:31:41.344000Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 6 23:31:41.350948 waagent[1941]: 2025-07-06T23:31:41.350872Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 6 23:31:41.358504 systemd[1]: Reload requested from client PID 1954 ('systemctl') (unit waagent.service)... Jul 6 23:31:41.358523 systemd[1]: Reloading... Jul 6 23:31:41.466468 zram_generator::config[1997]: No configuration found. Jul 6 23:31:41.598354 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:31:41.717275 systemd[1]: Reloading finished in 358 ms. Jul 6 23:31:41.737197 waagent[1941]: 2025-07-06T23:31:41.737086Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Jul 6 23:31:41.744980 systemd[1]: Reload requested from client PID 2050 ('systemctl') (unit waagent.service)... Jul 6 23:31:41.744999 systemd[1]: Reloading... Jul 6 23:31:41.857507 zram_generator::config[2092]: No configuration found. Jul 6 23:31:41.985369 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:31:42.111272 systemd[1]: Reloading finished in 365 ms. Jul 6 23:31:42.131772 waagent[1941]: 2025-07-06T23:31:42.131663Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 6 23:31:42.133044 waagent[1941]: 2025-07-06T23:31:42.132065Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 6 23:31:42.251652 waagent[1941]: 2025-07-06T23:31:42.251487Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 6 23:31:42.252316 waagent[1941]: 2025-07-06T23:31:42.252240Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Jul 6 23:31:42.253230 waagent[1941]: 2025-07-06T23:31:42.253161Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 6 23:31:42.253667 waagent[1941]: 2025-07-06T23:31:42.253607Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 6 23:31:42.253809 waagent[1941]: 2025-07-06T23:31:42.253750Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 6 23:31:42.253938 waagent[1941]: 2025-07-06T23:31:42.253890Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 6 23:31:42.254116 waagent[1941]: 2025-07-06T23:31:42.254052Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 6 23:31:42.254245 waagent[1941]: 2025-07-06T23:31:42.254165Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 6 23:31:42.254561 waagent[1941]: 2025-07-06T23:31:42.254504Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 6 23:31:42.254782 waagent[1941]: 2025-07-06T23:31:42.254736Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 6 23:31:42.255098 waagent[1941]: 2025-07-06T23:31:42.255038Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 6 23:31:42.255455 waagent[1941]: 2025-07-06T23:31:42.255391Z INFO EnvHandler ExtHandler Configure routes Jul 6 23:31:42.255715 waagent[1941]: 2025-07-06T23:31:42.255673Z INFO EnvHandler ExtHandler Gateway:None Jul 6 23:31:42.256127 waagent[1941]: 2025-07-06T23:31:42.256037Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 6 23:31:42.256371 waagent[1941]: 2025-07-06T23:31:42.256330Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 6 23:31:42.256524 waagent[1941]: 2025-07-06T23:31:42.256264Z INFO EnvHandler ExtHandler Routes:None Jul 6 23:31:42.256649 waagent[1941]: 2025-07-06T23:31:42.256585Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 6 23:31:42.257410 waagent[1941]: 2025-07-06T23:31:42.257357Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 6 23:31:42.257410 waagent[1941]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 6 23:31:42.257410 waagent[1941]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jul 6 23:31:42.257410 waagent[1941]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 6 23:31:42.257410 waagent[1941]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 6 23:31:42.257410 waagent[1941]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 6 23:31:42.257410 waagent[1941]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 6 23:31:42.263650 waagent[1941]: 2025-07-06T23:31:42.263575Z INFO ExtHandler ExtHandler Jul 6 23:31:42.264038 waagent[1941]: 2025-07-06T23:31:42.263978Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 185c84cd-efbf-4d1b-bd6a-6a1c3ecf7d2d correlation b807ad69-3a14-49d0-a843-a6313aaa7dc2 created: 2025-07-06T23:30:36.103485Z] Jul 6 23:31:42.265170 waagent[1941]: 2025-07-06T23:31:42.265110Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 6 23:31:42.266496 waagent[1941]: 2025-07-06T23:31:42.266415Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Jul 6 23:31:42.281135 waagent[1941]: 2025-07-06T23:31:42.280543Z INFO MonitorHandler ExtHandler Network interfaces: Jul 6 23:31:42.281135 waagent[1941]: Executing ['ip', '-a', '-o', 'link']: Jul 6 23:31:42.281135 waagent[1941]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Jul 6 23:31:42.281135 waagent[1941]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:de:ea:86 brd ff:ff:ff:ff:ff:ff Jul 6 23:31:42.281135 waagent[1941]: 3: enP47209s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:de:ea:86 brd ff:ff:ff:ff:ff:ff\ altname enP47209p0s2 Jul 6 23:31:42.281135 waagent[1941]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 6 23:31:42.281135 waagent[1941]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 6 23:31:42.281135 waagent[1941]: 2: eth0 inet 10.200.8.24/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 6 23:31:42.281135 waagent[1941]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 6 23:31:42.281135 waagent[1941]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 6 23:31:42.281135 waagent[1941]: 2: eth0 inet6 fe80::6245:bdff:fede:ea86/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 6 23:31:42.281135 waagent[1941]: 3: enP47209s1 inet6 fe80::6245:bdff:fede:ea86/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 6 23:31:42.308067 waagent[1941]: 2025-07-06T23:31:42.307999Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 8BC8006B-7073-4672-8D1E-2B8168F19128;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Jul 6 23:31:42.327489 waagent[1941]: 2025-07-06T23:31:42.327372Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Jul 6 23:31:42.327489 waagent[1941]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:31:42.327489 waagent[1941]: pkts bytes target prot opt in out source destination Jul 6 23:31:42.327489 waagent[1941]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:31:42.327489 waagent[1941]: pkts bytes target prot opt in out source destination Jul 6 23:31:42.327489 waagent[1941]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:31:42.327489 waagent[1941]: pkts bytes target prot opt in out source destination Jul 6 23:31:42.327489 waagent[1941]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 6 23:31:42.327489 waagent[1941]: 4 594 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 6 23:31:42.327489 waagent[1941]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 6 23:31:42.331561 waagent[1941]: 2025-07-06T23:31:42.331490Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 6 23:31:42.331561 waagent[1941]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:31:42.331561 waagent[1941]: pkts bytes target prot opt in out source destination Jul 6 23:31:42.331561 waagent[1941]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:31:42.331561 waagent[1941]: pkts bytes target prot opt in out source destination Jul 6 23:31:42.331561 waagent[1941]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:31:42.331561 waagent[1941]: pkts bytes target prot opt in out source destination Jul 6 23:31:42.331561 waagent[1941]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 6 23:31:42.331561 waagent[1941]: 5 646 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 6 23:31:42.331561 waagent[1941]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 6 23:31:42.331992 waagent[1941]: 2025-07-06T23:31:42.331833Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 6 23:31:43.139815 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 3. Jul 6 23:31:43.144829 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:31:43.285754 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:43.295834 (kubelet)[2189]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 6 23:31:43.932679 kubelet[2189]: E0706 23:31:43.932621 2189 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 6 23:31:43.935359 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 6 23:31:43.935576 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 6 23:31:43.936021 systemd[1]: kubelet.service: Consumed 179ms CPU time, 110.2M memory peak. Jul 6 23:31:52.946664 update_engine[1688]: I20250706 23:31:52.946553 1688 update_attempter.cc:509] Updating boot flags... Jul 6 23:31:52.975238 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 6 23:31:52.981904 systemd[1]: Started sshd@0-10.200.8.24:22-10.200.16.10:39078.service - OpenSSH per-connection server daemon (10.200.16.10:39078). Jul 6 23:31:53.022462 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2213) Jul 6 23:31:53.195484 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2213) Jul 6 23:31:53.700014 sshd[2205]: Accepted publickey for core from 10.200.16.10 port 39078 ssh2: RSA SHA256:CrkEq+GS/CqPhM0mP128HUaLhez9RVr/lxtrGPplanM Jul 6 23:31:53.701534 sshd-session[2205]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:31:53.705944 systemd-logind[1687]: New session 3 of user core. Jul 6 23:31:53.713644 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 6 23:31:54.139659 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 4. Jul 6 23:31:54.151747 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:31:54.251816 systemd[1]: Started sshd@1-10.200.8.24:22-10.200.16.10:39084.service - OpenSSH per-connection server daemon (10.200.16.10:39084). Jul 6 23:31:54.295419 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:54.305458 kernel: hv_balloon: Max. dynamic memory size: 8192 MB Jul 6 23:31:54.306883 (kubelet)[2326]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 6 23:31:54.887189 sshd[2319]: Accepted publickey for core from 10.200.16.10 port 39084 ssh2: RSA SHA256:CrkEq+GS/CqPhM0mP128HUaLhez9RVr/lxtrGPplanM Jul 6 23:31:54.888727 sshd-session[2319]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:31:54.894397 systemd-logind[1687]: New session 4 of user core. Jul 6 23:31:54.903669 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 6 23:31:54.974863 kubelet[2326]: E0706 23:31:54.974810 2326 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 6 23:31:54.977361 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 6 23:31:54.977571 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 6 23:31:54.977999 systemd[1]: kubelet.service: Consumed 157ms CPU time, 108.4M memory peak. Jul 6 23:31:55.333266 sshd[2331]: Connection closed by 10.200.16.10 port 39084 Jul 6 23:31:55.334211 sshd-session[2319]: pam_unix(sshd:session): session closed for user core Jul 6 23:31:55.337828 systemd[1]: sshd@1-10.200.8.24:22-10.200.16.10:39084.service: Deactivated successfully. Jul 6 23:31:55.339907 systemd[1]: session-4.scope: Deactivated successfully. Jul 6 23:31:55.341383 systemd-logind[1687]: Session 4 logged out. Waiting for processes to exit. Jul 6 23:31:55.342382 systemd-logind[1687]: Removed session 4. Jul 6 23:31:55.449782 systemd[1]: Started sshd@2-10.200.8.24:22-10.200.16.10:39096.service - OpenSSH per-connection server daemon (10.200.16.10:39096). Jul 6 23:31:56.078456 sshd[2339]: Accepted publickey for core from 10.200.16.10 port 39096 ssh2: RSA SHA256:CrkEq+GS/CqPhM0mP128HUaLhez9RVr/lxtrGPplanM Jul 6 23:31:56.079948 sshd-session[2339]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:31:56.084300 systemd-logind[1687]: New session 5 of user core. Jul 6 23:31:56.095627 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 6 23:31:56.520894 sshd[2341]: Connection closed by 10.200.16.10 port 39096 Jul 6 23:31:56.521722 sshd-session[2339]: pam_unix(sshd:session): session closed for user core Jul 6 23:31:56.525352 systemd[1]: sshd@2-10.200.8.24:22-10.200.16.10:39096.service: Deactivated successfully. Jul 6 23:31:56.527686 systemd[1]: session-5.scope: Deactivated successfully. Jul 6 23:31:56.529313 systemd-logind[1687]: Session 5 logged out. Waiting for processes to exit. Jul 6 23:31:56.530323 systemd-logind[1687]: Removed session 5. Jul 6 23:31:56.636794 systemd[1]: Started sshd@3-10.200.8.24:22-10.200.16.10:39106.service - OpenSSH per-connection server daemon (10.200.16.10:39106). Jul 6 23:31:57.264808 sshd[2347]: Accepted publickey for core from 10.200.16.10 port 39106 ssh2: RSA SHA256:CrkEq+GS/CqPhM0mP128HUaLhez9RVr/lxtrGPplanM Jul 6 23:31:57.266519 sshd-session[2347]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:31:57.273094 systemd-logind[1687]: New session 6 of user core. Jul 6 23:31:57.280649 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 6 23:31:57.709248 sshd[2349]: Connection closed by 10.200.16.10 port 39106 Jul 6 23:31:57.710191 sshd-session[2347]: pam_unix(sshd:session): session closed for user core Jul 6 23:31:57.713644 systemd[1]: sshd@3-10.200.8.24:22-10.200.16.10:39106.service: Deactivated successfully. Jul 6 23:31:57.715742 systemd[1]: session-6.scope: Deactivated successfully. Jul 6 23:31:57.717390 systemd-logind[1687]: Session 6 logged out. Waiting for processes to exit. Jul 6 23:31:57.718324 systemd-logind[1687]: Removed session 6. Jul 6 23:31:57.824780 systemd[1]: Started sshd@4-10.200.8.24:22-10.200.16.10:39118.service - OpenSSH per-connection server daemon (10.200.16.10:39118). Jul 6 23:31:58.451732 sshd[2355]: Accepted publickey for core from 10.200.16.10 port 39118 ssh2: RSA SHA256:CrkEq+GS/CqPhM0mP128HUaLhez9RVr/lxtrGPplanM Jul 6 23:31:58.453422 sshd-session[2355]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:31:58.458729 systemd-logind[1687]: New session 7 of user core. Jul 6 23:31:58.468628 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 6 23:31:58.834671 sudo[2358]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 6 23:31:58.835053 sudo[2358]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:31:59.392242 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:59.392508 systemd[1]: kubelet.service: Consumed 157ms CPU time, 108.4M memory peak. Jul 6 23:31:59.399756 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:31:59.440516 systemd[1]: Reload requested from client PID 2393 ('systemctl') (unit session-7.scope)... Jul 6 23:31:59.440694 systemd[1]: Reloading... Jul 6 23:31:59.589468 zram_generator::config[2439]: No configuration found. Jul 6 23:31:59.722769 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 6 23:31:59.841806 systemd[1]: Reloading finished in 400 ms. Jul 6 23:31:59.893525 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:59.906930 (kubelet)[2500]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 6 23:31:59.908845 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:31:59.910928 systemd[1]: kubelet.service: Deactivated successfully. Jul 6 23:31:59.911220 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:31:59.911294 systemd[1]: kubelet.service: Consumed 130ms CPU time, 98.3M memory peak. Jul 6 23:31:59.922804 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:32:00.177500 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:32:00.186831 (kubelet)[2512]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 6 23:32:00.934376 kubelet[2512]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Jul 6 23:32:00.934376 kubelet[2512]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Jul 6 23:32:00.934376 kubelet[2512]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Jul 6 23:32:00.934892 kubelet[2512]: I0706 23:32:00.934449 2512 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jul 6 23:32:01.851321 kubelet[2512]: I0706 23:32:01.851270 2512 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Jul 6 23:32:01.851321 kubelet[2512]: I0706 23:32:01.851303 2512 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 6 23:32:01.851653 kubelet[2512]: I0706 23:32:01.851630 2512 server.go:956] "Client rotation is on, will bootstrap in background" Jul 6 23:32:01.877309 kubelet[2512]: I0706 23:32:01.877192 2512 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 6 23:32:01.888500 kubelet[2512]: E0706 23:32:01.888457 2512 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Jul 6 23:32:01.888500 kubelet[2512]: I0706 23:32:01.888494 2512 server.go:1423] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Jul 6 23:32:01.892571 kubelet[2512]: I0706 23:32:01.892534 2512 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 6 23:32:01.892891 kubelet[2512]: I0706 23:32:01.892849 2512 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 6 23:32:01.893103 kubelet[2512]: I0706 23:32:01.892884 2512 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.8.24","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":"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},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Jul 6 23:32:01.893264 kubelet[2512]: I0706 23:32:01.893105 2512 topology_manager.go:138] "Creating topology manager with none policy" Jul 6 23:32:01.893264 kubelet[2512]: I0706 23:32:01.893120 2512 container_manager_linux.go:303] "Creating device plugin manager" Jul 6 23:32:01.893342 kubelet[2512]: I0706 23:32:01.893273 2512 state_mem.go:36] "Initialized new in-memory state store" Jul 6 23:32:01.896771 kubelet[2512]: I0706 23:32:01.896737 2512 kubelet.go:480] "Attempting to sync node with API server" Jul 6 23:32:01.896771 kubelet[2512]: I0706 23:32:01.896770 2512 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 6 23:32:01.896892 kubelet[2512]: I0706 23:32:01.896799 2512 kubelet.go:386] "Adding apiserver pod source" Jul 6 23:32:01.896892 kubelet[2512]: I0706 23:32:01.896818 2512 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 6 23:32:01.902197 kubelet[2512]: E0706 23:32:01.901895 2512 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:01.902197 kubelet[2512]: E0706 23:32:01.901957 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:01.902366 kubelet[2512]: I0706 23:32:01.902354 2512 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Jul 6 23:32:01.903042 kubelet[2512]: I0706 23:32:01.902947 2512 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Jul 6 23:32:01.904024 kubelet[2512]: W0706 23:32:01.903635 2512 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 6 23:32:01.906686 kubelet[2512]: I0706 23:32:01.906407 2512 watchdog_linux.go:99] "Systemd watchdog is not enabled" Jul 6 23:32:01.906686 kubelet[2512]: I0706 23:32:01.906479 2512 server.go:1289] "Started kubelet" Jul 6 23:32:01.915326 kubelet[2512]: I0706 23:32:01.913936 2512 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Jul 6 23:32:01.915326 kubelet[2512]: I0706 23:32:01.914760 2512 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 6 23:32:01.915326 kubelet[2512]: I0706 23:32:01.915106 2512 server.go:317] "Adding debug handlers to kubelet server" Jul 6 23:32:01.915326 kubelet[2512]: I0706 23:32:01.915176 2512 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 6 23:32:01.918037 kubelet[2512]: I0706 23:32:01.917861 2512 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 6 23:32:01.921534 kubelet[2512]: E0706 23:32:01.921497 2512 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.200.8.24\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Jul 6 23:32:01.921886 kubelet[2512]: E0706 23:32:01.921767 2512 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Jul 6 23:32:01.924809 kubelet[2512]: E0706 23:32:01.922554 2512 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.200.8.24.184fcd7e2ee02949 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.200.8.24,UID:10.200.8.24,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.200.8.24,},FirstTimestamp:2025-07-06 23:32:01.906428233 +0000 UTC m=+1.713760775,LastTimestamp:2025-07-06 23:32:01.906428233 +0000 UTC m=+1.713760775,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.200.8.24,}" Jul 6 23:32:01.924809 kubelet[2512]: I0706 23:32:01.924755 2512 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jul 6 23:32:01.927130 kubelet[2512]: I0706 23:32:01.926908 2512 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Jul 6 23:32:01.928888 kubelet[2512]: I0706 23:32:01.927844 2512 volume_manager.go:297] "Starting Kubelet Volume Manager" Jul 6 23:32:01.928888 kubelet[2512]: E0706 23:32:01.928115 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:01.928888 kubelet[2512]: I0706 23:32:01.928152 2512 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Jul 6 23:32:01.928888 kubelet[2512]: I0706 23:32:01.928200 2512 reconciler.go:26] "Reconciler: start to sync state" Jul 6 23:32:01.930452 kubelet[2512]: I0706 23:32:01.930417 2512 factory.go:223] Registration of the systemd container factory successfully Jul 6 23:32:01.930652 kubelet[2512]: I0706 23:32:01.930629 2512 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Jul 6 23:32:01.931227 kubelet[2512]: E0706 23:32:01.931196 2512 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 6 23:32:01.934599 kubelet[2512]: I0706 23:32:01.934557 2512 factory.go:223] Registration of the containerd container factory successfully Jul 6 23:32:01.969906 kubelet[2512]: E0706 23:32:01.969851 2512 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.200.8.24\" not found" node="10.200.8.24" Jul 6 23:32:01.969906 kubelet[2512]: I0706 23:32:01.969916 2512 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Jul 6 23:32:01.970124 kubelet[2512]: I0706 23:32:01.969942 2512 status_manager.go:230] "Starting to sync pod status with apiserver" Jul 6 23:32:01.970124 kubelet[2512]: I0706 23:32:01.969964 2512 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Jul 6 23:32:01.970124 kubelet[2512]: I0706 23:32:01.969972 2512 kubelet.go:2436] "Starting kubelet main sync loop" Jul 6 23:32:01.970124 kubelet[2512]: E0706 23:32:01.970019 2512 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Jul 6 23:32:01.989750 kubelet[2512]: I0706 23:32:01.989426 2512 cpu_manager.go:221] "Starting CPU manager" policy="none" Jul 6 23:32:01.989750 kubelet[2512]: I0706 23:32:01.989462 2512 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Jul 6 23:32:01.989750 kubelet[2512]: I0706 23:32:01.989485 2512 state_mem.go:36] "Initialized new in-memory state store" Jul 6 23:32:01.995665 kubelet[2512]: I0706 23:32:01.995631 2512 policy_none.go:49] "None policy: Start" Jul 6 23:32:01.995665 kubelet[2512]: I0706 23:32:01.995658 2512 memory_manager.go:186] "Starting memorymanager" policy="None" Jul 6 23:32:01.995665 kubelet[2512]: I0706 23:32:01.995673 2512 state_mem.go:35] "Initializing new in-memory state store" Jul 6 23:32:02.003729 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jul 6 23:32:02.023086 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jul 6 23:32:02.026832 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jul 6 23:32:02.029177 kubelet[2512]: E0706 23:32:02.029143 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.037856 kubelet[2512]: E0706 23:32:02.037398 2512 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Jul 6 23:32:02.037965 kubelet[2512]: I0706 23:32:02.037896 2512 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 6 23:32:02.037965 kubelet[2512]: I0706 23:32:02.037911 2512 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 6 23:32:02.038419 kubelet[2512]: I0706 23:32:02.038365 2512 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 6 23:32:02.039657 kubelet[2512]: E0706 23:32:02.039635 2512 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Jul 6 23:32:02.039856 kubelet[2512]: E0706 23:32:02.039802 2512 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.8.24\" not found" Jul 6 23:32:02.140041 kubelet[2512]: I0706 23:32:02.139692 2512 kubelet_node_status.go:75] "Attempting to register node" node="10.200.8.24" Jul 6 23:32:02.144384 kubelet[2512]: I0706 23:32:02.144351 2512 kubelet_node_status.go:78] "Successfully registered node" node="10.200.8.24" Jul 6 23:32:02.144384 kubelet[2512]: E0706 23:32:02.144386 2512 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.200.8.24\": node \"10.200.8.24\" not found" Jul 6 23:32:02.157727 kubelet[2512]: E0706 23:32:02.157679 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.216770 sudo[2358]: pam_unix(sudo:session): session closed for user root Jul 6 23:32:02.258142 kubelet[2512]: E0706 23:32:02.258081 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.319048 sshd[2357]: Connection closed by 10.200.16.10 port 39118 Jul 6 23:32:02.319938 sshd-session[2355]: pam_unix(sshd:session): session closed for user core Jul 6 23:32:02.323935 systemd[1]: sshd@4-10.200.8.24:22-10.200.16.10:39118.service: Deactivated successfully. Jul 6 23:32:02.326599 systemd[1]: session-7.scope: Deactivated successfully. Jul 6 23:32:02.326821 systemd[1]: session-7.scope: Consumed 478ms CPU time, 77M memory peak. Jul 6 23:32:02.329094 systemd-logind[1687]: Session 7 logged out. Waiting for processes to exit. Jul 6 23:32:02.330468 systemd-logind[1687]: Removed session 7. Jul 6 23:32:02.358963 kubelet[2512]: E0706 23:32:02.358903 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.459221 kubelet[2512]: E0706 23:32:02.459052 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.559689 kubelet[2512]: E0706 23:32:02.559632 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.660420 kubelet[2512]: E0706 23:32:02.660361 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.761374 kubelet[2512]: E0706 23:32:02.761229 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.853331 kubelet[2512]: I0706 23:32:02.853265 2512 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 6 23:32:02.853679 kubelet[2512]: I0706 23:32:02.853610 2512 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Jul 6 23:32:02.853785 kubelet[2512]: I0706 23:32:02.853717 2512 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Jul 6 23:32:02.861424 kubelet[2512]: E0706 23:32:02.861381 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:02.902996 kubelet[2512]: E0706 23:32:02.902922 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:02.962205 kubelet[2512]: E0706 23:32:02.962146 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:03.062941 kubelet[2512]: E0706 23:32:03.062808 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:03.163776 kubelet[2512]: E0706 23:32:03.163726 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:03.264732 kubelet[2512]: E0706 23:32:03.264669 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:03.365895 kubelet[2512]: E0706 23:32:03.365751 2512 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.8.24\" not found" Jul 6 23:32:03.467789 kubelet[2512]: I0706 23:32:03.467755 2512 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 6 23:32:03.468528 containerd[1706]: time="2025-07-06T23:32:03.468349233Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 6 23:32:03.469007 kubelet[2512]: I0706 23:32:03.468683 2512 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 6 23:32:03.903506 kubelet[2512]: I0706 23:32:03.903456 2512 apiserver.go:52] "Watching apiserver" Jul 6 23:32:03.903737 kubelet[2512]: E0706 23:32:03.903468 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:03.919713 systemd[1]: Created slice kubepods-burstable-pod755e1d55_fe9e_4442_8f7e_d237bb1e0c30.slice - libcontainer container kubepods-burstable-pod755e1d55_fe9e_4442_8f7e_d237bb1e0c30.slice. Jul 6 23:32:03.928820 kubelet[2512]: I0706 23:32:03.928636 2512 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Jul 6 23:32:03.931736 systemd[1]: Created slice kubepods-besteffort-podb49fa0bc_ee05_4a6d_b68b_4a3147e2061d.slice - libcontainer container kubepods-besteffort-podb49fa0bc_ee05_4a6d_b68b_4a3147e2061d.slice. Jul 6 23:32:03.941269 kubelet[2512]: I0706 23:32:03.941218 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/755e1d55-fe9e-4442-8f7e-d237bb1e0c30-cni-plugin\") pod \"kube-flannel-ds-pc66z\" (UID: \"755e1d55-fe9e-4442-8f7e-d237bb1e0c30\") " pod="kube-flannel/kube-flannel-ds-pc66z" Jul 6 23:32:03.941269 kubelet[2512]: I0706 23:32:03.941257 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/755e1d55-fe9e-4442-8f7e-d237bb1e0c30-cni\") pod \"kube-flannel-ds-pc66z\" (UID: \"755e1d55-fe9e-4442-8f7e-d237bb1e0c30\") " pod="kube-flannel/kube-flannel-ds-pc66z" Jul 6 23:32:03.941628 kubelet[2512]: I0706 23:32:03.941284 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/755e1d55-fe9e-4442-8f7e-d237bb1e0c30-flannel-cfg\") pod \"kube-flannel-ds-pc66z\" (UID: \"755e1d55-fe9e-4442-8f7e-d237bb1e0c30\") " pod="kube-flannel/kube-flannel-ds-pc66z" Jul 6 23:32:03.941628 kubelet[2512]: I0706 23:32:03.941307 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/755e1d55-fe9e-4442-8f7e-d237bb1e0c30-xtables-lock\") pod \"kube-flannel-ds-pc66z\" (UID: \"755e1d55-fe9e-4442-8f7e-d237bb1e0c30\") " pod="kube-flannel/kube-flannel-ds-pc66z" Jul 6 23:32:03.941628 kubelet[2512]: I0706 23:32:03.941328 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wjr8s\" (UniqueName: \"kubernetes.io/projected/755e1d55-fe9e-4442-8f7e-d237bb1e0c30-kube-api-access-wjr8s\") pod \"kube-flannel-ds-pc66z\" (UID: \"755e1d55-fe9e-4442-8f7e-d237bb1e0c30\") " pod="kube-flannel/kube-flannel-ds-pc66z" Jul 6 23:32:03.941628 kubelet[2512]: I0706 23:32:03.941368 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b49fa0bc-ee05-4a6d-b68b-4a3147e2061d-kube-proxy\") pod \"kube-proxy-mgpb5\" (UID: \"b49fa0bc-ee05-4a6d-b68b-4a3147e2061d\") " pod="kube-system/kube-proxy-mgpb5" Jul 6 23:32:03.941628 kubelet[2512]: I0706 23:32:03.941390 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/755e1d55-fe9e-4442-8f7e-d237bb1e0c30-run\") pod \"kube-flannel-ds-pc66z\" (UID: \"755e1d55-fe9e-4442-8f7e-d237bb1e0c30\") " pod="kube-flannel/kube-flannel-ds-pc66z" Jul 6 23:32:03.941787 kubelet[2512]: I0706 23:32:03.941426 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b49fa0bc-ee05-4a6d-b68b-4a3147e2061d-xtables-lock\") pod \"kube-proxy-mgpb5\" (UID: \"b49fa0bc-ee05-4a6d-b68b-4a3147e2061d\") " pod="kube-system/kube-proxy-mgpb5" Jul 6 23:32:03.941787 kubelet[2512]: I0706 23:32:03.941474 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b49fa0bc-ee05-4a6d-b68b-4a3147e2061d-lib-modules\") pod \"kube-proxy-mgpb5\" (UID: \"b49fa0bc-ee05-4a6d-b68b-4a3147e2061d\") " pod="kube-system/kube-proxy-mgpb5" Jul 6 23:32:03.941787 kubelet[2512]: I0706 23:32:03.941505 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7dwp6\" (UniqueName: \"kubernetes.io/projected/b49fa0bc-ee05-4a6d-b68b-4a3147e2061d-kube-api-access-7dwp6\") pod \"kube-proxy-mgpb5\" (UID: \"b49fa0bc-ee05-4a6d-b68b-4a3147e2061d\") " pod="kube-system/kube-proxy-mgpb5" Jul 6 23:32:04.231042 containerd[1706]: time="2025-07-06T23:32:04.230334557Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-pc66z,Uid:755e1d55-fe9e-4442-8f7e-d237bb1e0c30,Namespace:kube-flannel,Attempt:0,}" Jul 6 23:32:04.237130 containerd[1706]: time="2025-07-06T23:32:04.237086226Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-mgpb5,Uid:b49fa0bc-ee05-4a6d-b68b-4a3147e2061d,Namespace:kube-system,Attempt:0,}" Jul 6 23:32:04.904750 kubelet[2512]: E0706 23:32:04.904698 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:04.908511 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1342705441.mount: Deactivated successfully. Jul 6 23:32:04.936625 containerd[1706]: time="2025-07-06T23:32:04.936545908Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:32:04.942213 containerd[1706]: time="2025-07-06T23:32:04.942153466Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312064" Jul 6 23:32:04.945556 containerd[1706]: time="2025-07-06T23:32:04.945493300Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:32:04.951461 containerd[1706]: time="2025-07-06T23:32:04.951385361Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:32:04.954750 containerd[1706]: time="2025-07-06T23:32:04.954689195Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Jul 6 23:32:04.957730 containerd[1706]: time="2025-07-06T23:32:04.957638625Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:32:04.958846 containerd[1706]: time="2025-07-06T23:32:04.958537134Z" 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 727.989675ms" Jul 6 23:32:04.960633 containerd[1706]: time="2025-07-06T23:32:04.960591955Z" 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 723.390027ms" Jul 6 23:32:05.212420 containerd[1706]: time="2025-07-06T23:32:05.208832504Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:32:05.213117 containerd[1706]: time="2025-07-06T23:32:05.212282739Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:32:05.213117 containerd[1706]: time="2025-07-06T23:32:05.213019647Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:05.213388 containerd[1706]: time="2025-07-06T23:32:05.213326250Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:05.221719 containerd[1706]: time="2025-07-06T23:32:05.221569335Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:32:05.223248 containerd[1706]: time="2025-07-06T23:32:05.222012539Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:32:05.223248 containerd[1706]: time="2025-07-06T23:32:05.222073640Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:05.223248 containerd[1706]: time="2025-07-06T23:32:05.223120751Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:05.398187 systemd[1]: run-containerd-runc-k8s.io-273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129-runc.EhzJLE.mount: Deactivated successfully. Jul 6 23:32:05.416781 systemd[1]: Started cri-containerd-273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129.scope - libcontainer container 273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129. Jul 6 23:32:05.421656 systemd[1]: Started cri-containerd-64a71452d9eea8e87db22df163e3b9b589345fd8d8ed6883ec786c1350a804a6.scope - libcontainer container 64a71452d9eea8e87db22df163e3b9b589345fd8d8ed6883ec786c1350a804a6. Jul 6 23:32:05.454127 containerd[1706]: time="2025-07-06T23:32:05.454064422Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-mgpb5,Uid:b49fa0bc-ee05-4a6d-b68b-4a3147e2061d,Namespace:kube-system,Attempt:0,} returns sandbox id \"64a71452d9eea8e87db22df163e3b9b589345fd8d8ed6883ec786c1350a804a6\"" Jul 6 23:32:05.458216 containerd[1706]: time="2025-07-06T23:32:05.458157864Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.2\"" Jul 6 23:32:05.475825 containerd[1706]: time="2025-07-06T23:32:05.475224339Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-pc66z,Uid:755e1d55-fe9e-4442-8f7e-d237bb1e0c30,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129\"" Jul 6 23:32:05.905083 kubelet[2512]: E0706 23:32:05.905028 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:06.829698 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount521398143.mount: Deactivated successfully. Jul 6 23:32:06.905884 kubelet[2512]: E0706 23:32:06.905741 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:07.428653 containerd[1706]: time="2025-07-06T23:32:07.428591296Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:07.431257 containerd[1706]: time="2025-07-06T23:32:07.431078922Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.2: active requests=0, bytes read=31892754" Jul 6 23:32:07.435647 containerd[1706]: time="2025-07-06T23:32:07.435599368Z" level=info msg="ImageCreate event name:\"sha256:661d404f36f01cd854403fd3540f18dcf0342d22bd9c6516bb9de234ac183b19\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:07.439157 containerd[1706]: time="2025-07-06T23:32:07.439083404Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:4796ef3e43efa5ed2a5b015c18f81d3c2fe3aea36f555ea643cc01827eb65e51\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:07.439991 containerd[1706]: time="2025-07-06T23:32:07.439775911Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.2\" with image id \"sha256:661d404f36f01cd854403fd3540f18dcf0342d22bd9c6516bb9de234ac183b19\", repo tag \"registry.k8s.io/kube-proxy:v1.33.2\", repo digest \"registry.k8s.io/kube-proxy@sha256:4796ef3e43efa5ed2a5b015c18f81d3c2fe3aea36f555ea643cc01827eb65e51\", size \"31891765\" in 1.981572847s" Jul 6 23:32:07.439991 containerd[1706]: time="2025-07-06T23:32:07.439821511Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.2\" returns image reference \"sha256:661d404f36f01cd854403fd3540f18dcf0342d22bd9c6516bb9de234ac183b19\"" Jul 6 23:32:07.441341 containerd[1706]: time="2025-07-06T23:32:07.441112125Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Jul 6 23:32:07.447317 containerd[1706]: time="2025-07-06T23:32:07.447276988Z" level=info msg="CreateContainer within sandbox \"64a71452d9eea8e87db22df163e3b9b589345fd8d8ed6883ec786c1350a804a6\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 6 23:32:07.480938 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3926551606.mount: Deactivated successfully. Jul 6 23:32:07.494851 containerd[1706]: time="2025-07-06T23:32:07.494802976Z" level=info msg="CreateContainer within sandbox \"64a71452d9eea8e87db22df163e3b9b589345fd8d8ed6883ec786c1350a804a6\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"0aee028a46cd4f36376bb802d89586de4e1bcc49ccdc40dea2beece84ee8ba32\"" Jul 6 23:32:07.497046 containerd[1706]: time="2025-07-06T23:32:07.495587584Z" level=info msg="StartContainer for \"0aee028a46cd4f36376bb802d89586de4e1bcc49ccdc40dea2beece84ee8ba32\"" Jul 6 23:32:07.530680 systemd[1]: Started cri-containerd-0aee028a46cd4f36376bb802d89586de4e1bcc49ccdc40dea2beece84ee8ba32.scope - libcontainer container 0aee028a46cd4f36376bb802d89586de4e1bcc49ccdc40dea2beece84ee8ba32. Jul 6 23:32:07.561721 containerd[1706]: time="2025-07-06T23:32:07.561672162Z" level=info msg="StartContainer for \"0aee028a46cd4f36376bb802d89586de4e1bcc49ccdc40dea2beece84ee8ba32\" returns successfully" Jul 6 23:32:07.905898 kubelet[2512]: E0706 23:32:07.905862 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:08.014396 kubelet[2512]: I0706 23:32:08.014312 2512 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-mgpb5" podStartSLOduration=4.030771644 podStartE2EDuration="6.01429531s" podCreationTimestamp="2025-07-06 23:32:02 +0000 UTC" firstStartedPulling="2025-07-06 23:32:05.457425457 +0000 UTC m=+5.264757899" lastFinishedPulling="2025-07-06 23:32:07.440949123 +0000 UTC m=+7.248281565" observedRunningTime="2025-07-06 23:32:08.013805605 +0000 UTC m=+7.821138147" watchObservedRunningTime="2025-07-06 23:32:08.01429531 +0000 UTC m=+7.821627852" Jul 6 23:32:08.672108 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1442379438.mount: Deactivated successfully. Jul 6 23:32:08.764444 containerd[1706]: time="2025-07-06T23:32:08.764380012Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:08.768659 containerd[1706]: time="2025-07-06T23:32:08.766627935Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Jul 6 23:32:08.771921 containerd[1706]: time="2025-07-06T23:32:08.771841688Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:08.777515 containerd[1706]: time="2025-07-06T23:32:08.777398945Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:08.779555 containerd[1706]: time="2025-07-06T23:32:08.778527757Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 1.337378532s" Jul 6 23:32:08.779555 containerd[1706]: time="2025-07-06T23:32:08.778572057Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Jul 6 23:32:08.786084 containerd[1706]: time="2025-07-06T23:32:08.786035034Z" level=info msg="CreateContainer within sandbox \"273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 6 23:32:08.825764 containerd[1706]: time="2025-07-06T23:32:08.825711141Z" level=info msg="CreateContainer within sandbox \"273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50\"" Jul 6 23:32:08.826511 containerd[1706]: time="2025-07-06T23:32:08.826356248Z" level=info msg="StartContainer for \"730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50\"" Jul 6 23:32:08.863676 systemd[1]: Started cri-containerd-730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50.scope - libcontainer container 730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50. Jul 6 23:32:08.894168 systemd[1]: cri-containerd-730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50.scope: Deactivated successfully. Jul 6 23:32:08.898343 containerd[1706]: time="2025-07-06T23:32:08.898290286Z" level=info msg="StartContainer for \"730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50\" returns successfully" Jul 6 23:32:08.906843 kubelet[2512]: E0706 23:32:08.906800 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:09.552662 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50-rootfs.mount: Deactivated successfully. Jul 6 23:32:09.907505 kubelet[2512]: E0706 23:32:09.907452 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:09.911127 containerd[1706]: time="2025-07-06T23:32:09.911064885Z" level=info msg="shim disconnected" id=730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50 namespace=k8s.io Jul 6 23:32:09.911127 containerd[1706]: time="2025-07-06T23:32:09.911124386Z" level=warning msg="cleaning up after shim disconnected" id=730883e65fc11a52fba367c4d25524ec8b6d72136f1c0e31f6d38eceac63bb50 namespace=k8s.io Jul 6 23:32:09.911813 containerd[1706]: time="2025-07-06T23:32:09.911139086Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jul 6 23:32:10.013955 containerd[1706]: time="2025-07-06T23:32:10.013865341Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Jul 6 23:32:10.908691 kubelet[2512]: E0706 23:32:10.908637 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:11.909090 kubelet[2512]: E0706 23:32:11.909044 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:12.378457 containerd[1706]: time="2025-07-06T23:32:12.378298548Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:12.380806 containerd[1706]: time="2025-07-06T23:32:12.380554771Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Jul 6 23:32:12.384606 containerd[1706]: time="2025-07-06T23:32:12.384536712Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:12.390150 containerd[1706]: time="2025-07-06T23:32:12.390072769Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:12.391917 containerd[1706]: time="2025-07-06T23:32:12.391470484Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32996046\" in 2.377556943s" Jul 6 23:32:12.391917 containerd[1706]: time="2025-07-06T23:32:12.391513084Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Jul 6 23:32:12.398490 containerd[1706]: time="2025-07-06T23:32:12.398444256Z" level=info msg="CreateContainer within sandbox \"273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 6 23:32:12.439530 containerd[1706]: time="2025-07-06T23:32:12.439472478Z" level=info msg="CreateContainer within sandbox \"273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439\"" Jul 6 23:32:12.440351 containerd[1706]: time="2025-07-06T23:32:12.440085884Z" level=info msg="StartContainer for \"70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439\"" Jul 6 23:32:12.477666 systemd[1]: Started cri-containerd-70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439.scope - libcontainer container 70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439. Jul 6 23:32:12.503852 systemd[1]: cri-containerd-70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439.scope: Deactivated successfully. Jul 6 23:32:12.509957 containerd[1706]: time="2025-07-06T23:32:12.509824003Z" level=info msg="StartContainer for \"70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439\" returns successfully" Jul 6 23:32:12.529985 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439-rootfs.mount: Deactivated successfully. Jul 6 23:32:12.606095 kubelet[2512]: I0706 23:32:12.605875 2512 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Jul 6 23:32:12.909929 kubelet[2512]: E0706 23:32:12.909876 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:13.202786 containerd[1706]: time="2025-07-06T23:32:13.202463236Z" level=info msg="shim disconnected" id=70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439 namespace=k8s.io Jul 6 23:32:13.202786 containerd[1706]: time="2025-07-06T23:32:13.202547837Z" level=warning msg="cleaning up after shim disconnected" id=70a677664cef714881217551a9fff83658f6c5645e71dcce9c4c1cf14e2ed439 namespace=k8s.io Jul 6 23:32:13.202786 containerd[1706]: time="2025-07-06T23:32:13.202560937Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jul 6 23:32:13.910379 kubelet[2512]: E0706 23:32:13.910311 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:14.028995 containerd[1706]: time="2025-07-06T23:32:14.028952448Z" level=info msg="CreateContainer within sandbox \"273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 6 23:32:14.081917 containerd[1706]: time="2025-07-06T23:32:14.081857893Z" level=info msg="CreateContainer within sandbox \"273475bfb03ce7fb53e287bbddb796305948f99d96f548d19b0b712d16403129\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"4227d683670929fb83737e8e0c1789aad6d92e7f27f76385761d08fa7feff714\"" Jul 6 23:32:14.082674 containerd[1706]: time="2025-07-06T23:32:14.082573901Z" level=info msg="StartContainer for \"4227d683670929fb83737e8e0c1789aad6d92e7f27f76385761d08fa7feff714\"" Jul 6 23:32:14.126643 systemd[1]: Started cri-containerd-4227d683670929fb83737e8e0c1789aad6d92e7f27f76385761d08fa7feff714.scope - libcontainer container 4227d683670929fb83737e8e0c1789aad6d92e7f27f76385761d08fa7feff714. Jul 6 23:32:14.162396 containerd[1706]: time="2025-07-06T23:32:14.162157120Z" level=info msg="StartContainer for \"4227d683670929fb83737e8e0c1789aad6d92e7f27f76385761d08fa7feff714\" returns successfully" Jul 6 23:32:14.910561 kubelet[2512]: E0706 23:32:14.910483 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:15.231603 systemd-networkd[1617]: flannel.1: Link UP Jul 6 23:32:15.231613 systemd-networkd[1617]: flannel.1: Gained carrier Jul 6 23:32:15.911510 kubelet[2512]: E0706 23:32:15.911443 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:16.341700 systemd-networkd[1617]: flannel.1: Gained IPv6LL Jul 6 23:32:16.912223 kubelet[2512]: E0706 23:32:16.912153 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:17.339247 kubelet[2512]: I0706 23:32:17.339079 2512 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-pc66z" podStartSLOduration=8.422714494 podStartE2EDuration="15.33905884s" podCreationTimestamp="2025-07-06 23:32:02 +0000 UTC" firstStartedPulling="2025-07-06 23:32:05.476586153 +0000 UTC m=+5.283918595" lastFinishedPulling="2025-07-06 23:32:12.392930499 +0000 UTC m=+12.200262941" observedRunningTime="2025-07-06 23:32:15.043473397 +0000 UTC m=+14.850805839" watchObservedRunningTime="2025-07-06 23:32:17.33905884 +0000 UTC m=+17.146391382" Jul 6 23:32:17.351117 systemd[1]: Created slice kubepods-besteffort-pod6450de57_8a5e_486b_9676_440cb2fe780b.slice - libcontainer container kubepods-besteffort-pod6450de57_8a5e_486b_9676_440cb2fe780b.slice. Jul 6 23:32:17.434179 kubelet[2512]: I0706 23:32:17.434108 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dzmj5\" (UniqueName: \"kubernetes.io/projected/6450de57-8a5e-486b-9676-440cb2fe780b-kube-api-access-dzmj5\") pod \"nginx-deployment-7fcdb87857-h5fhb\" (UID: \"6450de57-8a5e-486b-9676-440cb2fe780b\") " pod="default/nginx-deployment-7fcdb87857-h5fhb" Jul 6 23:32:17.655520 containerd[1706]: time="2025-07-06T23:32:17.655466899Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-h5fhb,Uid:6450de57-8a5e-486b-9676-440cb2fe780b,Namespace:default,Attempt:0,}" Jul 6 23:32:17.716238 systemd-networkd[1617]: cni0: Link UP Jul 6 23:32:17.716252 systemd-networkd[1617]: cni0: Gained carrier Jul 6 23:32:17.716579 systemd-networkd[1617]: cni0: Lost carrier Jul 6 23:32:17.736472 kernel: cni0: port 1(vethf950372f) entered blocking state Jul 6 23:32:17.736643 kernel: cni0: port 1(vethf950372f) entered disabled state Jul 6 23:32:17.737198 kernel: vethf950372f: entered allmulticast mode Jul 6 23:32:17.741653 kernel: vethf950372f: entered promiscuous mode Jul 6 23:32:17.743631 kernel: cni0: port 1(vethf950372f) entered blocking state Jul 6 23:32:17.743695 kernel: cni0: port 1(vethf950372f) entered forwarding state Jul 6 23:32:17.748585 kernel: cni0: port 1(vethf950372f) entered disabled state Jul 6 23:32:17.751679 systemd-networkd[1617]: vethf950372f: Link UP Jul 6 23:32:17.759917 kernel: cni0: port 1(vethf950372f) entered blocking state Jul 6 23:32:17.760133 kernel: cni0: port 1(vethf950372f) entered forwarding state Jul 6 23:32:17.760286 systemd-networkd[1617]: vethf950372f: Gained carrier Jul 6 23:32:17.760514 systemd-networkd[1617]: cni0: Gained carrier Jul 6 23:32:17.763240 containerd[1706]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Jul 6 23:32:17.763240 containerd[1706]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:32:17.794766 containerd[1706]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-06T23:32:17.794619132Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:32:17.794766 containerd[1706]: time="2025-07-06T23:32:17.794688733Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:32:17.794766 containerd[1706]: time="2025-07-06T23:32:17.794710333Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:17.795130 containerd[1706]: time="2025-07-06T23:32:17.794916635Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:17.820638 systemd[1]: Started cri-containerd-de45a637d3851b5a1e9b0b1600722275e7c660d3950ad7bc9035455f5b03e861.scope - libcontainer container de45a637d3851b5a1e9b0b1600722275e7c660d3950ad7bc9035455f5b03e861. Jul 6 23:32:17.860073 containerd[1706]: time="2025-07-06T23:32:17.859956105Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-h5fhb,Uid:6450de57-8a5e-486b-9676-440cb2fe780b,Namespace:default,Attempt:0,} returns sandbox id \"de45a637d3851b5a1e9b0b1600722275e7c660d3950ad7bc9035455f5b03e861\"" Jul 6 23:32:17.861413 containerd[1706]: time="2025-07-06T23:32:17.861380119Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 6 23:32:17.912972 kubelet[2512]: E0706 23:32:17.912819 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:18.913865 kubelet[2512]: E0706 23:32:18.913761 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:19.032535 systemd-networkd[1617]: cni0: Gained IPv6LL Jul 6 23:32:19.285619 systemd-networkd[1617]: vethf950372f: Gained IPv6LL Jul 6 23:32:19.914595 kubelet[2512]: E0706 23:32:19.914524 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:20.752839 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2693850292.mount: Deactivated successfully. Jul 6 23:32:20.915625 kubelet[2512]: E0706 23:32:20.915581 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:21.897642 kubelet[2512]: E0706 23:32:21.897588 2512 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:21.916103 kubelet[2512]: E0706 23:32:21.916061 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:22.063915 containerd[1706]: time="2025-07-06T23:32:22.063861471Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:22.068481 containerd[1706]: time="2025-07-06T23:32:22.068289817Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73313230" Jul 6 23:32:22.072457 containerd[1706]: time="2025-07-06T23:32:22.070993645Z" level=info msg="ImageCreate event name:\"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:22.075866 containerd[1706]: time="2025-07-06T23:32:22.075829795Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:22.076761 containerd[1706]: time="2025-07-06T23:32:22.076722805Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"73313108\" in 4.215308485s" Jul 6 23:32:22.076859 containerd[1706]: time="2025-07-06T23:32:22.076765105Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\"" Jul 6 23:32:22.084326 containerd[1706]: time="2025-07-06T23:32:22.084287483Z" level=info msg="CreateContainer within sandbox \"de45a637d3851b5a1e9b0b1600722275e7c660d3950ad7bc9035455f5b03e861\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 6 23:32:22.138241 containerd[1706]: time="2025-07-06T23:32:22.138182644Z" level=info msg="CreateContainer within sandbox \"de45a637d3851b5a1e9b0b1600722275e7c660d3950ad7bc9035455f5b03e861\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"592df0f4c7a0ca9e3cab6d66cba3932a578b59bab53fc9aa94380408a6855510\"" Jul 6 23:32:22.138901 containerd[1706]: time="2025-07-06T23:32:22.138832351Z" level=info msg="StartContainer for \"592df0f4c7a0ca9e3cab6d66cba3932a578b59bab53fc9aa94380408a6855510\"" Jul 6 23:32:22.175651 systemd[1]: Started cri-containerd-592df0f4c7a0ca9e3cab6d66cba3932a578b59bab53fc9aa94380408a6855510.scope - libcontainer container 592df0f4c7a0ca9e3cab6d66cba3932a578b59bab53fc9aa94380408a6855510. Jul 6 23:32:22.207264 containerd[1706]: time="2025-07-06T23:32:22.206965360Z" level=info msg="StartContainer for \"592df0f4c7a0ca9e3cab6d66cba3932a578b59bab53fc9aa94380408a6855510\" returns successfully" Jul 6 23:32:22.917258 kubelet[2512]: E0706 23:32:22.917196 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:23.917859 kubelet[2512]: E0706 23:32:23.917802 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:24.918061 kubelet[2512]: E0706 23:32:24.917999 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:25.919105 kubelet[2512]: E0706 23:32:25.919040 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:26.920198 kubelet[2512]: E0706 23:32:26.920089 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:27.920569 kubelet[2512]: E0706 23:32:27.920513 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:28.254905 kubelet[2512]: I0706 23:32:28.254737 2512 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-h5fhb" podStartSLOduration=7.037934539 podStartE2EDuration="11.25471864s" podCreationTimestamp="2025-07-06 23:32:17 +0000 UTC" firstStartedPulling="2025-07-06 23:32:17.861072116 +0000 UTC m=+17.668404658" lastFinishedPulling="2025-07-06 23:32:22.077856317 +0000 UTC m=+21.885188759" observedRunningTime="2025-07-06 23:32:23.06838132 +0000 UTC m=+22.875713862" watchObservedRunningTime="2025-07-06 23:32:28.25471864 +0000 UTC m=+28.062051182" Jul 6 23:32:28.265607 systemd[1]: Created slice kubepods-besteffort-pod48195bb1_4cfc_4131_8b25_c99533507f8a.slice - libcontainer container kubepods-besteffort-pod48195bb1_4cfc_4131_8b25_c99533507f8a.slice. Jul 6 23:32:28.301855 kubelet[2512]: I0706 23:32:28.301707 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/48195bb1-4cfc-4131-8b25-c99533507f8a-data\") pod \"nfs-server-provisioner-0\" (UID: \"48195bb1-4cfc-4131-8b25-c99533507f8a\") " pod="default/nfs-server-provisioner-0" Jul 6 23:32:28.301855 kubelet[2512]: I0706 23:32:28.301786 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xd7w6\" (UniqueName: \"kubernetes.io/projected/48195bb1-4cfc-4131-8b25-c99533507f8a-kube-api-access-xd7w6\") pod \"nfs-server-provisioner-0\" (UID: \"48195bb1-4cfc-4131-8b25-c99533507f8a\") " pod="default/nfs-server-provisioner-0" Jul 6 23:32:28.569358 containerd[1706]: time="2025-07-06T23:32:28.569219900Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:48195bb1-4cfc-4131-8b25-c99533507f8a,Namespace:default,Attempt:0,}" Jul 6 23:32:28.621304 systemd-networkd[1617]: veth5e8acd6f: Link UP Jul 6 23:32:28.626290 kernel: cni0: port 2(veth5e8acd6f) entered blocking state Jul 6 23:32:28.626380 kernel: cni0: port 2(veth5e8acd6f) entered disabled state Jul 6 23:32:28.626406 kernel: veth5e8acd6f: entered allmulticast mode Jul 6 23:32:28.630271 kernel: veth5e8acd6f: entered promiscuous mode Jul 6 23:32:28.641408 kernel: cni0: port 2(veth5e8acd6f) entered blocking state Jul 6 23:32:28.641564 kernel: cni0: port 2(veth5e8acd6f) entered forwarding state Jul 6 23:32:28.641409 systemd-networkd[1617]: veth5e8acd6f: Gained carrier Jul 6 23:32:28.643976 containerd[1706]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000082280), "name":"cbr0", "type":"bridge"} Jul 6 23:32:28.643976 containerd[1706]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:32:28.677834 containerd[1706]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-06T23:32:28.677295621Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:32:28.677834 containerd[1706]: time="2025-07-06T23:32:28.677359321Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:32:28.677834 containerd[1706]: time="2025-07-06T23:32:28.677380122Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:28.677834 containerd[1706]: time="2025-07-06T23:32:28.677534223Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:28.704627 systemd[1]: Started cri-containerd-b78ceb67d0b4d6679b91bf9d2567b3f08b669962bec4a872c8c5517718e307ac.scope - libcontainer container b78ceb67d0b4d6679b91bf9d2567b3f08b669962bec4a872c8c5517718e307ac. Jul 6 23:32:28.747143 containerd[1706]: time="2025-07-06T23:32:28.747011343Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:48195bb1-4cfc-4131-8b25-c99533507f8a,Namespace:default,Attempt:0,} returns sandbox id \"b78ceb67d0b4d6679b91bf9d2567b3f08b669962bec4a872c8c5517718e307ac\"" Jul 6 23:32:28.748810 containerd[1706]: time="2025-07-06T23:32:28.748775462Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 6 23:32:28.920940 kubelet[2512]: E0706 23:32:28.920876 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:29.415044 systemd[1]: run-containerd-runc-k8s.io-b78ceb67d0b4d6679b91bf9d2567b3f08b669962bec4a872c8c5517718e307ac-runc.i9cxTB.mount: Deactivated successfully. Jul 6 23:32:29.922045 kubelet[2512]: E0706 23:32:29.921982 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:30.421584 systemd-networkd[1617]: veth5e8acd6f: Gained IPv6LL Jul 6 23:32:30.922734 kubelet[2512]: E0706 23:32:30.922691 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:31.342989 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1411781.mount: Deactivated successfully. Jul 6 23:32:31.923026 kubelet[2512]: E0706 23:32:31.922803 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:32.923910 kubelet[2512]: E0706 23:32:32.923859 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:33.263649 containerd[1706]: time="2025-07-06T23:32:33.263501365Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:33.265639 containerd[1706]: time="2025-07-06T23:32:33.265572086Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039414" Jul 6 23:32:33.270300 containerd[1706]: time="2025-07-06T23:32:33.270236035Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:33.279883 containerd[1706]: time="2025-07-06T23:32:33.278793124Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:33.279883 containerd[1706]: time="2025-07-06T23:32:33.279718733Z" 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.530692869s" Jul 6 23:32:33.279883 containerd[1706]: time="2025-07-06T23:32:33.279757734Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Jul 6 23:32:33.302125 containerd[1706]: time="2025-07-06T23:32:33.302028464Z" level=info msg="CreateContainer within sandbox \"b78ceb67d0b4d6679b91bf9d2567b3f08b669962bec4a872c8c5517718e307ac\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 6 23:32:33.379388 containerd[1706]: time="2025-07-06T23:32:33.379339666Z" level=info msg="CreateContainer within sandbox \"b78ceb67d0b4d6679b91bf9d2567b3f08b669962bec4a872c8c5517718e307ac\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"b3f2965fd7f58540111fe7ddcff50a4de0277d9c6bed5938b5e796d615c41b9e\"" Jul 6 23:32:33.380143 containerd[1706]: time="2025-07-06T23:32:33.379987273Z" level=info msg="StartContainer for \"b3f2965fd7f58540111fe7ddcff50a4de0277d9c6bed5938b5e796d615c41b9e\"" Jul 6 23:32:33.414652 systemd[1]: Started cri-containerd-b3f2965fd7f58540111fe7ddcff50a4de0277d9c6bed5938b5e796d615c41b9e.scope - libcontainer container b3f2965fd7f58540111fe7ddcff50a4de0277d9c6bed5938b5e796d615c41b9e. Jul 6 23:32:33.450958 containerd[1706]: time="2025-07-06T23:32:33.450807007Z" level=info msg="StartContainer for \"b3f2965fd7f58540111fe7ddcff50a4de0277d9c6bed5938b5e796d615c41b9e\" returns successfully" Jul 6 23:32:33.925125 kubelet[2512]: E0706 23:32:33.925052 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:34.926300 kubelet[2512]: E0706 23:32:34.926239 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:35.926584 kubelet[2512]: E0706 23:32:35.926520 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:36.926749 kubelet[2512]: E0706 23:32:36.926670 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:37.927823 kubelet[2512]: E0706 23:32:37.927759 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:38.693316 kubelet[2512]: I0706 23:32:38.693008 2512 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.160515122 podStartE2EDuration="10.692982709s" podCreationTimestamp="2025-07-06 23:32:28 +0000 UTC" firstStartedPulling="2025-07-06 23:32:28.748420358 +0000 UTC m=+28.555752800" lastFinishedPulling="2025-07-06 23:32:33.280887945 +0000 UTC m=+33.088220387" observedRunningTime="2025-07-06 23:32:34.097003006 +0000 UTC m=+33.904335448" watchObservedRunningTime="2025-07-06 23:32:38.692982709 +0000 UTC m=+38.500315151" Jul 6 23:32:38.705304 systemd[1]: Created slice kubepods-besteffort-pod736d2047_1c3a_4c98_b771_6ae5750dee4c.slice - libcontainer container kubepods-besteffort-pod736d2047_1c3a_4c98_b771_6ae5750dee4c.slice. Jul 6 23:32:38.774535 kubelet[2512]: I0706 23:32:38.774453 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xfl64\" (UniqueName: \"kubernetes.io/projected/736d2047-1c3a-4c98-b771-6ae5750dee4c-kube-api-access-xfl64\") pod \"test-pod-1\" (UID: \"736d2047-1c3a-4c98-b771-6ae5750dee4c\") " pod="default/test-pod-1" Jul 6 23:32:38.774946 kubelet[2512]: I0706 23:32:38.774541 2512 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-16e3c59a-fa36-4606-a10b-34ad9aa21c9b\" (UniqueName: \"kubernetes.io/nfs/736d2047-1c3a-4c98-b771-6ae5750dee4c-pvc-16e3c59a-fa36-4606-a10b-34ad9aa21c9b\") pod \"test-pod-1\" (UID: \"736d2047-1c3a-4c98-b771-6ae5750dee4c\") " pod="default/test-pod-1" Jul 6 23:32:38.928168 kubelet[2512]: E0706 23:32:38.928102 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:38.933518 kernel: FS-Cache: Loaded Jul 6 23:32:39.009866 kernel: RPC: Registered named UNIX socket transport module. Jul 6 23:32:39.009991 kernel: RPC: Registered udp transport module. Jul 6 23:32:39.010012 kernel: RPC: Registered tcp transport module. Jul 6 23:32:39.013326 kernel: RPC: Registered tcp-with-tls transport module. Jul 6 23:32:39.014285 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 6 23:32:39.279476 kernel: NFS: Registering the id_resolver key type Jul 6 23:32:39.279622 kernel: Key type id_resolver registered Jul 6 23:32:39.279645 kernel: Key type id_legacy registered Jul 6 23:32:39.323998 nfsidmap[3513]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.1-a-ea411ff846' Jul 6 23:32:39.332231 nfsidmap[3514]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.1-a-ea411ff846' Jul 6 23:32:39.608655 containerd[1706]: time="2025-07-06T23:32:39.608506960Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:736d2047-1c3a-4c98-b771-6ae5750dee4c,Namespace:default,Attempt:0,}" Jul 6 23:32:39.683028 systemd-networkd[1617]: veth50f177d4: Link UP Jul 6 23:32:39.690486 kernel: cni0: port 3(veth50f177d4) entered blocking state Jul 6 23:32:39.690597 kernel: cni0: port 3(veth50f177d4) entered disabled state Jul 6 23:32:39.690644 kernel: veth50f177d4: entered allmulticast mode Jul 6 23:32:39.696255 kernel: veth50f177d4: entered promiscuous mode Jul 6 23:32:39.707627 kernel: cni0: port 3(veth50f177d4) entered blocking state Jul 6 23:32:39.707839 kernel: cni0: port 3(veth50f177d4) entered forwarding state Jul 6 23:32:39.707887 systemd-networkd[1617]: veth50f177d4: Gained carrier Jul 6 23:32:39.710317 containerd[1706]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00008c950), "name":"cbr0", "type":"bridge"} Jul 6 23:32:39.710317 containerd[1706]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:32:39.735242 containerd[1706]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-06T23:32:39.734828464Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:32:39.735242 containerd[1706]: time="2025-07-06T23:32:39.734894064Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:32:39.735242 containerd[1706]: time="2025-07-06T23:32:39.734915165Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:39.735242 containerd[1706]: time="2025-07-06T23:32:39.735096466Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:32:39.810716 systemd[1]: Started cri-containerd-30d6091fb1a806f9a9cabb6576139e940ba688c5ef2c5f33e7695fefd2813088.scope - libcontainer container 30d6091fb1a806f9a9cabb6576139e940ba688c5ef2c5f33e7695fefd2813088. Jul 6 23:32:39.870543 containerd[1706]: time="2025-07-06T23:32:39.870393763Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:736d2047-1c3a-4c98-b771-6ae5750dee4c,Namespace:default,Attempt:0,} returns sandbox id \"30d6091fb1a806f9a9cabb6576139e940ba688c5ef2c5f33e7695fefd2813088\"" Jul 6 23:32:39.872621 containerd[1706]: time="2025-07-06T23:32:39.872590586Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 6 23:32:39.929068 kubelet[2512]: E0706 23:32:39.929006 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:40.474951 containerd[1706]: time="2025-07-06T23:32:40.474890603Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:32:40.477088 containerd[1706]: time="2025-07-06T23:32:40.477017925Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jul 6 23:32:40.479886 containerd[1706]: time="2025-07-06T23:32:40.479841254Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"73313108\" in 607.216368ms" Jul 6 23:32:40.479886 containerd[1706]: time="2025-07-06T23:32:40.479884655Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\"" Jul 6 23:32:40.487228 containerd[1706]: time="2025-07-06T23:32:40.487084929Z" level=info msg="CreateContainer within sandbox \"30d6091fb1a806f9a9cabb6576139e940ba688c5ef2c5f33e7695fefd2813088\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 6 23:32:40.533602 containerd[1706]: time="2025-07-06T23:32:40.532836001Z" level=info msg="CreateContainer within sandbox \"30d6091fb1a806f9a9cabb6576139e940ba688c5ef2c5f33e7695fefd2813088\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"add9973efbe703a1551cc684f51a4fd7f59ec92d239b8aa051222733616a4c7a\"" Jul 6 23:32:40.534934 containerd[1706]: time="2025-07-06T23:32:40.534902623Z" level=info msg="StartContainer for \"add9973efbe703a1551cc684f51a4fd7f59ec92d239b8aa051222733616a4c7a\"" Jul 6 23:32:40.580687 systemd[1]: Started cri-containerd-add9973efbe703a1551cc684f51a4fd7f59ec92d239b8aa051222733616a4c7a.scope - libcontainer container add9973efbe703a1551cc684f51a4fd7f59ec92d239b8aa051222733616a4c7a. Jul 6 23:32:40.613205 containerd[1706]: time="2025-07-06T23:32:40.613119330Z" level=info msg="StartContainer for \"add9973efbe703a1551cc684f51a4fd7f59ec92d239b8aa051222733616a4c7a\" returns successfully" Jul 6 23:32:40.853676 systemd-networkd[1617]: veth50f177d4: Gained IPv6LL Jul 6 23:32:40.929850 kubelet[2512]: E0706 23:32:40.929754 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:41.897592 kubelet[2512]: E0706 23:32:41.897524 2512 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:41.930372 kubelet[2512]: E0706 23:32:41.930317 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:42.931210 kubelet[2512]: E0706 23:32:42.931148 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:43.932230 kubelet[2512]: E0706 23:32:43.932162 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:44.932695 kubelet[2512]: E0706 23:32:44.932629 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:45.933527 kubelet[2512]: E0706 23:32:45.933464 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:46.934676 kubelet[2512]: E0706 23:32:46.934616 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:47.935578 kubelet[2512]: E0706 23:32:47.935513 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:32:48.936573 kubelet[2512]: E0706 23:32:48.936505 2512 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"