Oct 13 04:59:34.675117 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Oct 13 04:59:34.675136 kernel: Linux version 6.12.51-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Mon Oct 13 03:30:16 -00 2025 Oct 13 04:59:34.675142 kernel: KASLR enabled Oct 13 04:59:34.675146 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Oct 13 04:59:34.675151 kernel: printk: legacy bootconsole [pl11] enabled Oct 13 04:59:34.675155 kernel: efi: EFI v2.7 by EDK II Oct 13 04:59:34.675161 kernel: efi: ACPI 2.0=0x3f979018 SMBIOS=0x3f8a0000 SMBIOS 3.0=0x3f880000 MEMATTR=0x3e89d018 RNG=0x3f979998 MEMRESERVE=0x3db7d598 Oct 13 04:59:34.675165 kernel: random: crng init done Oct 13 04:59:34.675169 kernel: secureboot: Secure boot disabled Oct 13 04:59:34.675173 kernel: ACPI: Early table checksum verification disabled Oct 13 04:59:34.675177 kernel: ACPI: RSDP 0x000000003F979018 000024 (v02 VRTUAL) Oct 13 04:59:34.675181 kernel: ACPI: XSDT 0x000000003F979F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675185 kernel: ACPI: FACP 0x000000003F979C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675190 kernel: ACPI: DSDT 0x000000003F95A018 01E046 (v02 MSFTVM DSDT01 00000001 INTL 20230628) Oct 13 04:59:34.675196 kernel: ACPI: DBG2 0x000000003F979B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675200 kernel: ACPI: GTDT 0x000000003F979D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675204 kernel: ACPI: OEM0 0x000000003F979098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675210 kernel: ACPI: SPCR 0x000000003F979A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675214 kernel: ACPI: APIC 0x000000003F979818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675218 kernel: ACPI: SRAT 0x000000003F979198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675223 kernel: ACPI: PPTT 0x000000003F979418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Oct 13 04:59:34.675227 kernel: ACPI: BGRT 0x000000003F979E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 04:59:34.675232 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Oct 13 04:59:34.675236 kernel: ACPI: Use ACPI SPCR as default console: No Oct 13 04:59:34.675240 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 13 04:59:34.675245 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Oct 13 04:59:34.675249 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Oct 13 04:59:34.675254 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Oct 13 04:59:34.675265 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Oct 13 04:59:34.675269 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Oct 13 04:59:34.675273 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Oct 13 04:59:34.675278 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Oct 13 04:59:34.675282 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Oct 13 04:59:34.675286 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Oct 13 04:59:34.675291 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Oct 13 04:59:34.675295 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Oct 13 04:59:34.675300 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Oct 13 04:59:34.675304 kernel: NODE_DATA(0) allocated [mem 0x1bf7ffa00-0x1bf806fff] Oct 13 04:59:34.675309 kernel: Zone ranges: Oct 13 04:59:34.675314 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Oct 13 04:59:34.675320 kernel: DMA32 empty Oct 13 04:59:34.675325 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Oct 13 04:59:34.675330 kernel: Device empty Oct 13 04:59:34.675335 kernel: Movable zone start for each node Oct 13 04:59:34.675340 kernel: Early memory node ranges Oct 13 04:59:34.675345 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Oct 13 04:59:34.675349 kernel: node 0: [mem 0x0000000000824000-0x000000003f38ffff] Oct 13 04:59:34.675354 kernel: node 0: [mem 0x000000003f390000-0x000000003f93ffff] Oct 13 04:59:34.675358 kernel: node 0: [mem 0x000000003f940000-0x000000003f9effff] Oct 13 04:59:34.675363 kernel: node 0: [mem 0x000000003f9f0000-0x000000003fdeffff] Oct 13 04:59:34.675368 kernel: node 0: [mem 0x000000003fdf0000-0x000000003fffffff] Oct 13 04:59:34.675372 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Oct 13 04:59:34.675378 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Oct 13 04:59:34.675382 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Oct 13 04:59:34.675387 kernel: cma: Reserved 16 MiB at 0x000000003ca00000 on node -1 Oct 13 04:59:34.675392 kernel: psci: probing for conduit method from ACPI. Oct 13 04:59:34.675396 kernel: psci: PSCIv1.3 detected in firmware. Oct 13 04:59:34.675401 kernel: psci: Using standard PSCI v0.2 function IDs Oct 13 04:59:34.675406 kernel: psci: MIGRATE_INFO_TYPE not supported. Oct 13 04:59:34.675410 kernel: psci: SMC Calling Convention v1.4 Oct 13 04:59:34.675415 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Oct 13 04:59:34.675420 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Oct 13 04:59:34.675424 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 13 04:59:34.675429 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 13 04:59:34.675435 kernel: pcpu-alloc: [0] 0 [0] 1 Oct 13 04:59:34.675439 kernel: Detected PIPT I-cache on CPU0 Oct 13 04:59:34.675444 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Oct 13 04:59:34.675449 kernel: CPU features: detected: GIC system register CPU interface Oct 13 04:59:34.675453 kernel: CPU features: detected: Spectre-v4 Oct 13 04:59:34.675458 kernel: CPU features: detected: Spectre-BHB Oct 13 04:59:34.675463 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 13 04:59:34.675467 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 13 04:59:34.675472 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Oct 13 04:59:34.675476 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 13 04:59:34.675482 kernel: alternatives: applying boot alternatives Oct 13 04:59:34.675488 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=1a81e36b39d22063d1d9b2ac3307af6d1e57cfd926c8fafd214fb74284e73d99 Oct 13 04:59:34.675493 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 13 04:59:34.675497 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 13 04:59:34.675502 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 13 04:59:34.675507 kernel: Fallback order for Node 0: 0 Oct 13 04:59:34.675512 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Oct 13 04:59:34.675516 kernel: Policy zone: Normal Oct 13 04:59:34.675521 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 13 04:59:34.675526 kernel: software IO TLB: area num 2. Oct 13 04:59:34.675530 kernel: software IO TLB: mapped [mem 0x00000000372f0000-0x000000003b2f0000] (64MB) Oct 13 04:59:34.675536 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 13 04:59:34.675541 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 13 04:59:34.675546 kernel: rcu: RCU event tracing is enabled. Oct 13 04:59:34.675551 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 13 04:59:34.675556 kernel: Trampoline variant of Tasks RCU enabled. Oct 13 04:59:34.675560 kernel: Tracing variant of Tasks RCU enabled. Oct 13 04:59:34.675565 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 13 04:59:34.675570 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 13 04:59:34.675574 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 13 04:59:34.675579 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 13 04:59:34.675585 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 13 04:59:34.675589 kernel: GICv3: 960 SPIs implemented Oct 13 04:59:34.675594 kernel: GICv3: 0 Extended SPIs implemented Oct 13 04:59:34.675599 kernel: Root IRQ handler: gic_handle_irq Oct 13 04:59:34.675603 kernel: GICv3: GICv3 features: 16 PPIs, RSS Oct 13 04:59:34.675608 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Oct 13 04:59:34.675613 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Oct 13 04:59:34.675617 kernel: ITS: No ITS available, not enabling LPIs Oct 13 04:59:34.675622 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 13 04:59:34.675627 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Oct 13 04:59:34.675632 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 13 04:59:34.675637 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Oct 13 04:59:34.675642 kernel: Console: colour dummy device 80x25 Oct 13 04:59:34.675647 kernel: printk: legacy console [tty1] enabled Oct 13 04:59:34.675652 kernel: ACPI: Core revision 20240827 Oct 13 04:59:34.675658 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Oct 13 04:59:34.675663 kernel: pid_max: default: 32768 minimum: 301 Oct 13 04:59:34.675668 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 13 04:59:34.675672 kernel: landlock: Up and running. Oct 13 04:59:34.675678 kernel: SELinux: Initializing. Oct 13 04:59:34.675683 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 13 04:59:34.675688 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 13 04:59:34.675693 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0xa0000e, misc 0x31e1 Oct 13 04:59:34.675702 kernel: Hyper-V: Host Build 10.0.26102.1083-1-0 Oct 13 04:59:34.675708 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 13 04:59:34.675713 kernel: rcu: Hierarchical SRCU implementation. Oct 13 04:59:34.675718 kernel: rcu: Max phase no-delay instances is 400. Oct 13 04:59:34.675723 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 13 04:59:34.675729 kernel: Remapping and enabling EFI services. Oct 13 04:59:34.675734 kernel: smp: Bringing up secondary CPUs ... Oct 13 04:59:34.675739 kernel: Detected PIPT I-cache on CPU1 Oct 13 04:59:34.675744 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Oct 13 04:59:34.675750 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Oct 13 04:59:34.675755 kernel: smp: Brought up 1 node, 2 CPUs Oct 13 04:59:34.675760 kernel: SMP: Total of 2 processors activated. Oct 13 04:59:34.675765 kernel: CPU: All CPU(s) started at EL1 Oct 13 04:59:34.675771 kernel: CPU features: detected: 32-bit EL0 Support Oct 13 04:59:34.675776 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Oct 13 04:59:34.675781 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 13 04:59:34.675786 kernel: CPU features: detected: Common not Private translations Oct 13 04:59:34.675792 kernel: CPU features: detected: CRC32 instructions Oct 13 04:59:34.675798 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Oct 13 04:59:34.675803 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 13 04:59:34.675808 kernel: CPU features: detected: LSE atomic instructions Oct 13 04:59:34.675813 kernel: CPU features: detected: Privileged Access Never Oct 13 04:59:34.675882 kernel: CPU features: detected: Speculation barrier (SB) Oct 13 04:59:34.675887 kernel: CPU features: detected: TLB range maintenance instructions Oct 13 04:59:34.675894 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 13 04:59:34.675900 kernel: CPU features: detected: Scalable Vector Extension Oct 13 04:59:34.675905 kernel: alternatives: applying system-wide alternatives Oct 13 04:59:34.675910 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Oct 13 04:59:34.675915 kernel: SVE: maximum available vector length 16 bytes per vector Oct 13 04:59:34.675920 kernel: SVE: default vector length 16 bytes per vector Oct 13 04:59:34.675926 kernel: Memory: 3979452K/4194160K available (11200K kernel code, 2456K rwdata, 9080K rodata, 12992K init, 1038K bss, 193520K reserved, 16384K cma-reserved) Oct 13 04:59:34.675932 kernel: devtmpfs: initialized Oct 13 04:59:34.675937 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 13 04:59:34.675943 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 13 04:59:34.675948 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 13 04:59:34.675953 kernel: 0 pages in range for non-PLT usage Oct 13 04:59:34.675958 kernel: 515040 pages in range for PLT usage Oct 13 04:59:34.675963 kernel: pinctrl core: initialized pinctrl subsystem Oct 13 04:59:34.675969 kernel: SMBIOS 3.1.0 present. Oct 13 04:59:34.675975 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 06/10/2025 Oct 13 04:59:34.675980 kernel: DMI: Memory slots populated: 2/2 Oct 13 04:59:34.675985 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 13 04:59:34.675990 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 13 04:59:34.675995 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 13 04:59:34.676001 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 13 04:59:34.676007 kernel: audit: initializing netlink subsys (disabled) Oct 13 04:59:34.676012 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Oct 13 04:59:34.676017 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 13 04:59:34.676023 kernel: cpuidle: using governor menu Oct 13 04:59:34.676028 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 13 04:59:34.676033 kernel: ASID allocator initialised with 32768 entries Oct 13 04:59:34.676038 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 13 04:59:34.676044 kernel: Serial: AMBA PL011 UART driver Oct 13 04:59:34.676050 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 13 04:59:34.676055 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 13 04:59:34.676060 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 13 04:59:34.676065 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 13 04:59:34.676070 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 13 04:59:34.676075 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 13 04:59:34.676081 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 13 04:59:34.676086 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 13 04:59:34.676091 kernel: ACPI: Added _OSI(Module Device) Oct 13 04:59:34.676097 kernel: ACPI: Added _OSI(Processor Device) Oct 13 04:59:34.676102 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 13 04:59:34.676107 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 13 04:59:34.676112 kernel: ACPI: Interpreter enabled Oct 13 04:59:34.676118 kernel: ACPI: Using GIC for interrupt routing Oct 13 04:59:34.676123 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Oct 13 04:59:34.676128 kernel: printk: legacy console [ttyAMA0] enabled Oct 13 04:59:34.676133 kernel: printk: legacy bootconsole [pl11] disabled Oct 13 04:59:34.676139 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Oct 13 04:59:34.676144 kernel: ACPI: CPU0 has been hot-added Oct 13 04:59:34.676149 kernel: ACPI: CPU1 has been hot-added Oct 13 04:59:34.676155 kernel: iommu: Default domain type: Translated Oct 13 04:59:34.676160 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 13 04:59:34.676165 kernel: efivars: Registered efivars operations Oct 13 04:59:34.676170 kernel: vgaarb: loaded Oct 13 04:59:34.676176 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 13 04:59:34.676181 kernel: VFS: Disk quotas dquot_6.6.0 Oct 13 04:59:34.676186 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 13 04:59:34.676191 kernel: pnp: PnP ACPI init Oct 13 04:59:34.676197 kernel: pnp: PnP ACPI: found 0 devices Oct 13 04:59:34.676202 kernel: NET: Registered PF_INET protocol family Oct 13 04:59:34.676208 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 13 04:59:34.676213 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 13 04:59:34.676218 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 04:59:34.676223 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 13 04:59:34.676229 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 13 04:59:34.676235 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 13 04:59:34.676240 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 13 04:59:34.676245 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 13 04:59:34.676250 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 13 04:59:34.676255 kernel: PCI: CLS 0 bytes, default 64 Oct 13 04:59:34.676260 kernel: kvm [1]: HYP mode not available Oct 13 04:59:34.676266 kernel: Initialise system trusted keyrings Oct 13 04:59:34.676271 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 13 04:59:34.676276 kernel: Key type asymmetric registered Oct 13 04:59:34.676282 kernel: Asymmetric key parser 'x509' registered Oct 13 04:59:34.676287 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 13 04:59:34.676292 kernel: io scheduler mq-deadline registered Oct 13 04:59:34.676297 kernel: io scheduler kyber registered Oct 13 04:59:34.676302 kernel: io scheduler bfq registered Oct 13 04:59:34.676308 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 13 04:59:34.676313 kernel: thunder_xcv, ver 1.0 Oct 13 04:59:34.676318 kernel: thunder_bgx, ver 1.0 Oct 13 04:59:34.676323 kernel: nicpf, ver 1.0 Oct 13 04:59:34.676328 kernel: nicvf, ver 1.0 Oct 13 04:59:34.676462 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 13 04:59:34.676531 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-13T04:59:28 UTC (1760331568) Oct 13 04:59:34.676539 kernel: efifb: probing for efifb Oct 13 04:59:34.676545 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 13 04:59:34.676550 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 13 04:59:34.676555 kernel: efifb: scrolling: redraw Oct 13 04:59:34.676560 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 13 04:59:34.676566 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 04:59:34.676572 kernel: fb0: EFI VGA frame buffer device Oct 13 04:59:34.676577 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Oct 13 04:59:34.676582 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 13 04:59:34.676587 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 13 04:59:34.676593 kernel: NET: Registered PF_INET6 protocol family Oct 13 04:59:34.676598 kernel: watchdog: NMI not fully supported Oct 13 04:59:34.676603 kernel: watchdog: Hard watchdog permanently disabled Oct 13 04:59:34.676608 kernel: Segment Routing with IPv6 Oct 13 04:59:34.676614 kernel: In-situ OAM (IOAM) with IPv6 Oct 13 04:59:34.676619 kernel: NET: Registered PF_PACKET protocol family Oct 13 04:59:34.676624 kernel: Key type dns_resolver registered Oct 13 04:59:34.676630 kernel: registered taskstats version 1 Oct 13 04:59:34.676635 kernel: Loading compiled-in X.509 certificates Oct 13 04:59:34.676640 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.51-flatcar: 0d5be6bcdaeaf26c55e47d87e2567b03196058e4' Oct 13 04:59:34.676645 kernel: Demotion targets for Node 0: null Oct 13 04:59:34.676651 kernel: Key type .fscrypt registered Oct 13 04:59:34.676656 kernel: Key type fscrypt-provisioning registered Oct 13 04:59:34.676662 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 13 04:59:34.676669 kernel: ima: Allocated hash algorithm: sha1 Oct 13 04:59:34.676675 kernel: ima: No architecture policies found Oct 13 04:59:34.676680 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 13 04:59:34.676685 kernel: clk: Disabling unused clocks Oct 13 04:59:34.676691 kernel: PM: genpd: Disabling unused power domains Oct 13 04:59:34.676696 kernel: Freeing unused kernel memory: 12992K Oct 13 04:59:34.676701 kernel: Run /init as init process Oct 13 04:59:34.676706 kernel: with arguments: Oct 13 04:59:34.676711 kernel: /init Oct 13 04:59:34.676716 kernel: with environment: Oct 13 04:59:34.676721 kernel: HOME=/ Oct 13 04:59:34.676727 kernel: TERM=linux Oct 13 04:59:34.676733 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 13 04:59:34.676737 kernel: hv_vmbus: Vmbus version:5.3 Oct 13 04:59:34.676743 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.676748 kernel: hv_vmbus: registering driver hid_hyperv Oct 13 04:59:34.676753 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Oct 13 04:59:34.678913 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 13 04:59:34.678938 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.678944 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 13 04:59:34.678950 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Oct 13 04:59:34.678956 kernel: SCSI subsystem initialized Oct 13 04:59:34.678961 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.678966 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 13 04:59:34.678972 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 13 04:59:34.678979 kernel: PTP clock support registered Oct 13 04:59:34.678984 kernel: hv_utils: Registering HyperV Utility Driver Oct 13 04:59:34.678989 kernel: hv_vmbus: registering driver hv_utils Oct 13 04:59:34.678994 kernel: hv_utils: Heartbeat IC version 3.0 Oct 13 04:59:34.679000 kernel: hv_utils: Shutdown IC version 3.2 Oct 13 04:59:34.679005 kernel: hv_utils: TimeSync IC version 4.0 Oct 13 04:59:34.679010 kernel: hv_vmbus: registering driver hv_storvsc Oct 13 04:59:34.679127 kernel: scsi host1: storvsc_host_t Oct 13 04:59:34.679213 kernel: scsi host0: storvsc_host_t Oct 13 04:59:34.679299 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 04:59:34.679385 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Oct 13 04:59:34.679459 kernel: sd 0:0:0:0: [sda] 71737344 512-byte logical blocks: (36.7 GB/34.2 GiB) Oct 13 04:59:34.679535 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 13 04:59:34.679608 kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 13 04:59:34.679682 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 13 04:59:34.679754 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 04:59:34.679846 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#189 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 04:59:34.679916 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#132 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 04:59:34.679924 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 04:59:34.679997 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 13 04:59:34.680071 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 13 04:59:34.680078 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 13 04:59:34.680149 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 13 04:59:34.680156 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 13 04:59:34.680162 kernel: device-mapper: uevent: version 1.0.3 Oct 13 04:59:34.680168 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 13 04:59:34.680173 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 13 04:59:34.680179 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680184 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680189 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680194 kernel: raid6: neonx8 gen() 18540 MB/s Oct 13 04:59:34.680200 kernel: raid6: neonx4 gen() 18565 MB/s Oct 13 04:59:34.680206 kernel: raid6: neonx2 gen() 17100 MB/s Oct 13 04:59:34.680211 kernel: raid6: neonx1 gen() 15077 MB/s Oct 13 04:59:34.680216 kernel: raid6: int64x8 gen() 10539 MB/s Oct 13 04:59:34.680221 kernel: raid6: int64x4 gen() 10615 MB/s Oct 13 04:59:34.680226 kernel: raid6: int64x2 gen() 8980 MB/s Oct 13 04:59:34.680231 kernel: raid6: int64x1 gen() 6987 MB/s Oct 13 04:59:34.680236 kernel: raid6: using algorithm neonx4 gen() 18565 MB/s Oct 13 04:59:34.680242 kernel: raid6: .... xor() 15152 MB/s, rmw enabled Oct 13 04:59:34.680248 kernel: raid6: using neon recovery algorithm Oct 13 04:59:34.680253 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680258 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680263 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680268 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680273 kernel: xor: measuring software checksum speed Oct 13 04:59:34.680278 kernel: 8regs : 28657 MB/sec Oct 13 04:59:34.680284 kernel: 32regs : 28739 MB/sec Oct 13 04:59:34.680289 kernel: arm64_neon : 37569 MB/sec Oct 13 04:59:34.680294 kernel: xor: using function: arm64_neon (37569 MB/sec) Oct 13 04:59:34.680300 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680305 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 13 04:59:34.680310 kernel: BTRFS: device fsid 976d1a25-6e06-4ce9-b674-96d83e61f95d devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (380) Oct 13 04:59:34.680316 kernel: BTRFS info (device dm-0): first mount of filesystem 976d1a25-6e06-4ce9-b674-96d83e61f95d Oct 13 04:59:34.680322 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 13 04:59:34.680327 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 13 04:59:34.680332 kernel: BTRFS info (device dm-0): enabling free space tree Oct 13 04:59:34.680338 kernel: Invalid ELF header magic: != \u007fELF Oct 13 04:59:34.680343 kernel: loop: module loaded Oct 13 04:59:34.680348 kernel: loop0: detected capacity change from 0 to 91456 Oct 13 04:59:34.680353 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 13 04:59:34.680360 systemd[1]: Successfully made /usr/ read-only. Oct 13 04:59:34.680368 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 04:59:34.680374 systemd[1]: Detected virtualization microsoft. Oct 13 04:59:34.680379 systemd[1]: Detected architecture arm64. Oct 13 04:59:34.680385 systemd[1]: Running in initrd. Oct 13 04:59:34.680390 systemd[1]: No hostname configured, using default hostname. Oct 13 04:59:34.680397 systemd[1]: Hostname set to . Oct 13 04:59:34.680402 systemd[1]: Initializing machine ID from random generator. Oct 13 04:59:34.680408 systemd[1]: Queued start job for default target initrd.target. Oct 13 04:59:34.680414 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 13 04:59:34.680419 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 04:59:34.680425 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 04:59:34.680432 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 13 04:59:34.680437 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 04:59:34.680445 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 13 04:59:34.680456 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 13 04:59:34.680464 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 04:59:34.680470 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 04:59:34.680475 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 13 04:59:34.680481 systemd[1]: Reached target paths.target - Path Units. Oct 13 04:59:34.680487 systemd[1]: Reached target slices.target - Slice Units. Oct 13 04:59:34.680493 systemd[1]: Reached target swap.target - Swaps. Oct 13 04:59:34.680498 systemd[1]: Reached target timers.target - Timer Units. Oct 13 04:59:34.680505 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 04:59:34.680511 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 04:59:34.680517 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 13 04:59:34.680523 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 13 04:59:34.680529 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 04:59:34.680534 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 04:59:34.680540 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 04:59:34.680547 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 04:59:34.680553 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 13 04:59:34.680559 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 13 04:59:34.680565 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 04:59:34.680571 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 13 04:59:34.680577 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 13 04:59:34.680584 systemd[1]: Starting systemd-fsck-usr.service... Oct 13 04:59:34.680589 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 04:59:34.680595 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 04:59:34.680616 systemd-journald[517]: Collecting audit messages is disabled. Oct 13 04:59:34.680631 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 04:59:34.680639 systemd-journald[517]: Journal started Oct 13 04:59:34.680654 systemd-journald[517]: Runtime Journal (/run/log/journal/25c59d322c55432eb4c4428d4a8cacfb) is 8M, max 78.3M, 70.3M free. Oct 13 04:59:34.697442 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 04:59:34.698024 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 13 04:59:34.703840 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 04:59:34.716292 systemd[1]: Finished systemd-fsck-usr.service. Oct 13 04:59:34.721477 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 04:59:34.742597 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 04:59:34.771837 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 13 04:59:34.844884 kernel: Bridge firewalling registered Oct 13 04:59:34.844879 systemd-modules-load[520]: Inserted module 'br_netfilter' Oct 13 04:59:34.845528 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 04:59:34.848914 systemd-tmpfiles[529]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 13 04:59:34.865661 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 04:59:34.876618 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 04:59:34.884042 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 04:59:34.894955 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 04:59:34.925705 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 04:59:34.937168 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 04:59:34.945724 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 04:59:34.952845 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 04:59:34.971765 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 04:59:35.019100 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 04:59:35.030977 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 13 04:59:35.160492 systemd-resolved[546]: Positive Trust Anchors: Oct 13 04:59:35.160506 systemd-resolved[546]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 04:59:35.160508 systemd-resolved[546]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 13 04:59:35.160528 systemd-resolved[546]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 13 04:59:35.258694 dracut-cmdline[558]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=1a81e36b39d22063d1d9b2ac3307af6d1e57cfd926c8fafd214fb74284e73d99 Oct 13 04:59:35.260412 systemd-resolved[546]: Defaulting to hostname 'linux'. Oct 13 04:59:35.261193 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 04:59:35.289427 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 04:59:35.473845 kernel: Loading iSCSI transport class v2.0-870. Oct 13 04:59:35.540844 kernel: iscsi: registered transport (tcp) Oct 13 04:59:35.584513 kernel: iscsi: registered transport (qla4xxx) Oct 13 04:59:35.584579 kernel: QLogic iSCSI HBA Driver Oct 13 04:59:35.683490 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 04:59:35.703912 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 04:59:35.710863 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 04:59:35.759876 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 13 04:59:35.769307 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 13 04:59:35.784077 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 13 04:59:35.803584 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 13 04:59:35.814974 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 04:59:35.923644 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 04:59:35.949950 systemd-udevd[765]: Using default interface naming scheme 'v257'. Oct 13 04:59:35.955226 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 04:59:35.963996 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 13 04:59:35.986908 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 04:59:36.000529 dracut-pre-trigger[899]: rd.md=0: removing MD RAID activation Oct 13 04:59:36.024615 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 04:59:36.036143 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 04:59:36.039198 systemd-networkd[900]: lo: Link UP Oct 13 04:59:36.039201 systemd-networkd[900]: lo: Gained carrier Oct 13 04:59:36.047320 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 04:59:36.052396 systemd[1]: Reached target network.target - Network. Oct 13 04:59:36.094981 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 04:59:36.104347 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 13 04:59:36.189950 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 04:59:36.193304 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 04:59:36.203339 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 04:59:36.210184 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 04:59:36.241454 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 04:59:36.260111 kernel: hv_vmbus: registering driver hv_netvsc Oct 13 04:59:36.260133 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#62 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Oct 13 04:59:36.242852 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 04:59:36.265967 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 04:59:36.317245 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 04:59:36.354327 kernel: hv_netvsc 0022487b-36f9-0022-487b-36f90022487b eth0: VF slot 1 added Oct 13 04:59:36.359622 systemd-networkd[900]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 04:59:36.359630 systemd-networkd[900]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 04:59:36.361100 systemd-networkd[900]: eth0: Link UP Oct 13 04:59:36.361168 systemd-networkd[900]: eth0: Gained carrier Oct 13 04:59:36.361177 systemd-networkd[900]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 04:59:36.392835 kernel: hv_vmbus: registering driver hv_pci Oct 13 04:59:36.399206 kernel: hv_pci 4880cd01-d90b-40c8-9d58-3e95a3abf397: PCI VMBus probing: Using version 0x10004 Oct 13 04:59:36.399401 kernel: hv_pci 4880cd01-d90b-40c8-9d58-3e95a3abf397: PCI host bridge to bus d90b:00 Oct 13 04:59:36.408399 kernel: pci_bus d90b:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Oct 13 04:59:36.412490 kernel: pci_bus d90b:00: No busn resource found for root bus, will use [bus 00-ff] Oct 13 04:59:36.419140 kernel: pci d90b:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Oct 13 04:59:36.423913 kernel: pci d90b:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Oct 13 04:59:36.428010 systemd-networkd[900]: eth0: DHCPv4 address 10.200.20.22/24, gateway 10.200.20.1 acquired from 168.63.129.16 Oct 13 04:59:36.434490 kernel: pci d90b:00:02.0: enabling Extended Tags Oct 13 04:59:36.444857 kernel: pci d90b:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at d90b:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Oct 13 04:59:36.454252 kernel: pci_bus d90b:00: busn_res: [bus 00-ff] end is updated to 00 Oct 13 04:59:36.454435 kernel: pci d90b:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Oct 13 04:59:36.716096 kernel: mlx5_core d90b:00:02.0: enabling device (0000 -> 0002) Oct 13 04:59:36.724835 kernel: mlx5_core d90b:00:02.0: PTM is not supported by PCIe Oct 13 04:59:36.725074 kernel: mlx5_core d90b:00:02.0: firmware version: 16.30.5006 Oct 13 04:59:36.877359 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 13 04:59:36.883973 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 13 04:59:36.924869 kernel: hv_netvsc 0022487b-36f9-0022-487b-36f90022487b eth0: VF registering: eth1 Oct 13 04:59:36.925101 kernel: mlx5_core d90b:00:02.0 eth1: joined to eth0 Oct 13 04:59:36.930411 kernel: mlx5_core d90b:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Oct 13 04:59:36.939221 systemd-networkd[900]: eth1: Interface name change detected, renamed to enP55563s1. Oct 13 04:59:36.944261 kernel: mlx5_core d90b:00:02.0 enP55563s1: renamed from eth1 Oct 13 04:59:37.057417 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 04:59:37.074956 kernel: mlx5_core d90b:00:02.0 enP55563s1: Link up Oct 13 04:59:37.078001 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 13 04:59:37.097063 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 13 04:59:37.115843 kernel: hv_netvsc 0022487b-36f9-0022-487b-36f90022487b eth0: Data path switched to VF: enP55563s1 Oct 13 04:59:37.115799 systemd-networkd[900]: enP55563s1: Link UP Oct 13 04:59:37.224789 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 13 04:59:37.229717 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 04:59:37.238772 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 04:59:37.248598 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 04:59:37.264981 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 13 04:59:37.297183 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 13 04:59:37.363165 systemd-networkd[900]: enP55563s1: Gained carrier Oct 13 04:59:37.731322 systemd-networkd[900]: eth0: Gained IPv6LL Oct 13 04:59:38.271483 disk-uuid[1017]: Warning: The kernel is still using the old partition table. Oct 13 04:59:38.271483 disk-uuid[1017]: The new table will be used at the next reboot or after you Oct 13 04:59:38.271483 disk-uuid[1017]: run partprobe(8) or kpartx(8) Oct 13 04:59:38.271483 disk-uuid[1017]: The operation has completed successfully. Oct 13 04:59:38.281900 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 13 04:59:38.282005 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 13 04:59:38.289227 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 13 04:59:38.355838 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1177) Oct 13 04:59:38.365530 kernel: BTRFS info (device sda6): first mount of filesystem e9d5eae2-c289-4bda-a378-1699d81be8dc Oct 13 04:59:38.365563 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Oct 13 04:59:38.457633 kernel: BTRFS info (device sda6): turning on async discard Oct 13 04:59:38.457713 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 04:59:38.466844 kernel: BTRFS info (device sda6): last unmount of filesystem e9d5eae2-c289-4bda-a378-1699d81be8dc Oct 13 04:59:38.467945 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 13 04:59:38.477502 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 13 04:59:40.879852 ignition[1196]: Ignition 2.22.0 Oct 13 04:59:40.879864 ignition[1196]: Stage: fetch-offline Oct 13 04:59:40.879971 ignition[1196]: no configs at "/usr/lib/ignition/base.d" Oct 13 04:59:40.885166 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 04:59:40.879979 ignition[1196]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 04:59:40.895368 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 13 04:59:40.880065 ignition[1196]: parsed url from cmdline: "" Oct 13 04:59:40.880068 ignition[1196]: no config URL provided Oct 13 04:59:40.880071 ignition[1196]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 04:59:40.880078 ignition[1196]: no config at "/usr/lib/ignition/user.ign" Oct 13 04:59:40.880081 ignition[1196]: failed to fetch config: resource requires networking Oct 13 04:59:40.882709 ignition[1196]: Ignition finished successfully Oct 13 04:59:40.929907 ignition[1204]: Ignition 2.22.0 Oct 13 04:59:40.929919 ignition[1204]: Stage: fetch Oct 13 04:59:40.930136 ignition[1204]: no configs at "/usr/lib/ignition/base.d" Oct 13 04:59:40.930144 ignition[1204]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 04:59:40.930221 ignition[1204]: parsed url from cmdline: "" Oct 13 04:59:40.930224 ignition[1204]: no config URL provided Oct 13 04:59:40.930228 ignition[1204]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 04:59:40.930233 ignition[1204]: no config at "/usr/lib/ignition/user.ign" Oct 13 04:59:40.930250 ignition[1204]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 13 04:59:41.003554 ignition[1204]: GET result: OK Oct 13 04:59:41.003612 ignition[1204]: config has been read from IMDS userdata Oct 13 04:59:41.003630 ignition[1204]: parsing config with SHA512: 3149be8d8d4ff3005df197d3c03caea0adb49ba0b47e1dfe5987e64a90bc283fb63833d0dfde403ac8bd0e5f0ec98c906d5861ca840e7c36121838b2d63f2d10 Oct 13 04:59:41.006131 unknown[1204]: fetched base config from "system" Oct 13 04:59:41.006294 ignition[1204]: fetch: fetch complete Oct 13 04:59:41.006138 unknown[1204]: fetched base config from "system" Oct 13 04:59:41.006298 ignition[1204]: fetch: fetch passed Oct 13 04:59:41.006142 unknown[1204]: fetched user config from "azure" Oct 13 04:59:41.006345 ignition[1204]: Ignition finished successfully Oct 13 04:59:41.010843 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 13 04:59:41.017964 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 13 04:59:41.058305 ignition[1210]: Ignition 2.22.0 Oct 13 04:59:41.060758 ignition[1210]: Stage: kargs Oct 13 04:59:41.060975 ignition[1210]: no configs at "/usr/lib/ignition/base.d" Oct 13 04:59:41.064035 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 13 04:59:41.060982 ignition[1210]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 04:59:41.073165 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 13 04:59:41.061383 ignition[1210]: kargs: kargs passed Oct 13 04:59:41.061431 ignition[1210]: Ignition finished successfully Oct 13 04:59:41.105872 ignition[1216]: Ignition 2.22.0 Oct 13 04:59:41.105882 ignition[1216]: Stage: disks Oct 13 04:59:41.109183 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 13 04:59:41.106057 ignition[1216]: no configs at "/usr/lib/ignition/base.d" Oct 13 04:59:41.115790 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 13 04:59:41.106064 ignition[1216]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 04:59:41.123224 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 13 04:59:41.106497 ignition[1216]: disks: disks passed Oct 13 04:59:41.131716 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 04:59:41.106534 ignition[1216]: Ignition finished successfully Oct 13 04:59:41.139983 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 04:59:41.148014 systemd[1]: Reached target basic.target - Basic System. Oct 13 04:59:41.156759 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 13 04:59:41.387067 systemd-fsck[1225]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks Oct 13 04:59:41.395370 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 13 04:59:41.406982 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 13 04:59:44.582855 kernel: EXT4-fs (sda9): mounted filesystem a42694d5-feb9-4394-9ac1-a45818242d2d r/w with ordered data mode. Quota mode: none. Oct 13 04:59:44.583410 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 13 04:59:44.587583 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 13 04:59:44.672666 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 04:59:44.702830 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1239) Oct 13 04:59:44.711961 kernel: BTRFS info (device sda6): first mount of filesystem e9d5eae2-c289-4bda-a378-1699d81be8dc Oct 13 04:59:44.712011 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Oct 13 04:59:44.708724 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 13 04:59:44.719614 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 13 04:59:44.738536 kernel: BTRFS info (device sda6): turning on async discard Oct 13 04:59:44.738559 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 04:59:44.729911 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 13 04:59:44.729951 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 04:59:44.744345 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 04:59:44.755858 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 13 04:59:44.765302 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 13 04:59:45.890705 coreos-metadata[1255]: Oct 13 04:59:45.890 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 04:59:45.899699 coreos-metadata[1255]: Oct 13 04:59:45.899 INFO Fetch successful Oct 13 04:59:45.903805 coreos-metadata[1255]: Oct 13 04:59:45.903 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 13 04:59:45.912679 coreos-metadata[1255]: Oct 13 04:59:45.912 INFO Fetch successful Oct 13 04:59:45.918989 coreos-metadata[1255]: Oct 13 04:59:45.913 INFO wrote hostname ci-4487.0.0-a-8fed64613a to /sysroot/etc/hostname Oct 13 04:59:45.918906 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 04:59:46.585687 initrd-setup-root[1270]: cut: /sysroot/etc/passwd: No such file or directory Oct 13 04:59:46.700105 initrd-setup-root[1277]: cut: /sysroot/etc/group: No such file or directory Oct 13 04:59:46.738474 initrd-setup-root[1284]: cut: /sysroot/etc/shadow: No such file or directory Oct 13 04:59:46.743914 initrd-setup-root[1291]: cut: /sysroot/etc/gshadow: No such file or directory Oct 13 04:59:48.494053 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 13 04:59:48.500925 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 13 04:59:48.516539 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 13 04:59:48.573041 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 13 04:59:48.582841 kernel: BTRFS info (device sda6): last unmount of filesystem e9d5eae2-c289-4bda-a378-1699d81be8dc Oct 13 04:59:48.593286 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 13 04:59:48.607024 ignition[1364]: INFO : Ignition 2.22.0 Oct 13 04:59:48.607024 ignition[1364]: INFO : Stage: mount Oct 13 04:59:48.613420 ignition[1364]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 04:59:48.613420 ignition[1364]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 04:59:48.613420 ignition[1364]: INFO : mount: mount passed Oct 13 04:59:48.613420 ignition[1364]: INFO : Ignition finished successfully Oct 13 04:59:48.613936 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 13 04:59:48.625925 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 13 04:59:48.648714 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 04:59:48.677835 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1372) Oct 13 04:59:48.687222 kernel: BTRFS info (device sda6): first mount of filesystem e9d5eae2-c289-4bda-a378-1699d81be8dc Oct 13 04:59:48.687241 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Oct 13 04:59:48.696055 kernel: BTRFS info (device sda6): turning on async discard Oct 13 04:59:48.696072 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 04:59:48.697717 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 04:59:48.727009 ignition[1389]: INFO : Ignition 2.22.0 Oct 13 04:59:48.727009 ignition[1389]: INFO : Stage: files Oct 13 04:59:48.732679 ignition[1389]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 04:59:48.732679 ignition[1389]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 04:59:48.732679 ignition[1389]: DEBUG : files: compiled without relabeling support, skipping Oct 13 04:59:48.784057 ignition[1389]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 13 04:59:48.784057 ignition[1389]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 13 04:59:48.912674 ignition[1389]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 13 04:59:48.918167 ignition[1389]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 13 04:59:48.918167 ignition[1389]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 13 04:59:48.913087 unknown[1389]: wrote ssh authorized keys file for user: core Oct 13 04:59:48.983097 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 13 04:59:48.990505 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 13 04:59:48.997452 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 04:59:48.997452 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 04:59:48.997452 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 13 04:59:49.022228 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 13 04:59:49.022228 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 13 04:59:49.022228 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Oct 13 04:59:49.528634 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 13 04:59:49.824871 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 13 04:59:49.834100 ignition[1389]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 13 04:59:49.834100 ignition[1389]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 13 04:59:49.834100 ignition[1389]: INFO : files: files passed Oct 13 04:59:49.834100 ignition[1389]: INFO : Ignition finished successfully Oct 13 04:59:49.834510 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 13 04:59:49.846962 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 13 04:59:49.865961 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 13 04:59:49.873313 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 13 04:59:49.873388 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 13 04:59:49.921130 initrd-setup-root-after-ignition[1418]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 04:59:49.921130 initrd-setup-root-after-ignition[1418]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 13 04:59:49.934056 initrd-setup-root-after-ignition[1422]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 04:59:49.929048 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 04:59:49.939110 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 13 04:59:49.949421 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 13 04:59:50.000742 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 13 04:59:50.000874 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 13 04:59:50.009679 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 13 04:59:50.018255 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 13 04:59:50.026097 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 13 04:59:50.026923 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 13 04:59:50.063895 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 04:59:50.075918 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 13 04:59:50.100523 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 13 04:59:50.100628 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 13 04:59:50.110019 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 04:59:50.119286 systemd[1]: Stopped target timers.target - Timer Units. Oct 13 04:59:50.127431 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 13 04:59:50.127557 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 04:59:50.138178 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 13 04:59:50.142117 systemd[1]: Stopped target basic.target - Basic System. Oct 13 04:59:50.150620 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 13 04:59:50.159049 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 04:59:50.167103 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 13 04:59:50.176436 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 13 04:59:50.185495 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 13 04:59:50.213555 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 04:59:50.222665 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 13 04:59:50.230589 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 13 04:59:50.239675 systemd[1]: Stopped target swap.target - Swaps. Oct 13 04:59:50.246843 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 13 04:59:50.246968 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 13 04:59:50.257663 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 13 04:59:50.261853 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 04:59:50.270594 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 13 04:59:50.270656 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 04:59:50.279704 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 13 04:59:50.279802 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 13 04:59:50.291966 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 13 04:59:50.292062 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 04:59:50.296895 systemd[1]: ignition-files.service: Deactivated successfully. Oct 13 04:59:50.296958 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 13 04:59:50.304437 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 13 04:59:50.369991 ignition[1442]: INFO : Ignition 2.22.0 Oct 13 04:59:50.369991 ignition[1442]: INFO : Stage: umount Oct 13 04:59:50.369991 ignition[1442]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 04:59:50.369991 ignition[1442]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 04:59:50.369991 ignition[1442]: INFO : umount: umount passed Oct 13 04:59:50.369991 ignition[1442]: INFO : Ignition finished successfully Oct 13 04:59:50.304507 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 04:59:50.315280 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 13 04:59:50.328973 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 13 04:59:50.329102 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 04:59:50.346476 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 13 04:59:50.355016 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 13 04:59:50.355142 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 04:59:50.368007 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 13 04:59:50.368098 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 04:59:50.374735 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 13 04:59:50.374807 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 04:59:50.386485 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 13 04:59:50.386583 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 13 04:59:50.398276 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 13 04:59:50.398361 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 13 04:59:50.406903 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 13 04:59:50.406952 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 13 04:59:50.413938 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 13 04:59:50.413974 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 13 04:59:50.421446 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 13 04:59:50.421482 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 13 04:59:50.430582 systemd[1]: Stopped target network.target - Network. Oct 13 04:59:50.437895 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 13 04:59:50.437945 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 04:59:50.446183 systemd[1]: Stopped target paths.target - Path Units. Oct 13 04:59:50.454168 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 13 04:59:50.457838 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 04:59:50.463684 systemd[1]: Stopped target slices.target - Slice Units. Oct 13 04:59:50.471341 systemd[1]: Stopped target sockets.target - Socket Units. Oct 13 04:59:50.480002 systemd[1]: iscsid.socket: Deactivated successfully. Oct 13 04:59:50.480046 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 04:59:50.488246 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 13 04:59:50.488273 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 04:59:50.497145 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 13 04:59:50.497193 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 13 04:59:50.504854 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 13 04:59:50.504888 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 13 04:59:50.514114 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 13 04:59:50.521987 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 13 04:59:50.540056 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 13 04:59:50.541657 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 13 04:59:50.729455 kernel: hv_netvsc 0022487b-36f9-0022-487b-36f90022487b eth0: Data path switched from VF: enP55563s1 Oct 13 04:59:50.541764 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 13 04:59:50.553402 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 13 04:59:50.553478 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 13 04:59:50.566298 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 13 04:59:50.574079 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 13 04:59:50.574125 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 13 04:59:50.586946 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 13 04:59:50.599204 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 13 04:59:50.599273 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 04:59:50.615738 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 13 04:59:50.615788 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 13 04:59:50.624492 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 13 04:59:50.624526 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 13 04:59:50.634922 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 04:59:50.661514 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 13 04:59:50.661654 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 04:59:50.670862 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 13 04:59:50.670921 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 13 04:59:50.678659 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 13 04:59:50.678690 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 04:59:50.686972 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 13 04:59:50.687023 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 13 04:59:50.700022 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 13 04:59:50.700066 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 13 04:59:50.718729 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 04:59:50.718780 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 04:59:50.730391 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 13 04:59:50.744693 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 13 04:59:50.744774 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 04:59:50.753089 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 13 04:59:50.753136 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 04:59:50.763893 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 04:59:50.763958 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 04:59:50.777859 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 13 04:59:50.777951 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 13 04:59:50.784962 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 13 04:59:50.785036 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 13 04:59:50.795295 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 13 04:59:50.795408 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 13 04:59:50.822759 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 13 04:59:50.822910 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 13 04:59:50.832195 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 13 04:59:50.840177 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 13 04:59:50.887349 systemd[1]: Switching root. Oct 13 04:59:51.037060 systemd-journald[517]: Journal stopped Oct 13 05:00:05.036561 systemd-journald[517]: Received SIGTERM from PID 1 (systemd). Oct 13 05:00:05.036582 kernel: SELinux: policy capability network_peer_controls=1 Oct 13 05:00:05.036591 kernel: SELinux: policy capability open_perms=1 Oct 13 05:00:05.036598 kernel: SELinux: policy capability extended_socket_class=1 Oct 13 05:00:05.036604 kernel: SELinux: policy capability always_check_network=0 Oct 13 05:00:05.036610 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 13 05:00:05.036617 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 13 05:00:05.036623 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 13 05:00:05.036628 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 13 05:00:05.036635 kernel: SELinux: policy capability userspace_initial_context=0 Oct 13 05:00:05.036641 systemd[1]: Successfully loaded SELinux policy in 302.879ms. Oct 13 05:00:05.036648 kernel: audit: type=1403 audit(1760331592.920:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 13 05:00:05.036654 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 4.629ms. Oct 13 05:00:05.036661 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 05:00:05.036670 systemd[1]: Detected virtualization microsoft. Oct 13 05:00:05.036676 systemd[1]: Detected architecture arm64. Oct 13 05:00:05.036682 systemd[1]: Detected first boot. Oct 13 05:00:05.036689 systemd[1]: Hostname set to . Oct 13 05:00:05.036695 systemd[1]: Initializing machine ID from random generator. Oct 13 05:00:05.036703 zram_generator::config[1485]: No configuration found. Oct 13 05:00:05.036710 kernel: NET: Registered PF_VSOCK protocol family Oct 13 05:00:05.036716 systemd[1]: Populated /etc with preset unit settings. Oct 13 05:00:05.036722 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 13 05:00:05.036728 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 13 05:00:05.036735 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 13 05:00:05.036743 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 13 05:00:05.036749 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 13 05:00:05.036756 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 13 05:00:05.036762 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 13 05:00:05.036769 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 13 05:00:05.036775 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 13 05:00:05.036783 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 13 05:00:05.036789 systemd[1]: Created slice user.slice - User and Session Slice. Oct 13 05:00:05.036796 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:00:05.036802 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:00:05.036811 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 13 05:00:05.036825 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 13 05:00:05.036832 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 13 05:00:05.036839 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 05:00:05.036845 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 13 05:00:05.036853 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:00:05.036860 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:00:05.036866 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 13 05:00:05.036874 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 13 05:00:05.036880 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 13 05:00:05.036887 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 13 05:00:05.036893 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:00:05.036900 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 05:00:05.036906 systemd[1]: Reached target slices.target - Slice Units. Oct 13 05:00:05.036913 systemd[1]: Reached target swap.target - Swaps. Oct 13 05:00:05.036920 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 13 05:00:05.036927 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 13 05:00:05.036933 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 13 05:00:05.036940 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:00:05.036947 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 05:00:05.036954 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:00:05.036961 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 13 05:00:05.036968 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 13 05:00:05.036974 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 13 05:00:05.036981 systemd[1]: Mounting media.mount - External Media Directory... Oct 13 05:00:05.036988 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 13 05:00:05.036995 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 13 05:00:05.037001 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 13 05:00:05.037008 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 13 05:00:05.037015 systemd[1]: Reached target machines.target - Containers. Oct 13 05:00:05.037022 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 13 05:00:05.037028 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:00:05.037036 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 05:00:05.037042 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 13 05:00:05.037049 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:00:05.037056 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:00:05.037062 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:00:05.037069 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 13 05:00:05.037075 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:00:05.037083 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 13 05:00:05.037090 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 13 05:00:05.037096 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 13 05:00:05.037103 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 13 05:00:05.037110 systemd[1]: Stopped systemd-fsck-usr.service. Oct 13 05:00:05.037117 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 13 05:00:05.037124 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 05:00:05.037131 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 05:00:05.037138 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 05:00:05.037145 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 13 05:00:05.037151 kernel: fuse: init (API version 7.41) Oct 13 05:00:05.037157 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 13 05:00:05.037164 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 05:00:05.037171 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 13 05:00:05.037178 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 13 05:00:05.037184 systemd[1]: Mounted media.mount - External Media Directory. Oct 13 05:00:05.037191 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 13 05:00:05.037197 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 13 05:00:05.037204 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 13 05:00:05.037221 systemd-journald[1565]: Collecting audit messages is disabled. Oct 13 05:00:05.037236 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 13 05:00:05.037244 systemd-journald[1565]: Journal started Oct 13 05:00:05.037259 systemd-journald[1565]: Runtime Journal (/run/log/journal/ce78ec3499d74a9e8193064b2813b709) is 8M, max 78.3M, 70.3M free. Oct 13 05:00:04.106420 systemd[1]: Queued start job for default target multi-user.target. Oct 13 05:00:04.113357 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Oct 13 05:00:04.113828 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 13 05:00:04.114110 systemd[1]: systemd-journald.service: Consumed 2.184s CPU time. Oct 13 05:00:05.052939 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 05:00:05.055258 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:00:05.063678 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 13 05:00:05.063890 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 13 05:00:05.071685 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:00:05.071845 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:00:05.072838 kernel: ACPI: bus type drm_connector registered Oct 13 05:00:05.077706 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:00:05.077873 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:00:05.082301 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:00:05.082442 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:00:05.087627 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 13 05:00:05.087757 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 13 05:00:05.092296 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:00:05.092430 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:00:05.096978 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 05:00:05.102005 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:00:05.108554 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 13 05:00:05.121501 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 05:00:05.126343 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 13 05:00:05.133941 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 13 05:00:05.141651 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 13 05:00:05.148856 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 13 05:00:05.148888 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 05:00:05.153781 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 13 05:00:05.158948 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:00:05.159866 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 13 05:00:05.175976 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 13 05:00:05.181052 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:00:05.187388 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 13 05:00:05.194135 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:00:05.204059 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 05:00:05.210309 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 13 05:00:05.215848 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 13 05:00:05.221866 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 13 05:00:05.228530 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:00:05.234333 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 13 05:00:05.239777 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 13 05:00:05.264087 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 13 05:00:05.269317 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 13 05:00:05.276098 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 13 05:00:05.335462 systemd-journald[1565]: Time spent on flushing to /var/log/journal/ce78ec3499d74a9e8193064b2813b709 is 17.995ms for 915 entries. Oct 13 05:00:05.335462 systemd-journald[1565]: System Journal (/var/log/journal/ce78ec3499d74a9e8193064b2813b709) is 8M, max 2.6G, 2.6G free. Oct 13 05:00:05.373709 systemd-journald[1565]: Received client request to flush runtime journal. Oct 13 05:00:05.375118 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 13 05:00:05.382637 kernel: loop1: detected capacity change from 0 to 100624 Oct 13 05:00:05.417417 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 13 05:00:05.418052 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 13 05:00:05.466118 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:00:06.333843 kernel: loop2: detected capacity change from 0 to 211168 Oct 13 05:00:06.435843 kernel: loop3: detected capacity change from 0 to 119344 Oct 13 05:00:06.452358 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 13 05:00:06.458840 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 05:00:06.464112 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 05:00:06.501888 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 13 05:00:06.581401 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 13 05:00:06.639037 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 13 05:00:06.751346 systemd-tmpfiles[1642]: ACLs are not supported, ignoring. Oct 13 05:00:06.751718 systemd-tmpfiles[1642]: ACLs are not supported, ignoring. Oct 13 05:00:06.754741 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:00:06.763001 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:00:06.791668 systemd-udevd[1653]: Using default interface naming scheme 'v257'. Oct 13 05:00:06.807376 systemd-resolved[1641]: Positive Trust Anchors: Oct 13 05:00:06.807393 systemd-resolved[1641]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 05:00:06.807398 systemd-resolved[1641]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 13 05:00:06.807418 systemd-resolved[1641]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 13 05:00:07.029264 systemd-resolved[1641]: Using system hostname 'ci-4487.0.0-a-8fed64613a'. Oct 13 05:00:07.060794 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 05:00:07.065515 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:00:07.291851 kernel: loop4: detected capacity change from 0 to 27760 Oct 13 05:00:07.458854 kernel: loop5: detected capacity change from 0 to 100624 Oct 13 05:00:07.470864 kernel: loop6: detected capacity change from 0 to 211168 Oct 13 05:00:07.485836 kernel: loop7: detected capacity change from 0 to 119344 Oct 13 05:00:07.497836 kernel: loop1: detected capacity change from 0 to 27760 Oct 13 05:00:07.508299 (sd-merge)[1656]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw', 'oem-azure.raw'. Oct 13 05:00:07.510687 (sd-merge)[1656]: Merged extensions into '/usr'. Oct 13 05:00:07.514107 systemd[1]: Reload requested from client PID 1623 ('systemd-sysext') (unit systemd-sysext.service)... Oct 13 05:00:07.514355 systemd[1]: Reloading... Oct 13 05:00:07.561844 zram_generator::config[1680]: No configuration found. Oct 13 05:00:07.718434 systemd[1]: Reloading finished in 203 ms. Oct 13 05:00:07.737859 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 13 05:00:07.754107 systemd[1]: Starting ensure-sysext.service... Oct 13 05:00:07.758508 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 05:00:07.775175 systemd-tmpfiles[1740]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 13 05:00:07.775197 systemd-tmpfiles[1740]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 13 05:00:07.775351 systemd-tmpfiles[1740]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 13 05:00:07.775493 systemd-tmpfiles[1740]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 13 05:00:07.775931 systemd-tmpfiles[1740]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 13 05:00:07.776154 systemd-tmpfiles[1740]: ACLs are not supported, ignoring. Oct 13 05:00:07.776201 systemd-tmpfiles[1740]: ACLs are not supported, ignoring. Oct 13 05:00:07.784562 systemd[1]: Reload requested from client PID 1739 ('systemctl') (unit ensure-sysext.service)... Oct 13 05:00:07.784689 systemd[1]: Reloading... Oct 13 05:00:07.835848 zram_generator::config[1770]: No configuration found. Oct 13 05:00:07.924853 systemd-tmpfiles[1740]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:00:07.924865 systemd-tmpfiles[1740]: Skipping /boot Oct 13 05:00:07.930253 systemd-tmpfiles[1740]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:00:07.930267 systemd-tmpfiles[1740]: Skipping /boot Oct 13 05:00:07.975141 systemd[1]: Reloading finished in 190 ms. Oct 13 05:00:07.995062 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:00:08.017295 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 05:00:08.040989 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 13 05:00:08.056485 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 13 05:00:08.062318 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 13 05:00:08.071987 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 13 05:00:08.086780 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Oct 13 05:00:08.091845 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:00:08.093189 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:00:08.100556 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:00:08.112147 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:00:08.120540 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:00:08.125483 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:00:08.125661 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 13 05:00:08.125984 systemd[1]: Reached target time-set.target - System Time Set. Oct 13 05:00:08.132611 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:00:08.132792 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:00:08.139676 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:00:08.139834 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:00:08.147910 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:00:08.148872 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:00:08.155111 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:00:08.155420 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:00:08.163063 systemd[1]: Finished ensure-sysext.service. Oct 13 05:00:08.172001 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:00:08.172124 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:00:08.173194 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 13 05:00:08.238617 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:00:08.250014 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 05:00:08.278528 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 13 05:00:08.404846 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#147 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Oct 13 05:00:08.417870 kernel: hv_vmbus: registering driver hv_balloon Oct 13 05:00:08.417963 kernel: mousedev: PS/2 mouse device common for all mice Oct 13 05:00:08.417984 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 13 05:00:08.424261 kernel: hv_balloon: Memory hot add disabled on ARM64 Oct 13 05:00:08.435080 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Oct 13 05:00:08.451973 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:00:08.467838 kernel: hv_vmbus: registering driver hyperv_fb Oct 13 05:00:08.467952 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 13 05:00:08.479021 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 13 05:00:08.487467 kernel: Console: switching to colour dummy device 80x25 Oct 13 05:00:08.493009 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 05:00:08.493657 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:00:08.494030 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:00:08.508366 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:00:08.524162 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:00:08.526142 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:00:08.536625 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:00:08.637268 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 13 05:00:08.987154 augenrules[1932]: No rules Oct 13 05:00:08.988502 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 05:00:08.988804 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 05:00:09.028247 systemd-networkd[1858]: lo: Link UP Oct 13 05:00:09.028254 systemd-networkd[1858]: lo: Gained carrier Oct 13 05:00:09.029380 systemd-networkd[1858]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:00:09.029386 systemd-networkd[1858]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:00:09.029495 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 05:00:09.034654 systemd[1]: Reached target network.target - Network. Oct 13 05:00:09.039575 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 13 05:00:09.045944 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 13 05:00:09.062836 kernel: MACsec IEEE 802.1AE Oct 13 05:00:09.078845 kernel: mlx5_core d90b:00:02.0 enP55563s1: Link up Oct 13 05:00:09.104202 kernel: hv_netvsc 0022487b-36f9-0022-487b-36f90022487b eth0: Data path switched to VF: enP55563s1 Oct 13 05:00:09.104265 systemd-networkd[1858]: enP55563s1: Link UP Oct 13 05:00:09.104383 systemd-networkd[1858]: eth0: Link UP Oct 13 05:00:09.104386 systemd-networkd[1858]: eth0: Gained carrier Oct 13 05:00:09.104400 systemd-networkd[1858]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:00:09.108042 systemd-networkd[1858]: enP55563s1: Gained carrier Oct 13 05:00:09.113863 systemd-networkd[1858]: eth0: DHCPv4 address 10.200.20.22/24, gateway 10.200.20.1 acquired from 168.63.129.16 Oct 13 05:00:09.177240 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 13 05:00:09.373055 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 05:00:09.379865 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 13 05:00:09.490847 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 13 05:00:10.306948 systemd-networkd[1858]: eth0: Gained IPv6LL Oct 13 05:00:10.308932 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 13 05:00:10.314677 systemd[1]: Reached target network-online.target - Network is Online. Oct 13 05:00:11.230980 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:00:13.529984 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 13 05:00:13.536531 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 13 05:00:24.022771 ldconfig[1829]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 05:00:24.037377 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 13 05:00:24.044289 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 13 05:00:24.082623 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 13 05:00:24.088621 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 05:00:24.093864 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 13 05:00:24.099281 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 13 05:00:24.104694 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 13 05:00:24.108941 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 13 05:00:24.114124 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 13 05:00:24.119118 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 13 05:00:24.119155 systemd[1]: Reached target paths.target - Path Units. Oct 13 05:00:24.122493 systemd[1]: Reached target timers.target - Timer Units. Oct 13 05:00:24.200899 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 13 05:00:24.206522 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 13 05:00:24.211705 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 13 05:00:24.216771 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 13 05:00:24.220909 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 13 05:00:24.226105 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 13 05:00:24.259050 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 13 05:00:24.264766 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 13 05:00:24.268768 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 05:00:24.272334 systemd[1]: Reached target basic.target - Basic System. Oct 13 05:00:24.276338 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:00:24.276366 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:00:24.311312 systemd[1]: Starting chronyd.service - NTP client/server... Oct 13 05:00:24.322936 systemd[1]: Starting containerd.service - containerd container runtime... Oct 13 05:00:24.328126 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 13 05:00:24.334968 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 13 05:00:24.342294 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 13 05:00:24.351937 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 13 05:00:24.359763 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 13 05:00:24.364132 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 13 05:00:24.365060 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 13 05:00:24.370095 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 13 05:00:24.371190 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 13 05:00:24.377189 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 13 05:00:24.382784 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 13 05:00:24.392433 jq[2019]: false Oct 13 05:00:24.412893 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 13 05:00:24.430009 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 13 05:00:24.436550 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 13 05:00:24.440604 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 13 05:00:24.441041 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 13 05:00:24.442959 systemd[1]: Starting update-engine.service - Update Engine... Oct 13 05:00:24.448529 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 13 05:00:24.457073 jq[2037]: true Oct 13 05:00:24.458197 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 13 05:00:24.466149 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 13 05:00:24.466326 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 13 05:00:24.466434 extend-filesystems[2020]: Found /dev/sda6 Oct 13 05:00:24.468130 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 13 05:00:24.468280 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 13 05:00:24.492478 jq[2047]: true Oct 13 05:00:24.492896 extend-filesystems[2020]: Found /dev/sda9 Oct 13 05:00:24.503678 extend-filesystems[2020]: Checking size of /dev/sda9 Oct 13 05:00:24.520259 systemd[1]: motdgen.service: Deactivated successfully. Oct 13 05:00:24.522203 (ntainerd)[2069]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 13 05:00:24.522762 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 13 05:00:24.556319 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 13 05:00:24.559477 extend-filesystems[2020]: Resized partition /dev/sda9 Oct 13 05:00:24.580699 systemd-logind[2031]: New seat seat0. Oct 13 05:00:24.582805 systemd-logind[2031]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Oct 13 05:00:24.584912 systemd[1]: Started systemd-logind.service - User Login Management. Oct 13 05:00:24.593343 update_engine[2034]: I20251013 05:00:24.592525 2034 main.cc:92] Flatcar Update Engine starting Oct 13 05:00:24.625842 bash[2080]: Updated "/home/core/.ssh/authorized_keys" Oct 13 05:00:24.630200 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 13 05:00:24.639222 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 13 05:00:24.653628 KVP[2021]: KVP starting; pid is:2021 Oct 13 05:00:24.660706 chronyd[2011]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 13 05:00:24.666499 KVP[2021]: KVP LIC Version: 3.1 Oct 13 05:00:24.667025 kernel: hv_utils: KVP IC version 4.0 Oct 13 05:00:24.689986 chronyd[2011]: Timezone right/UTC failed leap second check, ignoring Oct 13 05:00:24.690791 systemd[1]: Started chronyd.service - NTP client/server. Oct 13 05:00:24.690171 chronyd[2011]: Loaded seccomp filter (level 2) Oct 13 05:00:24.801891 extend-filesystems[2151]: resize2fs 1.47.3 (8-Jul-2025) Oct 13 05:00:24.816082 kernel: EXT4-fs (sda9): resizing filesystem from 7359488 to 7376891 blocks Oct 13 05:00:24.851841 kernel: EXT4-fs (sda9): resized filesystem to 7376891 Oct 13 05:00:24.892779 extend-filesystems[2151]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Oct 13 05:00:24.892779 extend-filesystems[2151]: old_desc_blocks = 4, new_desc_blocks = 4 Oct 13 05:00:24.892779 extend-filesystems[2151]: The filesystem on /dev/sda9 is now 7376891 (4k) blocks long. Oct 13 05:00:24.926189 extend-filesystems[2020]: Resized filesystem in /dev/sda9 Oct 13 05:00:24.900622 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 13 05:00:24.903874 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 13 05:00:24.970613 sshd_keygen[2056]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 13 05:00:24.992648 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 13 05:00:24.998946 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 13 05:00:25.009073 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 13 05:00:25.019310 systemd[1]: issuegen.service: Deactivated successfully. Oct 13 05:00:25.020188 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 13 05:00:25.029628 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 13 05:00:25.049228 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 13 05:00:25.058179 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 13 05:00:25.065337 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 13 05:00:25.076251 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 13 05:00:25.084368 systemd[1]: Reached target getty.target - Login Prompts. Oct 13 05:00:25.113118 dbus-daemon[2014]: [system] SELinux support is enabled Oct 13 05:00:25.113595 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 13 05:00:25.117300 update_engine[2034]: I20251013 05:00:25.116969 2034 update_check_scheduler.cc:74] Next update check in 6m8s Oct 13 05:00:25.122440 dbus-daemon[2014]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 13 05:00:25.122518 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 13 05:00:25.122553 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 13 05:00:25.129138 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 13 05:00:25.129744 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 13 05:00:25.136523 systemd[1]: Started update-engine.service - Update Engine. Oct 13 05:00:25.147051 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 13 05:00:25.194186 coreos-metadata[2013]: Oct 13 05:00:25.194 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 05:00:25.197894 coreos-metadata[2013]: Oct 13 05:00:25.197 INFO Fetch successful Oct 13 05:00:25.197894 coreos-metadata[2013]: Oct 13 05:00:25.197 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 13 05:00:25.202809 coreos-metadata[2013]: Oct 13 05:00:25.202 INFO Fetch successful Oct 13 05:00:25.203216 coreos-metadata[2013]: Oct 13 05:00:25.203 INFO Fetching http://168.63.129.16/machine/0d29207a-e281-439d-9e06-60d29ab63631/0cfdca66%2D6701%2D4172%2D9b4a%2Dab82393150fd.%5Fci%2D4487.0.0%2Da%2D8fed64613a?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 13 05:00:25.205453 coreos-metadata[2013]: Oct 13 05:00:25.205 INFO Fetch successful Oct 13 05:00:25.205665 coreos-metadata[2013]: Oct 13 05:00:25.205 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 13 05:00:25.214374 coreos-metadata[2013]: Oct 13 05:00:25.214 INFO Fetch successful Oct 13 05:00:25.323671 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 13 05:00:25.738052 (kubelet)[2197]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 13 05:00:25.754666 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 13 05:00:25.760430 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 13 05:00:25.858226 containerd[2069]: time="2025-10-13T05:00:25Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 13 05:00:25.858944 containerd[2069]: time="2025-10-13T05:00:25.858906388Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 13 05:00:25.866039 containerd[2069]: time="2025-10-13T05:00:25.865999052Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="17.84µs" Oct 13 05:00:25.866039 containerd[2069]: time="2025-10-13T05:00:25.866029172Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 13 05:00:25.866039 containerd[2069]: time="2025-10-13T05:00:25.866046692Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866191476Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866207324Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866224716Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866262508Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866268788Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866425196Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866434540Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866441364Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866446396Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866500012Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866646 containerd[2069]: time="2025-10-13T05:00:25.866643436Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866834 containerd[2069]: time="2025-10-13T05:00:25.866662796Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 13 05:00:25.866834 containerd[2069]: time="2025-10-13T05:00:25.866669948Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 13 05:00:25.866834 containerd[2069]: time="2025-10-13T05:00:25.866702052Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 13 05:00:25.866890 containerd[2069]: time="2025-10-13T05:00:25.866870764Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 13 05:00:25.866944 containerd[2069]: time="2025-10-13T05:00:25.866929228Z" level=info msg="metadata content store policy set" policy=shared Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888656884Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888730116Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888740476Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888748548Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888756988Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888765580Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888797484Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888806364Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888813796Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888829724Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888835972Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888844620Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.888995252Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 13 05:00:25.889662 containerd[2069]: time="2025-10-13T05:00:25.889010252Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889021956Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889030068Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889036636Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889046460Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889054036Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889060492Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889067284Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889074244Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889080356Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889138252Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889147996Z" level=info msg="Start snapshots syncer" Oct 13 05:00:25.889967 containerd[2069]: time="2025-10-13T05:00:25.889181044Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 13 05:00:25.890115 containerd[2069]: time="2025-10-13T05:00:25.889436172Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 13 05:00:25.890115 containerd[2069]: time="2025-10-13T05:00:25.889473676Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889537940Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889676436Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889697836Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889706852Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889715628Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889723044Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889733100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889739972Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889762348Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889770700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889777340Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889813060Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889876548Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 13 05:00:25.890199 containerd[2069]: time="2025-10-13T05:00:25.889883172Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889889100Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889893524Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889900660Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889907468Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889919812Z" level=info msg="runtime interface created" Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889923540Z" level=info msg="created NRI interface" Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889928980Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889937020Z" level=info msg="Connect containerd service" Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.889958252Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 13 05:00:25.892029 containerd[2069]: time="2025-10-13T05:00:25.890619196Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 13 05:00:25.967795 locksmithd[2186]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 13 05:00:26.124070 kubelet[2197]: E1013 05:00:26.123932 2197 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" Oct 13 05:00:26.126935 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 13 05:00:26.127268 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 13 05:00:26.127751 systemd[1]: kubelet.service: Consumed 560ms CPU time, 257.3M memory peak. Oct 13 05:00:26.434911 containerd[2069]: time="2025-10-13T05:00:26.434867564Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 13 05:00:26.435043 containerd[2069]: time="2025-10-13T05:00:26.434933060Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 13 05:00:26.435103 containerd[2069]: time="2025-10-13T05:00:26.435070500Z" level=info msg="Start subscribing containerd event" Oct 13 05:00:26.435123 containerd[2069]: time="2025-10-13T05:00:26.435119308Z" level=info msg="Start recovering state" Oct 13 05:00:26.435207 containerd[2069]: time="2025-10-13T05:00:26.435194692Z" level=info msg="Start event monitor" Oct 13 05:00:26.435223 containerd[2069]: time="2025-10-13T05:00:26.435212148Z" level=info msg="Start cni network conf syncer for default" Oct 13 05:00:26.435223 containerd[2069]: time="2025-10-13T05:00:26.435219924Z" level=info msg="Start streaming server" Oct 13 05:00:26.435249 containerd[2069]: time="2025-10-13T05:00:26.435226492Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 13 05:00:26.435249 containerd[2069]: time="2025-10-13T05:00:26.435234796Z" level=info msg="runtime interface starting up..." Oct 13 05:00:26.435249 containerd[2069]: time="2025-10-13T05:00:26.435239012Z" level=info msg="starting plugins..." Oct 13 05:00:26.435289 containerd[2069]: time="2025-10-13T05:00:26.435249948Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 13 05:00:26.436538 containerd[2069]: time="2025-10-13T05:00:26.435355892Z" level=info msg="containerd successfully booted in 0.577515s" Oct 13 05:00:26.435503 systemd[1]: Started containerd.service - containerd container runtime. Oct 13 05:00:26.442604 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 05:00:26.447943 systemd[1]: Startup finished in 4.533s (kernel) + 19.664s (initrd) + 33.829s (userspace) = 58.028s. Oct 13 05:00:27.889925 login[2183]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 13 05:00:27.942954 login[2182]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:00:27.953930 systemd-logind[2031]: New session 2 of user core. Oct 13 05:00:27.955464 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 13 05:00:27.956718 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 13 05:00:28.000093 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 13 05:00:28.002732 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 13 05:00:28.069313 (systemd)[2234]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 13 05:00:28.071642 systemd-logind[2031]: New session c1 of user core. Oct 13 05:00:28.441136 waagent[2180]: 2025-10-13T05:00:28.441057Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 13 05:00:28.449650 waagent[2180]: 2025-10-13T05:00:28.446192Z INFO Daemon Daemon OS: flatcar 4487.0.0 Oct 13 05:00:28.449989 waagent[2180]: 2025-10-13T05:00:28.449937Z INFO Daemon Daemon Python: 3.11.13 Oct 13 05:00:28.454839 waagent[2180]: 2025-10-13T05:00:28.453615Z INFO Daemon Daemon Run daemon Oct 13 05:00:28.458460 waagent[2180]: 2025-10-13T05:00:28.458410Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.0' Oct 13 05:00:28.465430 waagent[2180]: 2025-10-13T05:00:28.465364Z INFO Daemon Daemon Using waagent for provisioning Oct 13 05:00:28.469937 waagent[2180]: 2025-10-13T05:00:28.469878Z INFO Daemon Daemon Activate resource disk Oct 13 05:00:28.474412 waagent[2180]: 2025-10-13T05:00:28.474347Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 13 05:00:28.483356 waagent[2180]: 2025-10-13T05:00:28.483296Z INFO Daemon Daemon Found device: None Oct 13 05:00:28.487055 waagent[2180]: 2025-10-13T05:00:28.487008Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Oct 13 05:00:28.494650 waagent[2180]: 2025-10-13T05:00:28.494598Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Oct 13 05:00:28.494676 systemd[2234]: Queued start job for default target default.target. Oct 13 05:00:28.504369 waagent[2180]: 2025-10-13T05:00:28.504311Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:00:28.509437 waagent[2180]: 2025-10-13T05:00:28.509383Z INFO Daemon Daemon Running default provisioning handler Oct 13 05:00:28.514912 systemd[2234]: Created slice app.slice - User Application Slice. Oct 13 05:00:28.514943 systemd[2234]: Reached target paths.target - Paths. Oct 13 05:00:28.514978 systemd[2234]: Reached target timers.target - Timers. Oct 13 05:00:28.516044 systemd[2234]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 05:00:28.530379 waagent[2180]: 2025-10-13T05:00:28.519994Z 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. Oct 13 05:00:28.524278 systemd[2234]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:00:28.524334 systemd[2234]: Reached target sockets.target - Sockets. Oct 13 05:00:28.524384 systemd[2234]: Reached target basic.target - Basic System. Oct 13 05:00:28.524410 systemd[2234]: Reached target default.target - Main User Target. Oct 13 05:00:28.524436 systemd[2234]: Startup finished in 447ms. Oct 13 05:00:28.524668 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 13 05:00:28.530368 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 13 05:00:28.532165 waagent[2180]: 2025-10-13T05:00:28.532109Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 13 05:00:28.540738 waagent[2180]: 2025-10-13T05:00:28.540670Z INFO Daemon Daemon cloud-init is enabled: False Oct 13 05:00:28.544483 waagent[2180]: 2025-10-13T05:00:28.544440Z INFO Daemon Daemon Copying ovf-env.xml Oct 13 05:00:28.778227 waagent[2180]: 2025-10-13T05:00:28.777989Z INFO Daemon Daemon Successfully mounted dvd Oct 13 05:00:28.825221 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 13 05:00:28.828127 waagent[2180]: 2025-10-13T05:00:28.828062Z INFO Daemon Daemon Detect protocol endpoint Oct 13 05:00:28.832080 waagent[2180]: 2025-10-13T05:00:28.832034Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:00:28.836894 waagent[2180]: 2025-10-13T05:00:28.836849Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 13 05:00:28.842227 waagent[2180]: 2025-10-13T05:00:28.842189Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 13 05:00:28.846135 waagent[2180]: 2025-10-13T05:00:28.846098Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 13 05:00:28.849857 waagent[2180]: 2025-10-13T05:00:28.849828Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 13 05:00:28.891128 login[2183]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:00:28.895512 systemd-logind[2031]: New session 1 of user core. Oct 13 05:00:28.904965 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 13 05:00:28.971833 waagent[2180]: 2025-10-13T05:00:28.971782Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 13 05:00:28.978498 waagent[2180]: 2025-10-13T05:00:28.978225Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 13 05:00:28.983062 waagent[2180]: 2025-10-13T05:00:28.982880Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 13 05:00:29.127903 waagent[2180]: 2025-10-13T05:00:29.125925Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 13 05:00:29.131482 waagent[2180]: 2025-10-13T05:00:29.131427Z INFO Daemon Daemon Forcing an update of the goal state. Oct 13 05:00:29.140185 waagent[2180]: 2025-10-13T05:00:29.140140Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:00:29.170311 waagent[2180]: 2025-10-13T05:00:29.170269Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 13 05:00:29.175114 waagent[2180]: 2025-10-13T05:00:29.175075Z INFO Daemon Oct 13 05:00:29.177447 waagent[2180]: 2025-10-13T05:00:29.177414Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 966341df-333c-44ff-a944-9bae9e5b1372 eTag: 3463363322432713983 source: Fabric] Oct 13 05:00:29.186663 waagent[2180]: 2025-10-13T05:00:29.186623Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 13 05:00:29.191938 waagent[2180]: 2025-10-13T05:00:29.191902Z INFO Daemon Oct 13 05:00:29.193954 waagent[2180]: 2025-10-13T05:00:29.193925Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:00:29.203325 waagent[2180]: 2025-10-13T05:00:29.203292Z INFO Daemon Daemon Downloading artifacts profile blob Oct 13 05:00:29.270613 waagent[2180]: 2025-10-13T05:00:29.270534Z INFO Daemon Downloaded certificate {'thumbprint': '447AE6309A98A46FA4C112CDCCD42E8EA087A76E', 'hasPrivateKey': True} Oct 13 05:00:29.280584 waagent[2180]: 2025-10-13T05:00:29.280537Z INFO Daemon Fetch goal state completed Oct 13 05:00:29.292464 waagent[2180]: 2025-10-13T05:00:29.292427Z INFO Daemon Daemon Starting provisioning Oct 13 05:00:29.296469 waagent[2180]: 2025-10-13T05:00:29.296432Z INFO Daemon Daemon Handle ovf-env.xml. Oct 13 05:00:29.300359 waagent[2180]: 2025-10-13T05:00:29.300335Z INFO Daemon Daemon Set hostname [ci-4487.0.0-a-8fed64613a] Oct 13 05:00:29.373749 waagent[2180]: 2025-10-13T05:00:29.373682Z INFO Daemon Daemon Publish hostname [ci-4487.0.0-a-8fed64613a] Oct 13 05:00:29.379541 waagent[2180]: 2025-10-13T05:00:29.379420Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 13 05:00:29.385115 waagent[2180]: 2025-10-13T05:00:29.385069Z INFO Daemon Daemon Primary interface is [eth0] Oct 13 05:00:29.422686 systemd-networkd[1858]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:00:29.422694 systemd-networkd[1858]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:00:29.422779 systemd-networkd[1858]: eth0: DHCP lease lost Oct 13 05:00:29.440983 waagent[2180]: 2025-10-13T05:00:29.440908Z INFO Daemon Daemon Create user account if not exists Oct 13 05:00:29.445369 waagent[2180]: 2025-10-13T05:00:29.445316Z INFO Daemon Daemon User core already exists, skip useradd Oct 13 05:00:29.450102 waagent[2180]: 2025-10-13T05:00:29.450045Z INFO Daemon Daemon Configure sudoer Oct 13 05:00:29.459191 waagent[2180]: 2025-10-13T05:00:29.459126Z INFO Daemon Daemon Configure sshd Oct 13 05:00:29.462891 systemd-networkd[1858]: eth0: DHCPv4 address 10.200.20.22/24, gateway 10.200.20.1 acquired from 168.63.129.16 Oct 13 05:00:29.466743 waagent[2180]: 2025-10-13T05:00:29.466684Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 13 05:00:29.478107 waagent[2180]: 2025-10-13T05:00:29.478051Z INFO Daemon Daemon Deploy ssh public key. Oct 13 05:00:30.674974 waagent[2180]: 2025-10-13T05:00:30.674925Z INFO Daemon Daemon Provisioning complete Oct 13 05:00:30.689391 waagent[2180]: 2025-10-13T05:00:30.689347Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 13 05:00:30.694780 waagent[2180]: 2025-10-13T05:00:30.694734Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 13 05:00:30.703287 waagent[2180]: 2025-10-13T05:00:30.703242Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 13 05:00:30.805606 waagent[2284]: 2025-10-13T05:00:30.805531Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 13 05:00:30.807360 waagent[2284]: 2025-10-13T05:00:30.806109Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.0 Oct 13 05:00:30.807360 waagent[2284]: 2025-10-13T05:00:30.806171Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 13 05:00:30.807360 waagent[2284]: 2025-10-13T05:00:30.806210Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Oct 13 05:00:30.927552 waagent[2284]: 2025-10-13T05:00:30.927418Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 13 05:00:30.927884 waagent[2284]: 2025-10-13T05:00:30.927853Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:00:30.928008 waagent[2284]: 2025-10-13T05:00:30.927985Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:00:30.934781 waagent[2284]: 2025-10-13T05:00:30.934729Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:00:30.940610 waagent[2284]: 2025-10-13T05:00:30.940575Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 13 05:00:30.941155 waagent[2284]: 2025-10-13T05:00:30.941124Z INFO ExtHandler Oct 13 05:00:30.941287 waagent[2284]: 2025-10-13T05:00:30.941263Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: e3658a27-8e8d-4ccb-b131-12533bc5712f eTag: 3463363322432713983 source: Fabric] Oct 13 05:00:30.941619 waagent[2284]: 2025-10-13T05:00:30.941590Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 13 05:00:30.942151 waagent[2284]: 2025-10-13T05:00:30.942120Z INFO ExtHandler Oct 13 05:00:30.942256 waagent[2284]: 2025-10-13T05:00:30.942236Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:00:30.946239 waagent[2284]: 2025-10-13T05:00:30.946211Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 13 05:00:31.009374 waagent[2284]: 2025-10-13T05:00:31.009303Z INFO ExtHandler Downloaded certificate {'thumbprint': '447AE6309A98A46FA4C112CDCCD42E8EA087A76E', 'hasPrivateKey': True} Oct 13 05:00:31.010018 waagent[2284]: 2025-10-13T05:00:31.009979Z INFO ExtHandler Fetch goal state completed Oct 13 05:00:31.022727 waagent[2284]: 2025-10-13T05:00:31.022274Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Oct 13 05:00:31.025797 waagent[2284]: 2025-10-13T05:00:31.025749Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2284 Oct 13 05:00:31.026038 waagent[2284]: 2025-10-13T05:00:31.026008Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 13 05:00:31.026400 waagent[2284]: 2025-10-13T05:00:31.026367Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 13 05:00:31.027650 waagent[2284]: 2025-10-13T05:00:31.027612Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 13 05:00:31.028095 waagent[2284]: 2025-10-13T05:00:31.028059Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 13 05:00:31.028285 waagent[2284]: 2025-10-13T05:00:31.028258Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 13 05:00:31.028846 waagent[2284]: 2025-10-13T05:00:31.028789Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 13 05:00:31.163920 waagent[2284]: 2025-10-13T05:00:31.163883Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 13 05:00:31.164239 waagent[2284]: 2025-10-13T05:00:31.164208Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 13 05:00:31.169402 waagent[2284]: 2025-10-13T05:00:31.168995Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 13 05:00:31.181456 systemd[1]: Reload requested from client PID 2301 ('systemctl') (unit waagent.service)... Oct 13 05:00:31.181472 systemd[1]: Reloading... Oct 13 05:00:31.256838 zram_generator::config[2347]: No configuration found. Oct 13 05:00:31.403445 systemd[1]: Reloading finished in 221 ms. Oct 13 05:00:31.431076 waagent[2284]: 2025-10-13T05:00:31.431001Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 13 05:00:31.431204 waagent[2284]: 2025-10-13T05:00:31.431158Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 13 05:00:32.140856 waagent[2284]: 2025-10-13T05:00:32.140276Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 13 05:00:32.140856 waagent[2284]: 2025-10-13T05:00:32.140602Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Oct 13 05:00:32.141305 waagent[2284]: 2025-10-13T05:00:32.141259Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 13 05:00:32.141669 waagent[2284]: 2025-10-13T05:00:32.141625Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 13 05:00:32.141746 waagent[2284]: 2025-10-13T05:00:32.141713Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:00:32.141783 waagent[2284]: 2025-10-13T05:00:32.141764Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:00:32.142001 waagent[2284]: 2025-10-13T05:00:32.141946Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 13 05:00:32.142227 waagent[2284]: 2025-10-13T05:00:32.142185Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 13 05:00:32.142463 waagent[2284]: 2025-10-13T05:00:32.142354Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 13 05:00:32.142463 waagent[2284]: 2025-10-13T05:00:32.142422Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:00:32.142759 waagent[2284]: 2025-10-13T05:00:32.142726Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 13 05:00:32.142851 waagent[2284]: 2025-10-13T05:00:32.142786Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 13 05:00:32.142851 waagent[2284]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 13 05:00:32.142851 waagent[2284]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Oct 13 05:00:32.142851 waagent[2284]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 13 05:00:32.142851 waagent[2284]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:00:32.142851 waagent[2284]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:00:32.142851 waagent[2284]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:00:32.143047 waagent[2284]: 2025-10-13T05:00:32.142855Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 13 05:00:32.143216 waagent[2284]: 2025-10-13T05:00:32.143185Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:00:32.143523 waagent[2284]: 2025-10-13T05:00:32.143492Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 13 05:00:32.143771 waagent[2284]: 2025-10-13T05:00:32.143731Z INFO EnvHandler ExtHandler Configure routes Oct 13 05:00:32.145446 waagent[2284]: 2025-10-13T05:00:32.145160Z INFO EnvHandler ExtHandler Gateway:None Oct 13 05:00:32.145446 waagent[2284]: 2025-10-13T05:00:32.145208Z INFO EnvHandler ExtHandler Routes:None Oct 13 05:00:32.149896 waagent[2284]: 2025-10-13T05:00:32.149845Z INFO ExtHandler ExtHandler Oct 13 05:00:32.149976 waagent[2284]: 2025-10-13T05:00:32.149934Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: ae260cfa-db3a-434b-ac5a-7244302369e2 correlation d1ec062d-3048-41a9-8237-27495a38a658 created: 2025-10-13T04:58:42.303398Z] Oct 13 05:00:32.150267 waagent[2284]: 2025-10-13T05:00:32.150228Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 13 05:00:32.150684 waagent[2284]: 2025-10-13T05:00:32.150653Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Oct 13 05:00:32.231960 waagent[2284]: 2025-10-13T05:00:32.231892Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Oct 13 05:00:32.231960 waagent[2284]: Try `iptables -h' or 'iptables --help' for more information.) Oct 13 05:00:32.232562 waagent[2284]: 2025-10-13T05:00:32.232530Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 12F3253F-9E3D-401F-A649-23963079D3E0;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 13 05:00:32.348329 waagent[2284]: 2025-10-13T05:00:32.348250Z INFO MonitorHandler ExtHandler Network interfaces: Oct 13 05:00:32.348329 waagent[2284]: Executing ['ip', '-a', '-o', 'link']: Oct 13 05:00:32.348329 waagent[2284]: 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 Oct 13 05:00:32.348329 waagent[2284]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7b:36:f9 brd ff:ff:ff:ff:ff:ff\ altname enx0022487b36f9 Oct 13 05:00:32.348329 waagent[2284]: 3: enP55563s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7b:36:f9 brd ff:ff:ff:ff:ff:ff\ altname enP55563p0s2 Oct 13 05:00:32.348329 waagent[2284]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 13 05:00:32.348329 waagent[2284]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 13 05:00:32.348329 waagent[2284]: 2: eth0 inet 10.200.20.22/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 13 05:00:32.348329 waagent[2284]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 13 05:00:32.348329 waagent[2284]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 13 05:00:32.348329 waagent[2284]: 2: eth0 inet6 fe80::222:48ff:fe7b:36f9/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 13 05:00:32.449921 waagent[2284]: 2025-10-13T05:00:32.449136Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 13 05:00:32.449921 waagent[2284]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:00:32.449921 waagent[2284]: pkts bytes target prot opt in out source destination Oct 13 05:00:32.449921 waagent[2284]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:00:32.449921 waagent[2284]: pkts bytes target prot opt in out source destination Oct 13 05:00:32.449921 waagent[2284]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:00:32.449921 waagent[2284]: pkts bytes target prot opt in out source destination Oct 13 05:00:32.449921 waagent[2284]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:00:32.449921 waagent[2284]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:00:32.449921 waagent[2284]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:00:32.451948 waagent[2284]: 2025-10-13T05:00:32.451891Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 13 05:00:32.451948 waagent[2284]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:00:32.451948 waagent[2284]: pkts bytes target prot opt in out source destination Oct 13 05:00:32.451948 waagent[2284]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:00:32.451948 waagent[2284]: pkts bytes target prot opt in out source destination Oct 13 05:00:32.451948 waagent[2284]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:00:32.451948 waagent[2284]: pkts bytes target prot opt in out source destination Oct 13 05:00:32.451948 waagent[2284]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:00:32.451948 waagent[2284]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:00:32.451948 waagent[2284]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:00:32.452176 waagent[2284]: 2025-10-13T05:00:32.452148Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 13 05:00:36.175799 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Oct 13 05:00:36.177583 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 13 05:00:36.407216 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 13 05:00:36.410422 (kubelet)[2436]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 13 05:00:36.440806 kubelet[2436]: E1013 05:00:36.440665 2436 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" Oct 13 05:00:36.443839 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 13 05:00:36.444106 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 13 05:00:36.445914 systemd[1]: kubelet.service: Consumed 114ms CPU time, 105.3M memory peak. Oct 13 05:00:46.675098 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Oct 13 05:00:46.677060 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 13 05:00:46.991046 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 13 05:00:46.996146 (kubelet)[2451]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 13 05:00:47.020344 kubelet[2451]: E1013 05:00:47.020282 2451 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" Oct 13 05:00:47.022778 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 13 05:00:47.023010 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 13 05:00:47.023400 systemd[1]: kubelet.service: Consumed 109ms CPU time, 104.7M memory peak. Oct 13 05:00:48.490611 chronyd[2011]: Selected source PHC0 Oct 13 05:00:56.561129 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Oct 13 05:00:57.175006 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 3. Oct 13 05:00:57.176270 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 13 05:00:57.512523 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 13 05:00:57.515471 (kubelet)[2465]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 13 05:00:57.546683 kubelet[2465]: E1013 05:00:57.546611 2465 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" Oct 13 05:00:57.548891 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 13 05:00:57.549131 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 13 05:00:57.549635 systemd[1]: kubelet.service: Consumed 112ms CPU time, 105.4M memory peak. Oct 13 05:00:58.939316 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 13 05:00:58.940867 systemd[1]: Started sshd@0-10.200.20.22:22-10.200.16.10:40720.service - OpenSSH per-connection server daemon (10.200.16.10:40720). Oct 13 05:00:59.404458 sshd[2473]: Accepted publickey for core from 10.200.16.10 port 40720 ssh2: RSA SHA256:0u0fQSSzne3hQhq8oltmzZFBGtzHLYbFgWa9+RtcBLA Oct 13 05:00:59.405519 sshd-session[2473]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:00:59.409339 systemd-logind[2031]: New session 3 of user core. Oct 13 05:00:59.416960 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 13 05:00:59.797069 systemd[1]: Started sshd@1-10.200.20.22:22-10.200.16.10:40730.service - OpenSSH per-connection server daemon (10.200.16.10:40730). Oct 13 05:01:00.217090 sshd[2479]: Accepted publickey for core from 10.200.16.10 port 40730 ssh2: RSA SHA256:0u0fQSSzne3hQhq8oltmzZFBGtzHLYbFgWa9+RtcBLA Oct 13 05:01:00.218143 sshd-session[2479]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:01:00.221993 systemd-logind[2031]: New session 4 of user core. Oct 13 05:01:00.232985 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 13 05:01:00.524939 sshd[2482]: Connection closed by 10.200.16.10 port 40730 Oct 13 05:01:00.525574 sshd-session[2479]: pam_unix(sshd:session): session closed for user core Oct 13 05:01:00.528715 systemd[1]: sshd@1-10.200.20.22:22-10.200.16.10:40730.service: Deactivated successfully. Oct 13 05:01:00.530034 systemd[1]: session-4.scope: Deactivated successfully. Oct 13 05:01:00.530942 systemd-logind[2031]: Session 4 logged out. Waiting for processes to exit. Oct 13 05:01:00.532124 systemd-logind[2031]: Removed session 4. Oct 13 05:01:00.607518 systemd[1]: Started sshd@2-10.200.20.22:22-10.200.16.10:44248.service - OpenSSH per-connection server daemon (10.200.16.10:44248). Oct 13 05:01:01.031795 sshd[2488]: Accepted publickey for core from 10.200.16.10 port 44248 ssh2: RSA SHA256:0u0fQSSzne3hQhq8oltmzZFBGtzHLYbFgWa9+RtcBLA Oct 13 05:01:01.032886 sshd-session[2488]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:01:01.036512 systemd-logind[2031]: New session 5 of user core. Oct 13 05:01:01.046976 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 13 05:01:01.345976 sshd[2491]: Connection closed by 10.200.16.10 port 44248 Oct 13 05:01:01.342579 sshd-session[2488]: pam_unix(sshd:session): session closed for user core Oct 13 05:01:01.346695 systemd-logind[2031]: Session 5 logged out. Waiting for processes to exit. Oct 13 05:01:01.346903 systemd[1]: sshd@2-10.200.20.22:22-10.200.16.10:44248.service: Deactivated successfully. Oct 13 05:01:01.348311 systemd[1]: session-5.scope: Deactivated successfully. Oct 13 05:01:01.351091 systemd-logind[2031]: Removed session 5. Oct 13 05:01:01.422493 systemd[1]: Started sshd@3-10.200.20.22:22-10.200.16.10:44260.service - OpenSSH per-connection server daemon (10.200.16.10:44260). Oct 13 05:01:01.855381 sshd[2497]: Accepted publickey for core from 10.200.16.10 port 44260 ssh2: RSA SHA256:0u0fQSSzne3hQhq8oltmzZFBGtzHLYbFgWa9+RtcBLA Oct 13 05:01:01.856440 sshd-session[2497]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:01:01.860142 systemd-logind[2031]: New session 6 of user core. Oct 13 05:01:01.869152 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 13 05:01:02.165228 sshd[2500]: Connection closed by 10.200.16.10 port 44260 Oct 13 05:01:02.165759 sshd-session[2497]: pam_unix(sshd:session): session closed for user core Oct 13 05:01:02.169313 systemd[1]: sshd@3-10.200.20.22:22-10.200.16.10:44260.service: Deactivated successfully. Oct 13 05:01:02.170933 systemd[1]: session-6.scope: Deactivated successfully. Oct 13 05:01:02.171574 systemd-logind[2031]: Session 6 logged out. Waiting for processes to exit. Oct 13 05:01:02.172786 systemd-logind[2031]: Removed session 6. Oct 13 05:01:02.240528 systemd[1]: Started sshd@4-10.200.20.22:22-10.200.16.10:44276.service - OpenSSH per-connection server daemon (10.200.16.10:44276). Oct 13 05:01:02.658674 sshd[2506]: Accepted publickey for core from 10.200.16.10 port 44276 ssh2: RSA SHA256:0u0fQSSzne3hQhq8oltmzZFBGtzHLYbFgWa9+RtcBLA Oct 13 05:01:02.659744 sshd-session[2506]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:01:02.663557 systemd-logind[2031]: New session 7 of user core. Oct 13 05:01:02.670971 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 13 05:01:02.948335 sudo[2510]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 13 05:01:02.948968 sudo[2510]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 05:01:03.322249 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 13 05:01:03.322699 systemd[1]: kubelet.service: Consumed 112ms CPU time, 105.4M memory peak. Oct 13 05:01:03.325433 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 13 05:01:03.346525 systemd[1]: Reload requested from client PID 2545 ('systemctl') (unit session-7.scope)... Oct 13 05:01:03.346539 systemd[1]: Reloading... Oct 13 05:01:03.452008 zram_generator::config[2595]: No configuration found. Oct 13 05:01:03.592974 systemd[1]: Reloading finished in 246 ms. Oct 13 05:01:03.625736 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 13 05:01:03.625809 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 13 05:01:03.626234 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 13 05:01:03.626300 systemd[1]: kubelet.service: Consumed 66ms CPU time, 89.4M memory peak. Oct 13 05:01:03.629999 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 13 05:01:03.813080 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 13 05:01:03.823068 (kubelet)[2656]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 13 05:01:03.940624 kubelet[2656]: 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. Oct 13 05:01:03.940624 kubelet[2656]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 13 05:01:03.940624 kubelet[2656]: 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. Oct 13 05:01:03.941251 kubelet[2656]: I1013 05:01:03.940663 2656 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" Oct 13 05:01:04.553689 kubelet[2656]: I1013 05:01:04.553643 2656 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Oct 13 05:01:04.553689 kubelet[2656]: I1013 05:01:04.553677 2656 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 13 05:01:04.553926 kubelet[2656]: I1013 05:01:04.553909 2656 server.go:956] "Client rotation is on, will bootstrap in background" Oct 13 05:01:04.580235 kubelet[2656]: I1013 05:01:04.578865 2656 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 13 05:01:04.586966 kubelet[2656]: I1013 05:01:04.586942 2656 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 13 05:01:04.590004 kubelet[2656]: I1013 05:01:04.589980 2656 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 13 05:01:04.591352 kubelet[2656]: I1013 05:01:04.591314 2656 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 13 05:01:04.591585 kubelet[2656]: I1013 05:01:04.591446 2656 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.20.22","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 13 05:01:04.591722 kubelet[2656]: I1013 05:01:04.591711 2656 topology_manager.go:138] "Creating topology manager with none policy" Oct 13 05:01:04.591773 kubelet[2656]: I1013 05:01:04.591765 2656 container_manager_linux.go:303] "Creating device plugin manager" Oct 13 05:01:04.591984 kubelet[2656]: I1013 05:01:04.591968 2656 state_mem.go:36] "Initialized new in-memory state store" Oct 13 05:01:04.596443 kubelet[2656]: I1013 05:01:04.596409 2656 kubelet.go:480] "Attempting to sync node with API server" Oct 13 05:01:04.596552 kubelet[2656]: I1013 05:01:04.596543 2656 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 13 05:01:04.596611 kubelet[2656]: I1013 05:01:04.596605 2656 kubelet.go:386] "Adding apiserver pod source" Oct 13 05:01:04.597748 kubelet[2656]: I1013 05:01:04.597735 2656 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 13 05:01:04.597815 kubelet[2656]: E1013 05:01:04.597796 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:04.597815 kubelet[2656]: E1013 05:01:04.597745 2656 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:04.599087 kubelet[2656]: I1013 05:01:04.599068 2656 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Oct 13 05:01:04.599613 kubelet[2656]: I1013 05:01:04.599591 2656 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 13 05:01:04.599838 kubelet[2656]: W1013 05:01:04.599751 2656 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 13 05:01:04.600073 kubelet[2656]: E1013 05:01:04.600045 2656 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.200.20.22\" 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" Oct 13 05:01:04.600183 kubelet[2656]: E1013 05:01:04.600146 2656 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" Oct 13 05:01:04.601836 kubelet[2656]: I1013 05:01:04.601807 2656 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 13 05:01:04.601945 kubelet[2656]: I1013 05:01:04.601934 2656 server.go:1289] "Started kubelet" Oct 13 05:01:04.602836 kubelet[2656]: I1013 05:01:04.602435 2656 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Oct 13 05:01:04.603075 kubelet[2656]: I1013 05:01:04.603053 2656 server.go:317] "Adding debug handlers to kubelet server" Oct 13 05:01:04.605413 kubelet[2656]: I1013 05:01:04.605349 2656 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 13 05:01:04.606295 kubelet[2656]: I1013 05:01:04.606076 2656 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 13 05:01:04.606399 kubelet[2656]: I1013 05:01:04.606380 2656 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 13 05:01:04.608989 kubelet[2656]: I1013 05:01:04.608213 2656 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 13 05:01:04.610086 kubelet[2656]: E1013 05:01:04.610067 2656 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 13 05:01:04.610362 kubelet[2656]: E1013 05:01:04.610346 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:04.610456 kubelet[2656]: I1013 05:01:04.610447 2656 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 13 05:01:04.610678 kubelet[2656]: I1013 05:01:04.610662 2656 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 13 05:01:04.610785 kubelet[2656]: I1013 05:01:04.610776 2656 reconciler.go:26] "Reconciler: start to sync state" Oct 13 05:01:04.611412 kubelet[2656]: I1013 05:01:04.611391 2656 factory.go:223] Registration of the systemd container factory successfully Oct 13 05:01:04.611712 kubelet[2656]: I1013 05:01:04.611689 2656 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 Oct 13 05:01:04.613036 kubelet[2656]: I1013 05:01:04.613016 2656 factory.go:223] Registration of the containerd container factory successfully Oct 13 05:01:04.638294 kubelet[2656]: I1013 05:01:04.638247 2656 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Oct 13 05:01:04.639173 kubelet[2656]: I1013 05:01:04.639151 2656 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Oct 13 05:01:04.639173 kubelet[2656]: I1013 05:01:04.639177 2656 status_manager.go:230] "Starting to sync pod status with apiserver" Oct 13 05:01:04.639293 kubelet[2656]: I1013 05:01:04.639197 2656 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 13 05:01:04.639293 kubelet[2656]: I1013 05:01:04.639202 2656 kubelet.go:2436] "Starting kubelet main sync loop" Oct 13 05:01:04.639293 kubelet[2656]: E1013 05:01:04.639239 2656 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]" Oct 13 05:01:04.645484 kubelet[2656]: E1013 05:01:04.645236 2656 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.200.20.22\" not found" node="10.200.20.22" Oct 13 05:01:04.645635 kubelet[2656]: I1013 05:01:04.645621 2656 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 13 05:01:04.645694 kubelet[2656]: I1013 05:01:04.645685 2656 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 13 05:01:04.645752 kubelet[2656]: I1013 05:01:04.645744 2656 state_mem.go:36] "Initialized new in-memory state store" Oct 13 05:01:04.659124 kubelet[2656]: I1013 05:01:04.659100 2656 policy_none.go:49] "None policy: Start" Oct 13 05:01:04.659266 kubelet[2656]: I1013 05:01:04.659256 2656 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 13 05:01:04.659326 kubelet[2656]: I1013 05:01:04.659318 2656 state_mem.go:35] "Initializing new in-memory state store" Oct 13 05:01:04.669410 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 13 05:01:04.683794 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 13 05:01:04.686608 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 13 05:01:04.710526 kubelet[2656]: E1013 05:01:04.710495 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:04.710968 kubelet[2656]: E1013 05:01:04.710939 2656 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 13 05:01:04.711151 kubelet[2656]: I1013 05:01:04.711135 2656 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 13 05:01:04.711196 kubelet[2656]: I1013 05:01:04.711151 2656 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 13 05:01:04.711674 kubelet[2656]: I1013 05:01:04.711654 2656 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 13 05:01:04.713681 kubelet[2656]: E1013 05:01:04.713655 2656 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 13 05:01:04.713855 kubelet[2656]: E1013 05:01:04.713696 2656 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.20.22\" not found" Oct 13 05:01:04.812459 kubelet[2656]: I1013 05:01:04.812050 2656 kubelet_node_status.go:75] "Attempting to register node" node="10.200.20.22" Oct 13 05:01:04.816905 kubelet[2656]: I1013 05:01:04.816877 2656 kubelet_node_status.go:78] "Successfully registered node" node="10.200.20.22" Oct 13 05:01:04.817168 kubelet[2656]: E1013 05:01:04.817030 2656 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.200.20.22\": node \"10.200.20.22\" not found" Oct 13 05:01:04.834259 kubelet[2656]: E1013 05:01:04.834213 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:04.849633 sudo[2510]: pam_unix(sudo:session): session closed for user root Oct 13 05:01:04.932739 sshd[2509]: Connection closed by 10.200.16.10 port 44276 Oct 13 05:01:04.933293 sshd-session[2506]: pam_unix(sshd:session): session closed for user core Oct 13 05:01:04.935011 kubelet[2656]: E1013 05:01:04.934982 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:04.937220 systemd[1]: sshd@4-10.200.20.22:22-10.200.16.10:44276.service: Deactivated successfully. Oct 13 05:01:04.938953 systemd[1]: session-7.scope: Deactivated successfully. Oct 13 05:01:04.939100 systemd[1]: session-7.scope: Consumed 283ms CPU time, 74.2M memory peak. Oct 13 05:01:04.940308 systemd-logind[2031]: Session 7 logged out. Waiting for processes to exit. Oct 13 05:01:04.941544 systemd-logind[2031]: Removed session 7. Oct 13 05:01:05.035545 kubelet[2656]: E1013 05:01:05.035497 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.136487 kubelet[2656]: E1013 05:01:05.136258 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.237036 kubelet[2656]: E1013 05:01:05.236892 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.337638 kubelet[2656]: E1013 05:01:05.337490 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.438189 kubelet[2656]: E1013 05:01:05.438141 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.538906 kubelet[2656]: E1013 05:01:05.538778 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.556144 kubelet[2656]: I1013 05:01:05.556027 2656 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 13 05:01:05.556305 kubelet[2656]: I1013 05:01:05.556226 2656 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" Oct 13 05:01:05.556362 kubelet[2656]: I1013 05:01:05.556328 2656 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" Oct 13 05:01:05.598926 kubelet[2656]: E1013 05:01:05.598869 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:05.639472 kubelet[2656]: E1013 05:01:05.639384 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.740017 kubelet[2656]: E1013 05:01:05.739828 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.840463 kubelet[2656]: E1013 05:01:05.840337 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:05.941221 kubelet[2656]: E1013 05:01:05.941159 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:06.041615 kubelet[2656]: E1013 05:01:06.041375 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:06.141701 kubelet[2656]: E1013 05:01:06.141555 2656 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.22\" not found" Oct 13 05:01:06.242680 kubelet[2656]: I1013 05:01:06.242641 2656 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 13 05:01:06.243124 containerd[2069]: time="2025-10-13T05:01:06.242933260Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 13 05:01:06.243707 kubelet[2656]: I1013 05:01:06.243483 2656 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 13 05:01:06.600167 kubelet[2656]: I1013 05:01:06.599874 2656 apiserver.go:52] "Watching apiserver" Oct 13 05:01:06.600383 kubelet[2656]: E1013 05:01:06.600365 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:06.619086 systemd[1]: Created slice kubepods-besteffort-pod07e3cae2_0b32_4a80_b0a6_d4dd6128058c.slice - libcontainer container kubepods-besteffort-pod07e3cae2_0b32_4a80_b0a6_d4dd6128058c.slice. Oct 13 05:01:06.621852 kubelet[2656]: I1013 05:01:06.621665 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/e2ea323c-24a8-4f15-a23a-430b4b416007-cni-plugin\") pod \"kube-flannel-ds-fpv97\" (UID: \"e2ea323c-24a8-4f15-a23a-430b4b416007\") " pod="kube-flannel/kube-flannel-ds-fpv97" Oct 13 05:01:06.621852 kubelet[2656]: I1013 05:01:06.621686 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/e2ea323c-24a8-4f15-a23a-430b4b416007-flannel-cfg\") pod \"kube-flannel-ds-fpv97\" (UID: \"e2ea323c-24a8-4f15-a23a-430b4b416007\") " pod="kube-flannel/kube-flannel-ds-fpv97" Oct 13 05:01:06.621852 kubelet[2656]: I1013 05:01:06.621697 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/e2ea323c-24a8-4f15-a23a-430b4b416007-xtables-lock\") pod \"kube-flannel-ds-fpv97\" (UID: \"e2ea323c-24a8-4f15-a23a-430b4b416007\") " pod="kube-flannel/kube-flannel-ds-fpv97" Oct 13 05:01:06.621852 kubelet[2656]: I1013 05:01:06.621706 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zknn2\" (UniqueName: \"kubernetes.io/projected/e2ea323c-24a8-4f15-a23a-430b4b416007-kube-api-access-zknn2\") pod \"kube-flannel-ds-fpv97\" (UID: \"e2ea323c-24a8-4f15-a23a-430b4b416007\") " pod="kube-flannel/kube-flannel-ds-fpv97" Oct 13 05:01:06.621852 kubelet[2656]: I1013 05:01:06.621719 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/07e3cae2-0b32-4a80-b0a6-d4dd6128058c-kube-proxy\") pod \"kube-proxy-qqbr7\" (UID: \"07e3cae2-0b32-4a80-b0a6-d4dd6128058c\") " pod="kube-system/kube-proxy-qqbr7" Oct 13 05:01:06.622016 kubelet[2656]: I1013 05:01:06.621729 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/07e3cae2-0b32-4a80-b0a6-d4dd6128058c-xtables-lock\") pod \"kube-proxy-qqbr7\" (UID: \"07e3cae2-0b32-4a80-b0a6-d4dd6128058c\") " pod="kube-system/kube-proxy-qqbr7" Oct 13 05:01:06.622016 kubelet[2656]: I1013 05:01:06.621747 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/07e3cae2-0b32-4a80-b0a6-d4dd6128058c-lib-modules\") pod \"kube-proxy-qqbr7\" (UID: \"07e3cae2-0b32-4a80-b0a6-d4dd6128058c\") " pod="kube-system/kube-proxy-qqbr7" Oct 13 05:01:06.622016 kubelet[2656]: I1013 05:01:06.621759 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8b8tj\" (UniqueName: \"kubernetes.io/projected/07e3cae2-0b32-4a80-b0a6-d4dd6128058c-kube-api-access-8b8tj\") pod \"kube-proxy-qqbr7\" (UID: \"07e3cae2-0b32-4a80-b0a6-d4dd6128058c\") " pod="kube-system/kube-proxy-qqbr7" Oct 13 05:01:06.622016 kubelet[2656]: I1013 05:01:06.621768 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/e2ea323c-24a8-4f15-a23a-430b4b416007-run\") pod \"kube-flannel-ds-fpv97\" (UID: \"e2ea323c-24a8-4f15-a23a-430b4b416007\") " pod="kube-flannel/kube-flannel-ds-fpv97" Oct 13 05:01:06.622016 kubelet[2656]: I1013 05:01:06.621777 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/e2ea323c-24a8-4f15-a23a-430b4b416007-cni\") pod \"kube-flannel-ds-fpv97\" (UID: \"e2ea323c-24a8-4f15-a23a-430b4b416007\") " pod="kube-flannel/kube-flannel-ds-fpv97" Oct 13 05:01:06.627945 systemd[1]: Created slice kubepods-burstable-pode2ea323c_24a8_4f15_a23a_430b4b416007.slice - libcontainer container kubepods-burstable-pode2ea323c_24a8_4f15_a23a_430b4b416007.slice. Oct 13 05:01:06.711798 kubelet[2656]: I1013 05:01:06.711754 2656 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 13 05:01:06.926714 containerd[2069]: time="2025-10-13T05:01:06.926663211Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-qqbr7,Uid:07e3cae2-0b32-4a80-b0a6-d4dd6128058c,Namespace:kube-system,Attempt:0,}" Oct 13 05:01:06.936449 containerd[2069]: time="2025-10-13T05:01:06.936269889Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-fpv97,Uid:e2ea323c-24a8-4f15-a23a-430b4b416007,Namespace:kube-flannel,Attempt:0,}" Oct 13 05:01:07.600921 kubelet[2656]: E1013 05:01:07.600870 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:07.826361 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3385707932.mount: Deactivated successfully. Oct 13 05:01:07.868714 containerd[2069]: time="2025-10-13T05:01:07.868118008Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 13 05:01:07.871891 containerd[2069]: time="2025-10-13T05:01:07.871846362Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 13 05:01:07.876031 containerd[2069]: time="2025-10-13T05:01:07.875989568Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Oct 13 05:01:07.879228 containerd[2069]: time="2025-10-13T05:01:07.879180403Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 13 05:01:07.883080 containerd[2069]: time="2025-10-13T05:01:07.883024991Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 13 05:01:07.887938 containerd[2069]: time="2025-10-13T05:01:07.887880931Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 13 05:01:07.888758 containerd[2069]: time="2025-10-13T05:01:07.888280405Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 919.780013ms" Oct 13 05:01:07.889186 containerd[2069]: time="2025-10-13T05:01:07.889149928Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 929.248815ms" Oct 13 05:01:07.956047 containerd[2069]: time="2025-10-13T05:01:07.955977291Z" level=info msg="connecting to shim 81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1" address="unix:///run/containerd/s/1865f63b0ff7c3c918a4069863ba07e5ebaba8b988e77b56ff928251d6e256a3" namespace=k8s.io protocol=ttrpc version=3 Oct 13 05:01:07.958711 containerd[2069]: time="2025-10-13T05:01:07.958673435Z" level=info msg="connecting to shim 26703edb2acc1c1e7bc33642e70d6947082183ebed6e29bfbd5ddbd575b700af" address="unix:///run/containerd/s/1464d18c1e35386e1ba1ea39de1a1e1d950af192bded1eb24c2f06bdd6c07686" namespace=k8s.io protocol=ttrpc version=3 Oct 13 05:01:07.978993 systemd[1]: Started cri-containerd-81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1.scope - libcontainer container 81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1. Oct 13 05:01:07.989945 systemd[1]: Started cri-containerd-26703edb2acc1c1e7bc33642e70d6947082183ebed6e29bfbd5ddbd575b700af.scope - libcontainer container 26703edb2acc1c1e7bc33642e70d6947082183ebed6e29bfbd5ddbd575b700af. Oct 13 05:01:08.026750 containerd[2069]: time="2025-10-13T05:01:08.026705766Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-qqbr7,Uid:07e3cae2-0b32-4a80-b0a6-d4dd6128058c,Namespace:kube-system,Attempt:0,} returns sandbox id \"26703edb2acc1c1e7bc33642e70d6947082183ebed6e29bfbd5ddbd575b700af\"" Oct 13 05:01:08.028901 containerd[2069]: time="2025-10-13T05:01:08.028872801Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Oct 13 05:01:08.032211 containerd[2069]: time="2025-10-13T05:01:08.032183711Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-fpv97,Uid:e2ea323c-24a8-4f15-a23a-430b4b416007,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1\"" Oct 13 05:01:08.601786 kubelet[2656]: E1013 05:01:08.601747 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:08.997204 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1005614538.mount: Deactivated successfully. Oct 13 05:01:09.291627 containerd[2069]: time="2025-10-13T05:01:09.291097071Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:09.295099 containerd[2069]: time="2025-10-13T05:01:09.295069430Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240106" Oct 13 05:01:09.299824 containerd[2069]: time="2025-10-13T05:01:09.299782677Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:09.307889 containerd[2069]: time="2025-10-13T05:01:09.307828380Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:09.308512 containerd[2069]: time="2025-10-13T05:01:09.308192107Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.279282791s" Oct 13 05:01:09.308512 containerd[2069]: time="2025-10-13T05:01:09.308226406Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Oct 13 05:01:09.310085 containerd[2069]: time="2025-10-13T05:01:09.310044459Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 13 05:01:09.318628 containerd[2069]: time="2025-10-13T05:01:09.318580724Z" level=info msg="CreateContainer within sandbox \"26703edb2acc1c1e7bc33642e70d6947082183ebed6e29bfbd5ddbd575b700af\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 13 05:01:09.342591 containerd[2069]: time="2025-10-13T05:01:09.342545945Z" level=info msg="Container 486a64be95edc533f9b37b86041157e5fe1ebbce806e846138a3ec46d9806d53: CDI devices from CRI Config.CDIDevices: []" Oct 13 05:01:09.363311 containerd[2069]: time="2025-10-13T05:01:09.363266087Z" level=info msg="CreateContainer within sandbox \"26703edb2acc1c1e7bc33642e70d6947082183ebed6e29bfbd5ddbd575b700af\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"486a64be95edc533f9b37b86041157e5fe1ebbce806e846138a3ec46d9806d53\"" Oct 13 05:01:09.364497 containerd[2069]: time="2025-10-13T05:01:09.364270221Z" level=info msg="StartContainer for \"486a64be95edc533f9b37b86041157e5fe1ebbce806e846138a3ec46d9806d53\"" Oct 13 05:01:09.365333 containerd[2069]: time="2025-10-13T05:01:09.365309023Z" level=info msg="connecting to shim 486a64be95edc533f9b37b86041157e5fe1ebbce806e846138a3ec46d9806d53" address="unix:///run/containerd/s/1464d18c1e35386e1ba1ea39de1a1e1d950af192bded1eb24c2f06bdd6c07686" protocol=ttrpc version=3 Oct 13 05:01:09.382985 systemd[1]: Started cri-containerd-486a64be95edc533f9b37b86041157e5fe1ebbce806e846138a3ec46d9806d53.scope - libcontainer container 486a64be95edc533f9b37b86041157e5fe1ebbce806e846138a3ec46d9806d53. Oct 13 05:01:09.416702 containerd[2069]: time="2025-10-13T05:01:09.416654625Z" level=info msg="StartContainer for \"486a64be95edc533f9b37b86041157e5fe1ebbce806e846138a3ec46d9806d53\" returns successfully" Oct 13 05:01:09.602385 kubelet[2656]: E1013 05:01:09.602253 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:09.669118 kubelet[2656]: I1013 05:01:09.669043 2656 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-qqbr7" podStartSLOduration=32.388149423 podStartE2EDuration="33.66903088s" podCreationTimestamp="2025-10-13 05:00:36 +0000 UTC" firstStartedPulling="2025-10-13 05:01:08.028270501 +0000 UTC m=+4.202362849" lastFinishedPulling="2025-10-13 05:01:09.309151966 +0000 UTC m=+5.483244306" observedRunningTime="2025-10-13 05:01:09.668861146 +0000 UTC m=+5.842953486" watchObservedRunningTime="2025-10-13 05:01:09.66903088 +0000 UTC m=+5.843123220" Oct 13 05:01:10.420419 update_engine[2034]: I20251013 05:01:10.420212 2034 update_attempter.cc:509] Updating boot flags... Oct 13 05:01:10.603549 kubelet[2656]: E1013 05:01:10.603495 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:11.604392 kubelet[2656]: E1013 05:01:11.604327 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:12.605527 kubelet[2656]: E1013 05:01:12.605479 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:13.606413 kubelet[2656]: E1013 05:01:13.606377 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:14.606484 kubelet[2656]: E1013 05:01:14.606445 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:15.607144 kubelet[2656]: E1013 05:01:15.607096 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:16.608094 kubelet[2656]: E1013 05:01:16.608048 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:17.608742 kubelet[2656]: E1013 05:01:17.608702 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:18.609350 kubelet[2656]: E1013 05:01:18.609305 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:19.610038 kubelet[2656]: E1013 05:01:19.609989 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:20.015248 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1499979970.mount: Deactivated successfully. Oct 13 05:01:20.115366 containerd[2069]: time="2025-10-13T05:01:20.115306826Z" 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\"}" Oct 13 05:01:20.118726 containerd[2069]: time="2025-10-13T05:01:20.118678411Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Oct 13 05:01:20.122748 containerd[2069]: time="2025-10-13T05:01:20.122695107Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:20.128421 containerd[2069]: time="2025-10-13T05:01:20.128367858Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:20.129322 containerd[2069]: time="2025-10-13T05:01:20.129053722Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", 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 \"5125394\" in 10.818879412s" Oct 13 05:01:20.129322 containerd[2069]: time="2025-10-13T05:01:20.129085228Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 13 05:01:20.140013 containerd[2069]: time="2025-10-13T05:01:20.139972388Z" level=info msg="CreateContainer within sandbox \"81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 13 05:01:20.161783 containerd[2069]: time="2025-10-13T05:01:20.160994791Z" level=info msg="Container 4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134: CDI devices from CRI Config.CDIDevices: []" Oct 13 05:01:20.186241 containerd[2069]: time="2025-10-13T05:01:20.186183698Z" level=info msg="CreateContainer within sandbox \"81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134\"" Oct 13 05:01:20.187059 containerd[2069]: time="2025-10-13T05:01:20.187032442Z" level=info msg="StartContainer for \"4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134\"" Oct 13 05:01:20.187742 containerd[2069]: time="2025-10-13T05:01:20.187720499Z" level=info msg="connecting to shim 4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134" address="unix:///run/containerd/s/1865f63b0ff7c3c918a4069863ba07e5ebaba8b988e77b56ff928251d6e256a3" protocol=ttrpc version=3 Oct 13 05:01:20.204953 systemd[1]: Started cri-containerd-4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134.scope - libcontainer container 4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134. Oct 13 05:01:20.225997 systemd[1]: cri-containerd-4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134.scope: Deactivated successfully. Oct 13 05:01:20.228672 containerd[2069]: time="2025-10-13T05:01:20.228626740Z" level=info msg="TaskExit event in podsandbox handler container_id:\"4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134\" id:\"4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134\" pid:3052 exited_at:{seconds:1760331680 nanos:228159933}" Oct 13 05:01:20.270529 containerd[2069]: time="2025-10-13T05:01:20.270398454Z" level=info msg="received exit event container_id:\"4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134\" id:\"4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134\" pid:3052 exited_at:{seconds:1760331680 nanos:228159933}" Oct 13 05:01:20.270794 containerd[2069]: time="2025-10-13T05:01:20.270773655Z" level=info msg="StartContainer for \"4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134\" returns successfully" Oct 13 05:01:20.649157 kubelet[2656]: E1013 05:01:20.610067 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:20.960419 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-4fc5b667b9529e230a6148a86ea5634c2b3bb9c4226022c99510822d92732134-rootfs.mount: Deactivated successfully. Oct 13 05:01:21.611044 kubelet[2656]: E1013 05:01:21.610996 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:21.680461 containerd[2069]: time="2025-10-13T05:01:21.680420912Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 13 05:01:22.611487 kubelet[2656]: E1013 05:01:22.611441 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:23.612407 kubelet[2656]: E1013 05:01:23.612361 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:24.398706 containerd[2069]: time="2025-10-13T05:01:24.398643887Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:24.402009 containerd[2069]: time="2025-10-13T05:01:24.401967950Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Oct 13 05:01:24.406632 containerd[2069]: time="2025-10-13T05:01:24.406578421Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:24.411833 containerd[2069]: time="2025-10-13T05:01:24.411603290Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:24.412505 containerd[2069]: time="2025-10-13T05:01:24.412475075Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 2.732016512s" Oct 13 05:01:24.412598 containerd[2069]: time="2025-10-13T05:01:24.412584593Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 13 05:01:24.420291 containerd[2069]: time="2025-10-13T05:01:24.420251112Z" level=info msg="CreateContainer within sandbox \"81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 13 05:01:24.439737 containerd[2069]: time="2025-10-13T05:01:24.439688458Z" level=info msg="Container 3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d: CDI devices from CRI Config.CDIDevices: []" Oct 13 05:01:24.470781 containerd[2069]: time="2025-10-13T05:01:24.470714843Z" level=info msg="CreateContainer within sandbox \"81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d\"" Oct 13 05:01:24.471541 containerd[2069]: time="2025-10-13T05:01:24.471512384Z" level=info msg="StartContainer for \"3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d\"" Oct 13 05:01:24.472282 containerd[2069]: time="2025-10-13T05:01:24.472256681Z" level=info msg="connecting to shim 3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d" address="unix:///run/containerd/s/1865f63b0ff7c3c918a4069863ba07e5ebaba8b988e77b56ff928251d6e256a3" protocol=ttrpc version=3 Oct 13 05:01:24.491966 systemd[1]: Started cri-containerd-3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d.scope - libcontainer container 3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d. Oct 13 05:01:24.512091 systemd[1]: cri-containerd-3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d.scope: Deactivated successfully. Oct 13 05:01:24.512656 containerd[2069]: time="2025-10-13T05:01:24.512529001Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d\" id:\"3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d\" pid:3127 exited_at:{seconds:1760331684 nanos:512149012}" Oct 13 05:01:24.518117 containerd[2069]: time="2025-10-13T05:01:24.518009472Z" level=info msg="received exit event container_id:\"3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d\" id:\"3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d\" pid:3127 exited_at:{seconds:1760331684 nanos:512149012}" Oct 13 05:01:24.523847 containerd[2069]: time="2025-10-13T05:01:24.523717339Z" level=info msg="StartContainer for \"3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d\" returns successfully" Oct 13 05:01:24.532973 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3ca02b2a8abe9297b8eb08115954461ff8050fe88e2bceb4a4dc5fa540c0217d-rootfs.mount: Deactivated successfully. Oct 13 05:01:24.577684 kubelet[2656]: I1013 05:01:24.577127 2656 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 13 05:01:24.596976 kubelet[2656]: E1013 05:01:24.596930 2656 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:24.646631 kubelet[2656]: E1013 05:01:24.613193 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:25.613919 kubelet[2656]: E1013 05:01:25.613813 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:25.698053 containerd[2069]: time="2025-10-13T05:01:25.697590235Z" level=info msg="CreateContainer within sandbox \"81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 13 05:01:25.725214 containerd[2069]: time="2025-10-13T05:01:25.725177422Z" level=info msg="Container 34d813fbc6a56eaf0f0d4c8a08337bbb4460c934a6a8df39ff55d0db4071d35b: CDI devices from CRI Config.CDIDevices: []" Oct 13 05:01:25.727507 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3437915091.mount: Deactivated successfully. Oct 13 05:01:25.747252 containerd[2069]: time="2025-10-13T05:01:25.747181494Z" level=info msg="CreateContainer within sandbox \"81c189e1c90c783e0dab8eea0d415a79b459e0ebf1d91049118c6b2d74256ef1\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"34d813fbc6a56eaf0f0d4c8a08337bbb4460c934a6a8df39ff55d0db4071d35b\"" Oct 13 05:01:25.748049 containerd[2069]: time="2025-10-13T05:01:25.748024068Z" level=info msg="StartContainer for \"34d813fbc6a56eaf0f0d4c8a08337bbb4460c934a6a8df39ff55d0db4071d35b\"" Oct 13 05:01:25.748731 containerd[2069]: time="2025-10-13T05:01:25.748707682Z" level=info msg="connecting to shim 34d813fbc6a56eaf0f0d4c8a08337bbb4460c934a6a8df39ff55d0db4071d35b" address="unix:///run/containerd/s/1865f63b0ff7c3c918a4069863ba07e5ebaba8b988e77b56ff928251d6e256a3" protocol=ttrpc version=3 Oct 13 05:01:25.769999 systemd[1]: Started cri-containerd-34d813fbc6a56eaf0f0d4c8a08337bbb4460c934a6a8df39ff55d0db4071d35b.scope - libcontainer container 34d813fbc6a56eaf0f0d4c8a08337bbb4460c934a6a8df39ff55d0db4071d35b. Oct 13 05:01:25.800277 containerd[2069]: time="2025-10-13T05:01:25.799602453Z" level=info msg="StartContainer for \"34d813fbc6a56eaf0f0d4c8a08337bbb4460c934a6a8df39ff55d0db4071d35b\" returns successfully" Oct 13 05:01:26.614203 kubelet[2656]: E1013 05:01:26.614066 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:26.856897 systemd-networkd[1858]: flannel.1: Link UP Oct 13 05:01:26.856903 systemd-networkd[1858]: flannel.1: Gained carrier Oct 13 05:01:27.615070 kubelet[2656]: E1013 05:01:27.615009 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:28.131179 systemd-networkd[1858]: flannel.1: Gained IPv6LL Oct 13 05:01:28.615845 kubelet[2656]: E1013 05:01:28.615780 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:29.616789 kubelet[2656]: E1013 05:01:29.616731 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:30.254908 kubelet[2656]: I1013 05:01:30.254811 2656 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-fpv97" podStartSLOduration=37.874807172 podStartE2EDuration="54.254791509s" podCreationTimestamp="2025-10-13 05:00:36 +0000 UTC" firstStartedPulling="2025-10-13 05:01:08.033285926 +0000 UTC m=+4.207378266" lastFinishedPulling="2025-10-13 05:01:24.413270263 +0000 UTC m=+20.587362603" observedRunningTime="2025-10-13 05:01:26.703711088 +0000 UTC m=+22.877803436" watchObservedRunningTime="2025-10-13 05:01:30.254791509 +0000 UTC m=+26.428883849" Oct 13 05:01:30.286099 systemd[1]: Created slice kubepods-besteffort-pod50138bac_41f3_4890_867c_f24494af89f7.slice - libcontainer container kubepods-besteffort-pod50138bac_41f3_4890_867c_f24494af89f7.slice. Oct 13 05:01:30.366855 kubelet[2656]: I1013 05:01:30.366771 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xq5gn\" (UniqueName: \"kubernetes.io/projected/50138bac-41f3-4890-867c-f24494af89f7-kube-api-access-xq5gn\") pod \"nginx-deployment-7fcdb87857-z9wcf\" (UID: \"50138bac-41f3-4890-867c-f24494af89f7\") " pod="default/nginx-deployment-7fcdb87857-z9wcf" Oct 13 05:01:30.589183 containerd[2069]: time="2025-10-13T05:01:30.589066661Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-z9wcf,Uid:50138bac-41f3-4890-867c-f24494af89f7,Namespace:default,Attempt:0,}" Oct 13 05:01:30.612162 systemd-networkd[1858]: cni0: Link UP Oct 13 05:01:30.612169 systemd-networkd[1858]: cni0: Gained carrier Oct 13 05:01:30.613002 systemd-networkd[1858]: cni0: Lost carrier Oct 13 05:01:30.618445 kubelet[2656]: E1013 05:01:30.618173 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:30.621691 systemd-networkd[1858]: veth174434f1: Link UP Oct 13 05:01:30.630284 kernel: cni0: port 1(veth174434f1) entered blocking state Oct 13 05:01:30.630384 kernel: cni0: port 1(veth174434f1) entered disabled state Oct 13 05:01:30.635231 kernel: veth174434f1: entered allmulticast mode Oct 13 05:01:30.638217 kernel: veth174434f1: entered promiscuous mode Oct 13 05:01:30.650551 kernel: cni0: port 1(veth174434f1) entered blocking state Oct 13 05:01:30.650682 kernel: cni0: port 1(veth174434f1) entered forwarding state Oct 13 05:01:30.650963 systemd-networkd[1858]: veth174434f1: Gained carrier Oct 13 05:01:30.651549 systemd-networkd[1858]: cni0: Gained carrier Oct 13 05:01:30.654559 containerd[2069]: 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)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 13 05:01:30.654559 containerd[2069]: delegateAdd: netconf sent to delegate plugin: Oct 13 05:01:30.712646 containerd[2069]: {"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-10-13T05:01:30.712587861Z" level=info msg="connecting to shim 39db08fea5dd05d7c96fa6b5e0b6d26add17ec1be4236a820e147ba039e7d563" address="unix:///run/containerd/s/ef0a803af3b01747ea6a67c06b42a721c7b0ee7040df39222779627a4a668351" namespace=k8s.io protocol=ttrpc version=3 Oct 13 05:01:30.736994 systemd[1]: Started cri-containerd-39db08fea5dd05d7c96fa6b5e0b6d26add17ec1be4236a820e147ba039e7d563.scope - libcontainer container 39db08fea5dd05d7c96fa6b5e0b6d26add17ec1be4236a820e147ba039e7d563. Oct 13 05:01:30.770067 containerd[2069]: time="2025-10-13T05:01:30.770022890Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-z9wcf,Uid:50138bac-41f3-4890-867c-f24494af89f7,Namespace:default,Attempt:0,} returns sandbox id \"39db08fea5dd05d7c96fa6b5e0b6d26add17ec1be4236a820e147ba039e7d563\"" Oct 13 05:01:30.771324 containerd[2069]: time="2025-10-13T05:01:30.771267877Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 13 05:01:31.618576 kubelet[2656]: E1013 05:01:31.618526 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:32.162985 systemd-networkd[1858]: veth174434f1: Gained IPv6LL Oct 13 05:01:32.483992 systemd-networkd[1858]: cni0: Gained IPv6LL Oct 13 05:01:32.619063 kubelet[2656]: E1013 05:01:32.619027 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:33.087369 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3805388414.mount: Deactivated successfully. Oct 13 05:01:33.619868 kubelet[2656]: E1013 05:01:33.619806 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:34.620516 kubelet[2656]: E1013 05:01:34.620475 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:35.621043 kubelet[2656]: E1013 05:01:35.620939 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:36.621360 kubelet[2656]: E1013 05:01:36.621317 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:37.622281 kubelet[2656]: E1013 05:01:37.622237 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:38.558192 containerd[2069]: time="2025-10-13T05:01:38.558132197Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:38.565640 containerd[2069]: time="2025-10-13T05:01:38.565595504Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70015687" Oct 13 05:01:38.569663 containerd[2069]: time="2025-10-13T05:01:38.569610668Z" level=info msg="ImageCreate event name:\"sha256:e1e3942d93b7c9e68a5e902395859d4f53de5aa9a187cba800c72cee6f9cb03f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:38.574926 containerd[2069]: time="2025-10-13T05:01:38.574868710Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0c4ba30a5f6a65d2bbdf93f2eff51d5304fd8c7f92cfc83a135a226aa2cd96af\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:38.575594 containerd[2069]: time="2025-10-13T05:01:38.575312646Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e1e3942d93b7c9e68a5e902395859d4f53de5aa9a187cba800c72cee6f9cb03f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0c4ba30a5f6a65d2bbdf93f2eff51d5304fd8c7f92cfc83a135a226aa2cd96af\", size \"70015565\" in 7.804016871s" Oct 13 05:01:38.575594 containerd[2069]: time="2025-10-13T05:01:38.575345392Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e1e3942d93b7c9e68a5e902395859d4f53de5aa9a187cba800c72cee6f9cb03f\"" Oct 13 05:01:38.585727 containerd[2069]: time="2025-10-13T05:01:38.585694609Z" level=info msg="CreateContainer within sandbox \"39db08fea5dd05d7c96fa6b5e0b6d26add17ec1be4236a820e147ba039e7d563\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 13 05:01:38.609800 containerd[2069]: time="2025-10-13T05:01:38.609367719Z" level=info msg="Container c6d24680a338bee9c18397c878267acb7ed252b61fe79943fbbcd3ec8faa678a: CDI devices from CRI Config.CDIDevices: []" Oct 13 05:01:38.623239 kubelet[2656]: E1013 05:01:38.623192 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:38.630458 containerd[2069]: time="2025-10-13T05:01:38.630415933Z" level=info msg="CreateContainer within sandbox \"39db08fea5dd05d7c96fa6b5e0b6d26add17ec1be4236a820e147ba039e7d563\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"c6d24680a338bee9c18397c878267acb7ed252b61fe79943fbbcd3ec8faa678a\"" Oct 13 05:01:38.631184 containerd[2069]: time="2025-10-13T05:01:38.630976020Z" level=info msg="StartContainer for \"c6d24680a338bee9c18397c878267acb7ed252b61fe79943fbbcd3ec8faa678a\"" Oct 13 05:01:38.631694 containerd[2069]: time="2025-10-13T05:01:38.631671395Z" level=info msg="connecting to shim c6d24680a338bee9c18397c878267acb7ed252b61fe79943fbbcd3ec8faa678a" address="unix:///run/containerd/s/ef0a803af3b01747ea6a67c06b42a721c7b0ee7040df39222779627a4a668351" protocol=ttrpc version=3 Oct 13 05:01:38.651968 systemd[1]: Started cri-containerd-c6d24680a338bee9c18397c878267acb7ed252b61fe79943fbbcd3ec8faa678a.scope - libcontainer container c6d24680a338bee9c18397c878267acb7ed252b61fe79943fbbcd3ec8faa678a. Oct 13 05:01:38.680060 containerd[2069]: time="2025-10-13T05:01:38.679957059Z" level=info msg="StartContainer for \"c6d24680a338bee9c18397c878267acb7ed252b61fe79943fbbcd3ec8faa678a\" returns successfully" Oct 13 05:01:39.624011 kubelet[2656]: E1013 05:01:39.623958 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:40.624540 kubelet[2656]: E1013 05:01:40.624403 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:41.625589 kubelet[2656]: E1013 05:01:41.625533 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:42.626621 kubelet[2656]: E1013 05:01:42.626568 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:43.626844 kubelet[2656]: E1013 05:01:43.626772 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:44.597666 kubelet[2656]: E1013 05:01:44.597616 2656 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:44.627024 kubelet[2656]: E1013 05:01:44.626990 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:44.975069 kubelet[2656]: I1013 05:01:44.975010 2656 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-z9wcf" podStartSLOduration=36.169890218 podStartE2EDuration="43.974992207s" podCreationTimestamp="2025-10-13 05:01:01 +0000 UTC" firstStartedPulling="2025-10-13 05:01:30.771035343 +0000 UTC m=+26.945127683" lastFinishedPulling="2025-10-13 05:01:38.576137332 +0000 UTC m=+34.750229672" observedRunningTime="2025-10-13 05:01:38.723516175 +0000 UTC m=+34.897608515" watchObservedRunningTime="2025-10-13 05:01:44.974992207 +0000 UTC m=+41.149084555" Oct 13 05:01:44.985643 systemd[1]: Created slice kubepods-besteffort-podf4b0d5b1_c5fc_4b6e_827e_1955e24b1567.slice - libcontainer container kubepods-besteffort-podf4b0d5b1_c5fc_4b6e_827e_1955e24b1567.slice. Oct 13 05:01:45.051776 kubelet[2656]: I1013 05:01:45.051733 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/f4b0d5b1-c5fc-4b6e-827e-1955e24b1567-data\") pod \"nfs-server-provisioner-0\" (UID: \"f4b0d5b1-c5fc-4b6e-827e-1955e24b1567\") " pod="default/nfs-server-provisioner-0" Oct 13 05:01:45.051776 kubelet[2656]: I1013 05:01:45.051774 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-p58fq\" (UniqueName: \"kubernetes.io/projected/f4b0d5b1-c5fc-4b6e-827e-1955e24b1567-kube-api-access-p58fq\") pod \"nfs-server-provisioner-0\" (UID: \"f4b0d5b1-c5fc-4b6e-827e-1955e24b1567\") " pod="default/nfs-server-provisioner-0" Oct 13 05:01:45.288720 containerd[2069]: time="2025-10-13T05:01:45.288602073Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f4b0d5b1-c5fc-4b6e-827e-1955e24b1567,Namespace:default,Attempt:0,}" Oct 13 05:01:45.316244 systemd-networkd[1858]: veth192f25bf: Link UP Oct 13 05:01:45.324014 kernel: cni0: port 2(veth192f25bf) entered blocking state Oct 13 05:01:45.324122 kernel: cni0: port 2(veth192f25bf) entered disabled state Oct 13 05:01:45.328989 kernel: veth192f25bf: entered allmulticast mode Oct 13 05:01:45.332322 kernel: veth192f25bf: entered promiscuous mode Oct 13 05:01:45.342674 kernel: cni0: port 2(veth192f25bf) entered blocking state Oct 13 05:01:45.342842 kernel: cni0: port 2(veth192f25bf) entered forwarding state Oct 13 05:01:45.342794 systemd-networkd[1858]: veth192f25bf: Gained carrier Oct 13 05:01:45.345310 containerd[2069]: 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)(0x400000e840), "name":"cbr0", "type":"bridge"} Oct 13 05:01:45.345310 containerd[2069]: delegateAdd: netconf sent to delegate plugin: Oct 13 05:01:45.435352 containerd[2069]: {"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-10-13T05:01:45.435312644Z" level=info msg="connecting to shim 0793fd6422a4aab9ff0dc8fed384c2b29a520abfdba6d58259ce75955913f47f" address="unix:///run/containerd/s/9685465c655431dcfcd3b1adb6f1119d9369314ed8bdeb6f425d7ec002b523f7" namespace=k8s.io protocol=ttrpc version=3 Oct 13 05:01:45.454972 systemd[1]: Started cri-containerd-0793fd6422a4aab9ff0dc8fed384c2b29a520abfdba6d58259ce75955913f47f.scope - libcontainer container 0793fd6422a4aab9ff0dc8fed384c2b29a520abfdba6d58259ce75955913f47f. Oct 13 05:01:45.484321 containerd[2069]: time="2025-10-13T05:01:45.484265956Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f4b0d5b1-c5fc-4b6e-827e-1955e24b1567,Namespace:default,Attempt:0,} returns sandbox id \"0793fd6422a4aab9ff0dc8fed384c2b29a520abfdba6d58259ce75955913f47f\"" Oct 13 05:01:45.486145 containerd[2069]: time="2025-10-13T05:01:45.486001789Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 13 05:01:45.627931 kubelet[2656]: E1013 05:01:45.627800 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:46.628337 kubelet[2656]: E1013 05:01:46.628281 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:47.330995 systemd-networkd[1858]: veth192f25bf: Gained IPv6LL Oct 13 05:01:47.441906 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3911358066.mount: Deactivated successfully. Oct 13 05:01:47.629166 kubelet[2656]: E1013 05:01:47.629027 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:48.629634 kubelet[2656]: E1013 05:01:48.629589 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:49.073138 containerd[2069]: time="2025-10-13T05:01:49.073076225Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:49.077739 containerd[2069]: time="2025-10-13T05:01:49.077699232Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Oct 13 05:01:49.081669 containerd[2069]: time="2025-10-13T05:01:49.081634246Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:49.086882 containerd[2069]: time="2025-10-13T05:01:49.086845296Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:01:49.088005 containerd[2069]: time="2025-10-13T05:01:49.087971421Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.601557948s" Oct 13 05:01:49.088028 containerd[2069]: time="2025-10-13T05:01:49.088008678Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 13 05:01:49.096192 containerd[2069]: time="2025-10-13T05:01:49.096158201Z" level=info msg="CreateContainer within sandbox \"0793fd6422a4aab9ff0dc8fed384c2b29a520abfdba6d58259ce75955913f47f\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 13 05:01:49.115570 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount813592283.mount: Deactivated successfully. Oct 13 05:01:49.116674 containerd[2069]: time="2025-10-13T05:01:49.116616575Z" level=info msg="Container 4877fa728f41689257165c5208ca44db2499b2786ee7c1ac03826a0ca275ac51: CDI devices from CRI Config.CDIDevices: []" Oct 13 05:01:49.139913 containerd[2069]: time="2025-10-13T05:01:49.139860430Z" level=info msg="CreateContainer within sandbox \"0793fd6422a4aab9ff0dc8fed384c2b29a520abfdba6d58259ce75955913f47f\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"4877fa728f41689257165c5208ca44db2499b2786ee7c1ac03826a0ca275ac51\"" Oct 13 05:01:49.141719 containerd[2069]: time="2025-10-13T05:01:49.141671818Z" level=info msg="StartContainer for \"4877fa728f41689257165c5208ca44db2499b2786ee7c1ac03826a0ca275ac51\"" Oct 13 05:01:49.145650 containerd[2069]: time="2025-10-13T05:01:49.145597552Z" level=info msg="connecting to shim 4877fa728f41689257165c5208ca44db2499b2786ee7c1ac03826a0ca275ac51" address="unix:///run/containerd/s/9685465c655431dcfcd3b1adb6f1119d9369314ed8bdeb6f425d7ec002b523f7" protocol=ttrpc version=3 Oct 13 05:01:49.162953 systemd[1]: Started cri-containerd-4877fa728f41689257165c5208ca44db2499b2786ee7c1ac03826a0ca275ac51.scope - libcontainer container 4877fa728f41689257165c5208ca44db2499b2786ee7c1ac03826a0ca275ac51. Oct 13 05:01:49.191176 containerd[2069]: time="2025-10-13T05:01:49.191135043Z" level=info msg="StartContainer for \"4877fa728f41689257165c5208ca44db2499b2786ee7c1ac03826a0ca275ac51\" returns successfully" Oct 13 05:01:49.630085 kubelet[2656]: E1013 05:01:49.629957 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:49.743491 kubelet[2656]: I1013 05:01:49.743432 2656 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=30.140474961 podStartE2EDuration="33.743415829s" podCreationTimestamp="2025-10-13 05:01:16 +0000 UTC" firstStartedPulling="2025-10-13 05:01:45.485738273 +0000 UTC m=+41.659830613" lastFinishedPulling="2025-10-13 05:01:49.088679141 +0000 UTC m=+45.262771481" observedRunningTime="2025-10-13 05:01:49.742840746 +0000 UTC m=+45.916933086" watchObservedRunningTime="2025-10-13 05:01:49.743415829 +0000 UTC m=+45.917508177" Oct 13 05:01:50.630297 kubelet[2656]: E1013 05:01:50.630243 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:51.631414 kubelet[2656]: E1013 05:01:51.631369 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:52.632484 kubelet[2656]: E1013 05:01:52.632426 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:53.632994 kubelet[2656]: E1013 05:01:53.632932 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:54.457222 systemd[1]: Created slice kubepods-besteffort-podc52f2e32_5995_4c63_861e_b926ec61b483.slice - libcontainer container kubepods-besteffort-podc52f2e32_5995_4c63_861e_b926ec61b483.slice. Oct 13 05:01:54.504953 kubelet[2656]: I1013 05:01:54.504908 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-a861d16a-3c5d-4257-b3aa-3e9d0c035be9\" (UniqueName: \"kubernetes.io/nfs/c52f2e32-5995-4c63-861e-b926ec61b483-pvc-a861d16a-3c5d-4257-b3aa-3e9d0c035be9\") pod \"test-pod-1\" (UID: \"c52f2e32-5995-4c63-861e-b926ec61b483\") " pod="default/test-pod-1" Oct 13 05:01:54.504953 kubelet[2656]: I1013 05:01:54.504945 2656 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lql6q\" (UniqueName: \"kubernetes.io/projected/c52f2e32-5995-4c63-861e-b926ec61b483-kube-api-access-lql6q\") pod \"test-pod-1\" (UID: \"c52f2e32-5995-4c63-861e-b926ec61b483\") " pod="default/test-pod-1" Oct 13 05:01:54.633342 kubelet[2656]: E1013 05:01:54.633297 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:54.965940 kernel: netfs: FS-Cache loaded Oct 13 05:01:55.079492 kernel: RPC: Registered named UNIX socket transport module. Oct 13 05:01:55.079626 kernel: RPC: Registered udp transport module. Oct 13 05:01:55.079644 kernel: RPC: Registered tcp transport module. Oct 13 05:01:55.084924 kernel: RPC: Registered tcp-with-tls transport module. Oct 13 05:01:55.084969 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 13 05:01:55.476596 kernel: NFS: Registering the id_resolver key type Oct 13 05:01:55.476729 kernel: Key type id_resolver registered Oct 13 05:01:55.476747 kernel: Key type id_legacy registered Oct 13 05:01:55.633853 kubelet[2656]: E1013 05:01:55.633799 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:55.828643 nfsidmap[3716]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-a-8fed64613a' Oct 13 05:01:56.116990 nfsidmap[3717]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-a-8fed64613a' Oct 13 05:01:56.128296 nfsrahead[3720]: setting /var/lib/kubelet/pods/c52f2e32-5995-4c63-861e-b926ec61b483/volumes/kubernetes.io~nfs/pvc-a861d16a-3c5d-4257-b3aa-3e9d0c035be9 readahead to 128 Oct 13 05:01:56.260364 containerd[2069]: time="2025-10-13T05:01:56.260088572Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:c52f2e32-5995-4c63-861e-b926ec61b483,Namespace:default,Attempt:0,}" Oct 13 05:01:56.284168 systemd-networkd[1858]: veth93d02557: Link UP Oct 13 05:01:56.291857 kernel: cni0: port 3(veth93d02557) entered blocking state Oct 13 05:01:56.293875 kernel: cni0: port 3(veth93d02557) entered disabled state Oct 13 05:01:56.293933 kernel: veth93d02557: entered allmulticast mode Oct 13 05:01:56.298729 kernel: veth93d02557: entered promiscuous mode Oct 13 05:01:56.309547 kernel: cni0: port 3(veth93d02557) entered blocking state Oct 13 05:01:56.309634 kernel: cni0: port 3(veth93d02557) entered forwarding state Oct 13 05:01:56.309718 systemd-networkd[1858]: veth93d02557: Gained carrier Oct 13 05:01:56.311921 containerd[2069]: 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)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 13 05:01:56.311921 containerd[2069]: delegateAdd: netconf sent to delegate plugin: Oct 13 05:01:56.370526 containerd[2069]: {"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-10-13T05:01:56.370060398Z" level=info msg="connecting to shim 37dbfdb40b8ed76f9134ef4e68f5aa1bfea7beb6078ff6052b097b2d418c2d06" address="unix:///run/containerd/s/850ef761edbf30582cc1286f70167b7b650ec4c280daaad4159dafec6a524c3b" namespace=k8s.io protocol=ttrpc version=3 Oct 13 05:01:56.392991 systemd[1]: Started cri-containerd-37dbfdb40b8ed76f9134ef4e68f5aa1bfea7beb6078ff6052b097b2d418c2d06.scope - libcontainer container 37dbfdb40b8ed76f9134ef4e68f5aa1bfea7beb6078ff6052b097b2d418c2d06. Oct 13 05:01:56.423120 containerd[2069]: time="2025-10-13T05:01:56.422999566Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:c52f2e32-5995-4c63-861e-b926ec61b483,Namespace:default,Attempt:0,} returns sandbox id \"37dbfdb40b8ed76f9134ef4e68f5aa1bfea7beb6078ff6052b097b2d418c2d06\"" Oct 13 05:01:56.424160 containerd[2069]: time="2025-10-13T05:01:56.424080478Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 13 05:01:56.635368 kubelet[2656]: E1013 05:01:56.635234 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:57.635638 kubelet[2656]: E1013 05:01:57.635590 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:57.762991 systemd-networkd[1858]: veth93d02557: Gained IPv6LL Oct 13 05:01:58.636780 kubelet[2656]: E1013 05:01:58.636717 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:01:59.637411 kubelet[2656]: E1013 05:01:59.637354 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:00.637491 kubelet[2656]: E1013 05:02:00.637450 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:01.638545 kubelet[2656]: E1013 05:02:01.638494 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:02.639304 kubelet[2656]: E1013 05:02:02.639254 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:03.640139 kubelet[2656]: E1013 05:02:03.640092 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:04.597724 kubelet[2656]: E1013 05:02:04.597671 2656 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:04.640254 kubelet[2656]: E1013 05:02:04.640206 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:04.865952 containerd[2069]: time="2025-10-13T05:02:04.865614621Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 13 05:02:04.873528 containerd[2069]: time="2025-10-13T05:02:04.871799678Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 13 05:02:04.873528 containerd[2069]: time="2025-10-13T05:02:04.873399529Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e1e3942d93b7c9e68a5e902395859d4f53de5aa9a187cba800c72cee6f9cb03f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0c4ba30a5f6a65d2bbdf93f2eff51d5304fd8c7f92cfc83a135a226aa2cd96af\", size \"70015565\" in 8.449247047s" Oct 13 05:02:04.873528 containerd[2069]: time="2025-10-13T05:02:04.873429594Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e1e3942d93b7c9e68a5e902395859d4f53de5aa9a187cba800c72cee6f9cb03f\"" Oct 13 05:02:04.882355 containerd[2069]: time="2025-10-13T05:02:04.882322825Z" level=info msg="CreateContainer within sandbox \"37dbfdb40b8ed76f9134ef4e68f5aa1bfea7beb6078ff6052b097b2d418c2d06\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 13 05:02:04.907021 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3269590744.mount: Deactivated successfully. Oct 13 05:02:04.909436 containerd[2069]: time="2025-10-13T05:02:04.909398273Z" level=info msg="Container a4ae7c065d518f10573d20c58f96915e4db8060d1db53f2980df2d846d41efb5: CDI devices from CRI Config.CDIDevices: []" Oct 13 05:02:04.938354 containerd[2069]: time="2025-10-13T05:02:04.938305319Z" level=info msg="CreateContainer within sandbox \"37dbfdb40b8ed76f9134ef4e68f5aa1bfea7beb6078ff6052b097b2d418c2d06\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a4ae7c065d518f10573d20c58f96915e4db8060d1db53f2980df2d846d41efb5\"" Oct 13 05:02:04.939340 containerd[2069]: time="2025-10-13T05:02:04.939311878Z" level=info msg="StartContainer for \"a4ae7c065d518f10573d20c58f96915e4db8060d1db53f2980df2d846d41efb5\"" Oct 13 05:02:04.940335 containerd[2069]: time="2025-10-13T05:02:04.940297204Z" level=info msg="connecting to shim a4ae7c065d518f10573d20c58f96915e4db8060d1db53f2980df2d846d41efb5" address="unix:///run/containerd/s/850ef761edbf30582cc1286f70167b7b650ec4c280daaad4159dafec6a524c3b" protocol=ttrpc version=3 Oct 13 05:02:04.958977 systemd[1]: Started cri-containerd-a4ae7c065d518f10573d20c58f96915e4db8060d1db53f2980df2d846d41efb5.scope - libcontainer container a4ae7c065d518f10573d20c58f96915e4db8060d1db53f2980df2d846d41efb5. Oct 13 05:02:04.989017 containerd[2069]: time="2025-10-13T05:02:04.988981908Z" level=info msg="StartContainer for \"a4ae7c065d518f10573d20c58f96915e4db8060d1db53f2980df2d846d41efb5\" returns successfully" Oct 13 05:02:05.641114 kubelet[2656]: E1013 05:02:05.641076 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:06.641175 kubelet[2656]: E1013 05:02:06.641145 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:07.641652 kubelet[2656]: E1013 05:02:07.641611 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:08.641738 kubelet[2656]: E1013 05:02:08.641680 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:09.641986 kubelet[2656]: E1013 05:02:09.641940 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:10.642093 kubelet[2656]: E1013 05:02:10.642058 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:11.642559 kubelet[2656]: E1013 05:02:11.642499 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 13 05:02:12.642761 kubelet[2656]: E1013 05:02:12.642724 2656 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"