Nov 6 23:16:40.203759 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 6 23:16:40.203780 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT Thu Nov 6 21:59:06 -00 2025 Nov 6 23:16:40.203788 kernel: KASLR enabled Nov 6 23:16:40.203794 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Nov 6 23:16:40.203801 kernel: printk: bootconsole [pl11] enabled Nov 6 23:16:40.203806 kernel: efi: EFI v2.7 by EDK II Nov 6 23:16:40.203813 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f20e018 RNG=0x3fd5f998 MEMRESERVE=0x3e477598 Nov 6 23:16:40.203819 kernel: random: crng init done Nov 6 23:16:40.203825 kernel: secureboot: Secure boot disabled Nov 6 23:16:40.203831 kernel: ACPI: Early table checksum verification disabled Nov 6 23:16:40.203837 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) Nov 6 23:16:40.203843 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203849 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203856 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) Nov 6 23:16:40.203863 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203869 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203875 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203883 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203889 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203895 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203901 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Nov 6 23:16:40.203907 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:16:40.203914 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Nov 6 23:16:40.203920 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] Nov 6 23:16:40.203926 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] Nov 6 23:16:40.203932 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] Nov 6 23:16:40.203938 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] Nov 6 23:16:40.203944 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] Nov 6 23:16:40.203952 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] Nov 6 23:16:40.203958 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] Nov 6 23:16:40.203964 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] Nov 6 23:16:40.203970 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] Nov 6 23:16:40.203976 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] Nov 6 23:16:40.203982 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] Nov 6 23:16:40.203988 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] Nov 6 23:16:40.203994 kernel: NUMA: NODE_DATA [mem 0x1bf7f0800-0x1bf7f5fff] Nov 6 23:16:40.204001 kernel: Zone ranges: Nov 6 23:16:40.204007 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Nov 6 23:16:40.204013 kernel: DMA32 empty Nov 6 23:16:40.204019 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Nov 6 23:16:40.204029 kernel: Movable zone start for each node Nov 6 23:16:40.204035 kernel: Early memory node ranges Nov 6 23:16:40.204042 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Nov 6 23:16:40.204048 kernel: node 0: [mem 0x0000000000824000-0x000000003e45ffff] Nov 6 23:16:40.204055 kernel: node 0: [mem 0x000000003e460000-0x000000003e46ffff] Nov 6 23:16:40.204062 kernel: node 0: [mem 0x000000003e470000-0x000000003e54ffff] Nov 6 23:16:40.204069 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] Nov 6 23:16:40.204075 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] Nov 6 23:16:40.204082 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] Nov 6 23:16:40.204088 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] Nov 6 23:16:40.204095 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Nov 6 23:16:40.204101 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Nov 6 23:16:40.204108 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Nov 6 23:16:40.204114 kernel: psci: probing for conduit method from ACPI. Nov 6 23:16:40.204120 kernel: psci: PSCIv1.1 detected in firmware. Nov 6 23:16:40.204127 kernel: psci: Using standard PSCI v0.2 function IDs Nov 6 23:16:40.204133 kernel: psci: MIGRATE_INFO_TYPE not supported. Nov 6 23:16:40.204141 kernel: psci: SMC Calling Convention v1.4 Nov 6 23:16:40.204147 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Nov 6 23:16:40.204154 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Nov 6 23:16:40.204160 kernel: percpu: Embedded 31 pages/cpu s86120 r8192 d32664 u126976 Nov 6 23:16:40.204167 kernel: pcpu-alloc: s86120 r8192 d32664 u126976 alloc=31*4096 Nov 6 23:16:40.204173 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 6 23:16:40.204180 kernel: Detected PIPT I-cache on CPU0 Nov 6 23:16:40.204186 kernel: CPU features: detected: GIC system register CPU interface Nov 6 23:16:40.204193 kernel: CPU features: detected: Hardware dirty bit management Nov 6 23:16:40.204199 kernel: CPU features: detected: Spectre-BHB Nov 6 23:16:40.204205 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 6 23:16:40.204214 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 6 23:16:40.204221 kernel: CPU features: detected: ARM erratum 1418040 Nov 6 23:16:40.204227 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Nov 6 23:16:40.206266 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 6 23:16:40.206280 kernel: alternatives: applying boot alternatives Nov 6 23:16:40.206289 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=463065366e5b9a391e66d180eedbf8fe1b0462c2e722921ef25580943d9b67c6 Nov 6 23:16:40.206296 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 6 23:16:40.206303 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 6 23:16:40.206310 kernel: Fallback order for Node 0: 0 Nov 6 23:16:40.206316 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Nov 6 23:16:40.206323 kernel: Policy zone: Normal Nov 6 23:16:40.206334 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 6 23:16:40.206340 kernel: software IO TLB: area num 2. Nov 6 23:16:40.206347 kernel: software IO TLB: mapped [mem 0x0000000036530000-0x000000003a530000] (64MB) Nov 6 23:16:40.206354 kernel: Memory: 3983532K/4194160K available (10368K kernel code, 2180K rwdata, 8104K rodata, 38400K init, 897K bss, 210628K reserved, 0K cma-reserved) Nov 6 23:16:40.206361 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 6 23:16:40.206367 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 6 23:16:40.206374 kernel: rcu: RCU event tracing is enabled. Nov 6 23:16:40.206381 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 6 23:16:40.206388 kernel: Trampoline variant of Tasks RCU enabled. Nov 6 23:16:40.206394 kernel: Tracing variant of Tasks RCU enabled. Nov 6 23:16:40.206401 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 6 23:16:40.206409 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 6 23:16:40.206416 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 6 23:16:40.206422 kernel: GICv3: 960 SPIs implemented Nov 6 23:16:40.206429 kernel: GICv3: 0 Extended SPIs implemented Nov 6 23:16:40.206436 kernel: Root IRQ handler: gic_handle_irq Nov 6 23:16:40.206442 kernel: GICv3: GICv3 features: 16 PPIs, RSS Nov 6 23:16:40.206449 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Nov 6 23:16:40.206455 kernel: ITS: No ITS available, not enabling LPIs Nov 6 23:16:40.206462 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 6 23:16:40.206468 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 6 23:16:40.206475 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 6 23:16:40.206482 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 6 23:16:40.206490 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 6 23:16:40.206497 kernel: Console: colour dummy device 80x25 Nov 6 23:16:40.206504 kernel: printk: console [tty1] enabled Nov 6 23:16:40.206511 kernel: ACPI: Core revision 20230628 Nov 6 23:16:40.206517 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 6 23:16:40.206524 kernel: pid_max: default: 32768 minimum: 301 Nov 6 23:16:40.206531 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 6 23:16:40.206538 kernel: landlock: Up and running. Nov 6 23:16:40.206545 kernel: SELinux: Initializing. Nov 6 23:16:40.206553 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 6 23:16:40.206559 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 6 23:16:40.206566 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 6 23:16:40.206573 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 6 23:16:40.206580 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0x100000e, misc 0x31e1 Nov 6 23:16:40.206586 kernel: Hyper-V: Host Build 10.0.26100.1382-1-0 Nov 6 23:16:40.206593 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 6 23:16:40.206606 kernel: rcu: Hierarchical SRCU implementation. Nov 6 23:16:40.206613 kernel: rcu: Max phase no-delay instances is 400. Nov 6 23:16:40.206620 kernel: Remapping and enabling EFI services. Nov 6 23:16:40.206627 kernel: smp: Bringing up secondary CPUs ... Nov 6 23:16:40.206634 kernel: Detected PIPT I-cache on CPU1 Nov 6 23:16:40.206643 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Nov 6 23:16:40.206650 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 6 23:16:40.206657 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 6 23:16:40.206664 kernel: smp: Brought up 1 node, 2 CPUs Nov 6 23:16:40.206671 kernel: SMP: Total of 2 processors activated. Nov 6 23:16:40.206680 kernel: CPU features: detected: 32-bit EL0 Support Nov 6 23:16:40.206687 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Nov 6 23:16:40.206694 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 6 23:16:40.206701 kernel: CPU features: detected: CRC32 instructions Nov 6 23:16:40.206708 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 6 23:16:40.206715 kernel: CPU features: detected: LSE atomic instructions Nov 6 23:16:40.206722 kernel: CPU features: detected: Privileged Access Never Nov 6 23:16:40.206729 kernel: CPU: All CPU(s) started at EL1 Nov 6 23:16:40.206736 kernel: alternatives: applying system-wide alternatives Nov 6 23:16:40.206744 kernel: devtmpfs: initialized Nov 6 23:16:40.206751 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 6 23:16:40.206758 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 6 23:16:40.206765 kernel: pinctrl core: initialized pinctrl subsystem Nov 6 23:16:40.206772 kernel: SMBIOS 3.1.0 present. Nov 6 23:16:40.206780 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 Nov 6 23:16:40.206787 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 6 23:16:40.206794 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 6 23:16:40.206801 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 6 23:16:40.206810 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 6 23:16:40.206817 kernel: audit: initializing netlink subsys (disabled) Nov 6 23:16:40.206824 kernel: audit: type=2000 audit(0.047:1): state=initialized audit_enabled=0 res=1 Nov 6 23:16:40.206831 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 6 23:16:40.206838 kernel: cpuidle: using governor menu Nov 6 23:16:40.206845 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 6 23:16:40.206852 kernel: ASID allocator initialised with 32768 entries Nov 6 23:16:40.206859 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 6 23:16:40.206866 kernel: Serial: AMBA PL011 UART driver Nov 6 23:16:40.206875 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 6 23:16:40.206882 kernel: Modules: 0 pages in range for non-PLT usage Nov 6 23:16:40.206889 kernel: Modules: 509248 pages in range for PLT usage Nov 6 23:16:40.206896 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 6 23:16:40.206903 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 6 23:16:40.206910 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 6 23:16:40.206917 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 6 23:16:40.206924 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 6 23:16:40.206931 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 6 23:16:40.206940 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 6 23:16:40.206947 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 6 23:16:40.206953 kernel: ACPI: Added _OSI(Module Device) Nov 6 23:16:40.206960 kernel: ACPI: Added _OSI(Processor Device) Nov 6 23:16:40.206967 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 6 23:16:40.206974 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 6 23:16:40.206981 kernel: ACPI: Interpreter enabled Nov 6 23:16:40.206988 kernel: ACPI: Using GIC for interrupt routing Nov 6 23:16:40.206995 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Nov 6 23:16:40.207004 kernel: printk: console [ttyAMA0] enabled Nov 6 23:16:40.207011 kernel: printk: bootconsole [pl11] disabled Nov 6 23:16:40.207018 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Nov 6 23:16:40.207024 kernel: iommu: Default domain type: Translated Nov 6 23:16:40.207032 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 6 23:16:40.207039 kernel: efivars: Registered efivars operations Nov 6 23:16:40.207046 kernel: vgaarb: loaded Nov 6 23:16:40.207053 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 6 23:16:40.207060 kernel: VFS: Disk quotas dquot_6.6.0 Nov 6 23:16:40.207068 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 6 23:16:40.207075 kernel: pnp: PnP ACPI init Nov 6 23:16:40.207082 kernel: pnp: PnP ACPI: found 0 devices Nov 6 23:16:40.207090 kernel: NET: Registered PF_INET protocol family Nov 6 23:16:40.207097 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 6 23:16:40.207104 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 6 23:16:40.207111 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 6 23:16:40.207118 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 6 23:16:40.207125 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 6 23:16:40.207134 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 6 23:16:40.207141 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 6 23:16:40.207148 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 6 23:16:40.207155 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 6 23:16:40.207162 kernel: PCI: CLS 0 bytes, default 64 Nov 6 23:16:40.207169 kernel: kvm [1]: HYP mode not available Nov 6 23:16:40.207176 kernel: Initialise system trusted keyrings Nov 6 23:16:40.207183 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 6 23:16:40.207190 kernel: Key type asymmetric registered Nov 6 23:16:40.207199 kernel: Asymmetric key parser 'x509' registered Nov 6 23:16:40.207206 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 6 23:16:40.207213 kernel: io scheduler mq-deadline registered Nov 6 23:16:40.207220 kernel: io scheduler kyber registered Nov 6 23:16:40.207227 kernel: io scheduler bfq registered Nov 6 23:16:40.207241 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 6 23:16:40.207248 kernel: thunder_xcv, ver 1.0 Nov 6 23:16:40.207255 kernel: thunder_bgx, ver 1.0 Nov 6 23:16:40.207262 kernel: nicpf, ver 1.0 Nov 6 23:16:40.207273 kernel: nicvf, ver 1.0 Nov 6 23:16:40.207417 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 6 23:16:40.207489 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-06T23:16:39 UTC (1762470999) Nov 6 23:16:40.207499 kernel: efifb: probing for efifb Nov 6 23:16:40.207506 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 6 23:16:40.207514 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 6 23:16:40.207521 kernel: efifb: scrolling: redraw Nov 6 23:16:40.207528 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 6 23:16:40.207537 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 23:16:40.207544 kernel: fb0: EFI VGA frame buffer device Nov 6 23:16:40.207551 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Nov 6 23:16:40.207558 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 6 23:16:40.207565 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 6 counters available Nov 6 23:16:40.207573 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 6 23:16:40.207580 kernel: watchdog: Hard watchdog permanently disabled Nov 6 23:16:40.207587 kernel: NET: Registered PF_INET6 protocol family Nov 6 23:16:40.207594 kernel: Segment Routing with IPv6 Nov 6 23:16:40.207602 kernel: In-situ OAM (IOAM) with IPv6 Nov 6 23:16:40.207609 kernel: NET: Registered PF_PACKET protocol family Nov 6 23:16:40.207616 kernel: Key type dns_resolver registered Nov 6 23:16:40.207623 kernel: registered taskstats version 1 Nov 6 23:16:40.207630 kernel: Loading compiled-in X.509 certificates Nov 6 23:16:40.207638 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: e53d3b094875ce4245a8b2684246260baeee1996' Nov 6 23:16:40.207645 kernel: Key type .fscrypt registered Nov 6 23:16:40.207652 kernel: Key type fscrypt-provisioning registered Nov 6 23:16:40.207659 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 6 23:16:40.207668 kernel: ima: Allocated hash algorithm: sha1 Nov 6 23:16:40.207675 kernel: ima: No architecture policies found Nov 6 23:16:40.207682 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 6 23:16:40.207689 kernel: clk: Disabling unused clocks Nov 6 23:16:40.207696 kernel: Freeing unused kernel memory: 38400K Nov 6 23:16:40.207703 kernel: Run /init as init process Nov 6 23:16:40.207710 kernel: with arguments: Nov 6 23:16:40.207717 kernel: /init Nov 6 23:16:40.207724 kernel: with environment: Nov 6 23:16:40.207732 kernel: HOME=/ Nov 6 23:16:40.207739 kernel: TERM=linux Nov 6 23:16:40.207747 systemd[1]: Successfully made /usr/ read-only. Nov 6 23:16:40.207757 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 23:16:40.207765 systemd[1]: Detected virtualization microsoft. Nov 6 23:16:40.207772 systemd[1]: Detected architecture arm64. Nov 6 23:16:40.207780 systemd[1]: Running in initrd. Nov 6 23:16:40.207787 systemd[1]: No hostname configured, using default hostname. Nov 6 23:16:40.207797 systemd[1]: Hostname set to . Nov 6 23:16:40.207804 systemd[1]: Initializing machine ID from random generator. Nov 6 23:16:40.207812 systemd[1]: Queued start job for default target initrd.target. Nov 6 23:16:40.207820 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:16:40.207827 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:16:40.207836 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 6 23:16:40.207843 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 23:16:40.207851 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 6 23:16:40.207861 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 6 23:16:40.207870 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 6 23:16:40.207878 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 6 23:16:40.207886 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:16:40.207894 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:16:40.207901 systemd[1]: Reached target paths.target - Path Units. Nov 6 23:16:40.207910 systemd[1]: Reached target slices.target - Slice Units. Nov 6 23:16:40.207918 systemd[1]: Reached target swap.target - Swaps. Nov 6 23:16:40.207926 systemd[1]: Reached target timers.target - Timer Units. Nov 6 23:16:40.207933 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 23:16:40.207941 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 23:16:40.207949 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 6 23:16:40.207957 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 6 23:16:40.207964 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:16:40.207972 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 23:16:40.207982 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:16:40.207989 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 23:16:40.207997 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 6 23:16:40.208005 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 23:16:40.208012 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 6 23:16:40.208020 systemd[1]: Starting systemd-fsck-usr.service... Nov 6 23:16:40.208028 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 23:16:40.208036 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 23:16:40.208059 systemd-journald[218]: Collecting audit messages is disabled. Nov 6 23:16:40.208080 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:16:40.208088 systemd-journald[218]: Journal started Nov 6 23:16:40.208107 systemd-journald[218]: Runtime Journal (/run/log/journal/d5b8ae9c68874acc83533b43174bdbd8) is 8M, max 78.5M, 70.5M free. Nov 6 23:16:40.215322 systemd-modules-load[220]: Inserted module 'overlay' Nov 6 23:16:40.227863 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 23:16:40.231852 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 6 23:16:40.260372 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 6 23:16:40.260398 kernel: Bridge firewalling registered Nov 6 23:16:40.245614 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:16:40.263062 systemd-modules-load[220]: Inserted module 'br_netfilter' Nov 6 23:16:40.264573 systemd[1]: Finished systemd-fsck-usr.service. Nov 6 23:16:40.272747 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 23:16:40.282824 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:16:40.303556 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:16:40.316504 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 23:16:40.330426 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 23:16:40.354572 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 23:16:40.367183 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:16:40.381211 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:16:40.386583 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 23:16:40.397636 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:16:40.418476 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 6 23:16:40.426462 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 23:16:40.446348 dracut-cmdline[253]: dracut-dracut-053 Nov 6 23:16:40.467635 dracut-cmdline[253]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=463065366e5b9a391e66d180eedbf8fe1b0462c2e722921ef25580943d9b67c6 Nov 6 23:16:40.447392 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 23:16:40.503282 systemd-resolved[256]: Positive Trust Anchors: Nov 6 23:16:40.503294 systemd-resolved[256]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 23:16:40.503325 systemd-resolved[256]: 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 Nov 6 23:16:40.507465 systemd-resolved[256]: Defaulting to hostname 'linux'. Nov 6 23:16:40.508343 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 23:16:40.517634 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:16:40.587335 kernel: SCSI subsystem initialized Nov 6 23:16:40.563169 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:16:40.596248 kernel: Loading iSCSI transport class v2.0-870. Nov 6 23:16:40.606248 kernel: iscsi: registered transport (tcp) Nov 6 23:16:40.624295 kernel: iscsi: registered transport (qla4xxx) Nov 6 23:16:40.624375 kernel: QLogic iSCSI HBA Driver Nov 6 23:16:40.655967 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 6 23:16:40.674429 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 6 23:16:40.703177 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 6 23:16:40.703214 kernel: device-mapper: uevent: version 1.0.3 Nov 6 23:16:40.708472 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 6 23:16:40.756259 kernel: raid6: neonx8 gen() 15801 MB/s Nov 6 23:16:40.776243 kernel: raid6: neonx4 gen() 15870 MB/s Nov 6 23:16:40.795244 kernel: raid6: neonx2 gen() 13246 MB/s Nov 6 23:16:40.816241 kernel: raid6: neonx1 gen() 10548 MB/s Nov 6 23:16:40.835241 kernel: raid6: int64x8 gen() 6804 MB/s Nov 6 23:16:40.855240 kernel: raid6: int64x4 gen() 7375 MB/s Nov 6 23:16:40.876245 kernel: raid6: int64x2 gen() 6128 MB/s Nov 6 23:16:40.898983 kernel: raid6: int64x1 gen() 5072 MB/s Nov 6 23:16:40.898994 kernel: raid6: using algorithm neonx4 gen() 15870 MB/s Nov 6 23:16:40.922119 kernel: raid6: .... xor() 12466 MB/s, rmw enabled Nov 6 23:16:40.922136 kernel: raid6: using neon recovery algorithm Nov 6 23:16:40.933660 kernel: xor: measuring software checksum speed Nov 6 23:16:40.933671 kernel: 8regs : 21613 MB/sec Nov 6 23:16:40.936662 kernel: 32regs : 21681 MB/sec Nov 6 23:16:40.939745 kernel: arm64_neon : 28013 MB/sec Nov 6 23:16:40.943438 kernel: xor: using function: arm64_neon (28013 MB/sec) Nov 6 23:16:40.993257 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 6 23:16:41.003131 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 6 23:16:41.016364 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:16:41.038794 systemd-udevd[441]: Using default interface naming scheme 'v255'. Nov 6 23:16:41.043377 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:16:41.059437 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 6 23:16:41.075514 dracut-pre-trigger[452]: rd.md=0: removing MD RAID activation Nov 6 23:16:41.101361 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 23:16:41.114499 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 23:16:41.154953 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:16:41.174487 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 6 23:16:41.200286 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 6 23:16:41.213222 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 23:16:41.226671 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:16:41.239396 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 23:16:41.256448 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 6 23:16:41.269190 kernel: hv_vmbus: Vmbus version:5.3 Nov 6 23:16:41.276100 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 23:16:41.280733 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:16:41.292387 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:16:41.306740 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:16:41.306968 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:16:41.351534 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 6 23:16:41.351558 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 6 23:16:41.351568 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 6 23:16:41.351578 kernel: hv_vmbus: registering driver hv_netvsc Nov 6 23:16:41.351587 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Nov 6 23:16:41.351596 kernel: PTP clock support registered Nov 6 23:16:41.340806 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:16:41.368557 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:16:41.382860 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:16:41.414761 kernel: hv_utils: Registering HyperV Utility Driver Nov 6 23:16:41.414782 kernel: hv_vmbus: registering driver hid_hyperv Nov 6 23:16:41.414792 kernel: hv_vmbus: registering driver hv_utils Nov 6 23:16:41.383219 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 6 23:16:41.670550 kernel: hv_utils: Heartbeat IC version 3.0 Nov 6 23:16:41.670573 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Nov 6 23:16:41.670583 kernel: hv_utils: Shutdown IC version 3.2 Nov 6 23:16:41.670592 kernel: hv_vmbus: registering driver hv_storvsc Nov 6 23:16:41.670601 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 6 23:16:41.670744 kernel: hv_utils: TimeSync IC version 4.0 Nov 6 23:16:41.670754 kernel: scsi host0: storvsc_host_t Nov 6 23:16:41.670852 kernel: scsi host1: storvsc_host_t Nov 6 23:16:41.670955 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 6 23:16:41.670976 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Nov 6 23:16:41.670990 kernel: hv_netvsc 000d3af7-5456-000d-3af7-5456000d3af7 eth0: VF slot 1 added Nov 6 23:16:41.399605 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:16:41.399693 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:16:41.651328 systemd-resolved[256]: Clock change detected. Flushing caches. Nov 6 23:16:41.681082 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:16:41.697673 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:16:41.722680 kernel: hv_vmbus: registering driver hv_pci Nov 6 23:16:41.723257 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:16:41.755000 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 6 23:16:41.755174 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 6 23:16:41.755185 kernel: hv_pci 5375a635-caff-41c2-a786-ea5ab83358f8: PCI VMBus probing: Using version 0x10004 Nov 6 23:16:41.755280 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 6 23:16:41.755366 kernel: hv_pci 5375a635-caff-41c2-a786-ea5ab83358f8: PCI host bridge to bus caff:00 Nov 6 23:16:41.765406 kernel: pci_bus caff:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Nov 6 23:16:41.774382 kernel: pci_bus caff:00: No busn resource found for root bus, will use [bus 00-ff] Nov 6 23:16:41.777054 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 6 23:16:41.783790 kernel: pci caff:00:02.0: [15b3:1018] type 00 class 0x020000 Nov 6 23:16:41.783867 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 6 23:16:41.791685 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:16:41.817405 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 6 23:16:41.817689 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 6 23:16:41.817839 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 6 23:16:41.817943 kernel: pci caff:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Nov 6 23:16:41.817966 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:16:41.817980 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#214 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 6 23:16:41.828719 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 6 23:16:41.828895 kernel: pci caff:00:02.0: enabling Extended Tags Nov 6 23:16:41.848021 kernel: pci caff:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at caff:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Nov 6 23:16:41.858125 kernel: pci_bus caff:00: busn_res: [bus 00-ff] end is updated to 00 Nov 6 23:16:41.858339 kernel: pci caff:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Nov 6 23:16:41.875951 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#222 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 6 23:16:41.902648 kernel: mlx5_core caff:00:02.0: enabling device (0000 -> 0002) Nov 6 23:16:41.915934 kernel: mlx5_core caff:00:02.0: firmware version: 16.30.5006 Nov 6 23:16:42.115969 kernel: hv_netvsc 000d3af7-5456-000d-3af7-5456000d3af7 eth0: VF registering: eth1 Nov 6 23:16:42.116166 kernel: mlx5_core caff:00:02.0 eth1: joined to eth0 Nov 6 23:16:42.121719 kernel: mlx5_core caff:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Nov 6 23:16:42.131943 kernel: mlx5_core caff:00:02.0 enP51967s1: renamed from eth1 Nov 6 23:16:42.431541 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 6 23:16:42.449026 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (506) Nov 6 23:16:42.458271 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 23:16:42.475948 kernel: BTRFS: device fsid 8ac35527-52fd-4925-acbb-f12804e07c02 devid 1 transid 36 /dev/sda3 scanned by (udev-worker) (486) Nov 6 23:16:42.487691 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 6 23:16:42.500409 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 6 23:16:42.505944 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Nov 6 23:16:42.536060 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 6 23:16:42.557970 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:16:42.567945 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:16:43.577393 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:16:43.577449 disk-uuid[604]: The operation has completed successfully. Nov 6 23:16:43.642554 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 6 23:16:43.644727 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 6 23:16:43.695056 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 6 23:16:43.705461 sh[690]: Success Nov 6 23:16:43.731054 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 6 23:16:44.106779 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 6 23:16:44.111950 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 6 23:16:44.124051 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 6 23:16:44.151863 kernel: BTRFS info (device dm-0): first mount of filesystem 8ac35527-52fd-4925-acbb-f12804e07c02 Nov 6 23:16:44.151911 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:16:44.157549 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 6 23:16:44.161668 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 6 23:16:44.165320 kernel: BTRFS info (device dm-0): using free space tree Nov 6 23:16:44.564456 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 6 23:16:44.568900 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 6 23:16:44.585125 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 6 23:16:44.597096 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 6 23:16:44.627933 kernel: BTRFS info (device sda6): first mount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:16:44.627987 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:16:44.631356 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:16:44.676946 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:16:44.686951 kernel: BTRFS info (device sda6): last unmount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:16:44.690389 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 6 23:16:44.706138 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 6 23:16:44.719976 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 23:16:44.734075 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 23:16:44.767835 systemd-networkd[871]: lo: Link UP Nov 6 23:16:44.767846 systemd-networkd[871]: lo: Gained carrier Nov 6 23:16:44.769407 systemd-networkd[871]: Enumeration completed Nov 6 23:16:44.771281 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 23:16:44.779498 systemd-networkd[871]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:16:44.779501 systemd-networkd[871]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:16:44.780182 systemd[1]: Reached target network.target - Network. Nov 6 23:16:44.854942 kernel: mlx5_core caff:00:02.0 enP51967s1: Link up Nov 6 23:16:44.892948 kernel: hv_netvsc 000d3af7-5456-000d-3af7-5456000d3af7 eth0: Data path switched to VF: enP51967s1 Nov 6 23:16:44.893499 systemd-networkd[871]: enP51967s1: Link UP Nov 6 23:16:44.893731 systemd-networkd[871]: eth0: Link UP Nov 6 23:16:44.894095 systemd-networkd[871]: eth0: Gained carrier Nov 6 23:16:44.894104 systemd-networkd[871]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:16:44.914518 systemd-networkd[871]: enP51967s1: Gained carrier Nov 6 23:16:44.928961 systemd-networkd[871]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 6 23:16:45.484723 ignition[862]: Ignition 2.20.0 Nov 6 23:16:45.484734 ignition[862]: Stage: fetch-offline Nov 6 23:16:45.489297 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 23:16:45.484769 ignition[862]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:16:45.484777 ignition[862]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:16:45.484883 ignition[862]: parsed url from cmdline: "" Nov 6 23:16:45.484886 ignition[862]: no config URL provided Nov 6 23:16:45.484891 ignition[862]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 23:16:45.512179 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 6 23:16:45.484898 ignition[862]: no config at "/usr/lib/ignition/user.ign" Nov 6 23:16:45.484902 ignition[862]: failed to fetch config: resource requires networking Nov 6 23:16:45.485230 ignition[862]: Ignition finished successfully Nov 6 23:16:45.540740 ignition[880]: Ignition 2.20.0 Nov 6 23:16:45.540746 ignition[880]: Stage: fetch Nov 6 23:16:45.540940 ignition[880]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:16:45.540951 ignition[880]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:16:45.541046 ignition[880]: parsed url from cmdline: "" Nov 6 23:16:45.541049 ignition[880]: no config URL provided Nov 6 23:16:45.541054 ignition[880]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 23:16:45.541061 ignition[880]: no config at "/usr/lib/ignition/user.ign" Nov 6 23:16:45.541086 ignition[880]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 6 23:16:45.639307 ignition[880]: GET result: OK Nov 6 23:16:45.639410 ignition[880]: config has been read from IMDS userdata Nov 6 23:16:45.639451 ignition[880]: parsing config with SHA512: aa66cb1aa9bf37c8a82f2232d6ca65c516ac24c7135285ba01fc485788e088d9055dea98e4c7bb935f2f347f91362e781e3a39c467fdacd1bc61cbba5ee57d29 Nov 6 23:16:45.642861 unknown[880]: fetched base config from "system" Nov 6 23:16:45.643176 ignition[880]: fetch: fetch complete Nov 6 23:16:45.642869 unknown[880]: fetched base config from "system" Nov 6 23:16:45.643181 ignition[880]: fetch: fetch passed Nov 6 23:16:45.642874 unknown[880]: fetched user config from "azure" Nov 6 23:16:45.643234 ignition[880]: Ignition finished successfully Nov 6 23:16:45.645130 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 6 23:16:45.664135 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 6 23:16:45.682226 ignition[887]: Ignition 2.20.0 Nov 6 23:16:45.682233 ignition[887]: Stage: kargs Nov 6 23:16:45.689669 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 6 23:16:45.685806 ignition[887]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:16:45.704046 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 6 23:16:45.685818 ignition[887]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:16:45.686502 ignition[887]: kargs: kargs passed Nov 6 23:16:45.728717 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 6 23:16:45.686544 ignition[887]: Ignition finished successfully Nov 6 23:16:45.738899 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 6 23:16:45.719103 ignition[893]: Ignition 2.20.0 Nov 6 23:16:45.747106 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 6 23:16:45.719110 ignition[893]: Stage: disks Nov 6 23:16:45.753209 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 23:16:45.719282 ignition[893]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:16:45.763167 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 23:16:45.719291 ignition[893]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:16:45.771601 systemd[1]: Reached target basic.target - Basic System. Nov 6 23:16:45.719960 ignition[893]: disks: disks passed Nov 6 23:16:45.792117 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 6 23:16:45.720001 ignition[893]: Ignition finished successfully Nov 6 23:16:45.881634 systemd-fsck[903]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Nov 6 23:16:45.890128 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 6 23:16:45.905145 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 6 23:16:45.958950 kernel: EXT4-fs (sda9): mounted filesystem 93ef6c07-4a07-4e6a-86ce-df7a94c95ac7 r/w with ordered data mode. Quota mode: none. Nov 6 23:16:45.960488 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 6 23:16:45.964668 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 6 23:16:46.005004 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 23:16:46.029042 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (914) Nov 6 23:16:46.025194 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 6 23:16:46.041350 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 6 23:16:46.057419 kernel: BTRFS info (device sda6): first mount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:16:46.057442 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:16:46.057452 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:16:46.063783 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 6 23:16:46.063829 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 23:16:46.085632 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 6 23:16:46.104938 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:16:46.105110 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 6 23:16:46.111615 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 23:16:46.641346 coreos-metadata[916]: Nov 06 23:16:46.641 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 23:16:46.648024 coreos-metadata[916]: Nov 06 23:16:46.647 INFO Fetch successful Nov 6 23:16:46.648024 coreos-metadata[916]: Nov 06 23:16:46.648 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 6 23:16:46.660960 coreos-metadata[916]: Nov 06 23:16:46.660 INFO Fetch successful Nov 6 23:16:46.672977 coreos-metadata[916]: Nov 06 23:16:46.672 INFO wrote hostname ci-4230.2.4-n-5fb6643642 to /sysroot/etc/hostname Nov 6 23:16:46.680985 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 23:16:46.885072 systemd-networkd[871]: eth0: Gained IPv6LL Nov 6 23:16:47.049620 initrd-setup-root[946]: cut: /sysroot/etc/passwd: No such file or directory Nov 6 23:16:47.093899 initrd-setup-root[953]: cut: /sysroot/etc/group: No such file or directory Nov 6 23:16:47.127482 initrd-setup-root[960]: cut: /sysroot/etc/shadow: No such file or directory Nov 6 23:16:47.133644 initrd-setup-root[967]: cut: /sysroot/etc/gshadow: No such file or directory Nov 6 23:16:48.210423 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 6 23:16:48.225106 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 6 23:16:48.233102 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 6 23:16:48.250298 kernel: BTRFS info (device sda6): last unmount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:16:48.249812 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 6 23:16:48.274881 ignition[1034]: INFO : Ignition 2.20.0 Nov 6 23:16:48.274881 ignition[1034]: INFO : Stage: mount Nov 6 23:16:48.281991 ignition[1034]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:16:48.281991 ignition[1034]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:16:48.281991 ignition[1034]: INFO : mount: mount passed Nov 6 23:16:48.281991 ignition[1034]: INFO : Ignition finished successfully Nov 6 23:16:48.282046 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 6 23:16:48.313340 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 6 23:16:48.322988 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 6 23:16:48.341511 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 23:16:48.362941 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1046) Nov 6 23:16:48.375664 kernel: BTRFS info (device sda6): first mount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:16:48.375710 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:16:48.379315 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:16:48.387954 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:16:48.389169 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 23:16:48.420788 ignition[1064]: INFO : Ignition 2.20.0 Nov 6 23:16:48.420788 ignition[1064]: INFO : Stage: files Nov 6 23:16:48.427620 ignition[1064]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:16:48.427620 ignition[1064]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:16:48.427620 ignition[1064]: DEBUG : files: compiled without relabeling support, skipping Nov 6 23:16:48.448255 ignition[1064]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 6 23:16:48.448255 ignition[1064]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 6 23:16:48.543249 ignition[1064]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 6 23:16:48.549585 ignition[1064]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 6 23:16:48.549585 ignition[1064]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 6 23:16:48.543631 unknown[1064]: wrote ssh authorized keys file for user: core Nov 6 23:16:48.586216 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 6 23:16:48.594533 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 6 23:16:48.602206 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 23:16:48.610457 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 23:16:48.610457 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 6 23:16:48.610457 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 6 23:16:48.610457 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 6 23:16:48.610457 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.34.1-arm64.raw: attempt #1 Nov 6 23:16:49.157699 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 6 23:16:49.431758 ignition[1064]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 6 23:16:49.431758 ignition[1064]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 6 23:16:49.431758 ignition[1064]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 6 23:16:49.431758 ignition[1064]: INFO : files: files passed Nov 6 23:16:49.431758 ignition[1064]: INFO : Ignition finished successfully Nov 6 23:16:49.435560 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 6 23:16:49.466163 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 6 23:16:49.477127 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 6 23:16:49.487153 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 6 23:16:49.487236 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 6 23:16:49.514904 initrd-setup-root-after-ignition[1092]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:16:49.514904 initrd-setup-root-after-ignition[1092]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:16:49.535863 initrd-setup-root-after-ignition[1096]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:16:49.516207 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 23:16:49.528697 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 6 23:16:49.560371 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 6 23:16:49.585600 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 6 23:16:49.585698 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 6 23:16:49.596880 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 6 23:16:49.607468 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 6 23:16:49.617097 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 6 23:16:49.633409 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 6 23:16:49.652546 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 23:16:49.666125 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 6 23:16:49.685681 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 6 23:16:49.685792 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 6 23:16:49.696196 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:16:49.706432 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:16:49.717086 systemd[1]: Stopped target timers.target - Timer Units. Nov 6 23:16:49.726344 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 6 23:16:49.726409 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 23:16:49.739547 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 6 23:16:49.749236 systemd[1]: Stopped target basic.target - Basic System. Nov 6 23:16:49.757541 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 6 23:16:49.766652 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 23:16:49.776619 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 6 23:16:49.786737 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 6 23:16:49.795763 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 23:16:49.805431 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 6 23:16:49.815164 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 6 23:16:49.823957 systemd[1]: Stopped target swap.target - Swaps. Nov 6 23:16:49.831671 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 6 23:16:49.831740 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 6 23:16:49.843697 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:16:49.852840 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:16:49.862575 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 6 23:16:49.867602 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:16:49.873126 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 6 23:16:49.873184 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 6 23:16:49.887715 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 6 23:16:49.887759 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 23:16:49.897368 systemd[1]: ignition-files.service: Deactivated successfully. Nov 6 23:16:49.897404 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 6 23:16:49.906212 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 6 23:16:49.906252 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 23:16:49.931047 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 6 23:16:49.956915 ignition[1117]: INFO : Ignition 2.20.0 Nov 6 23:16:49.956915 ignition[1117]: INFO : Stage: umount Nov 6 23:16:49.975006 ignition[1117]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:16:49.975006 ignition[1117]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:16:49.975006 ignition[1117]: INFO : umount: umount passed Nov 6 23:16:49.975006 ignition[1117]: INFO : Ignition finished successfully Nov 6 23:16:49.957118 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 6 23:16:49.966163 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 6 23:16:49.966245 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:16:49.971533 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 6 23:16:49.971576 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 23:16:49.985682 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 6 23:16:49.985789 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 6 23:16:49.998333 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 6 23:16:49.998423 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 6 23:16:50.006433 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 6 23:16:50.006479 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 6 23:16:50.014828 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 6 23:16:50.014867 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 6 23:16:50.025298 systemd[1]: Stopped target network.target - Network. Nov 6 23:16:50.033402 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 6 23:16:50.033463 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 23:16:50.044021 systemd[1]: Stopped target paths.target - Path Units. Nov 6 23:16:50.052224 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 6 23:16:50.061946 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:16:50.071787 systemd[1]: Stopped target slices.target - Slice Units. Nov 6 23:16:50.080771 systemd[1]: Stopped target sockets.target - Socket Units. Nov 6 23:16:50.085344 systemd[1]: iscsid.socket: Deactivated successfully. Nov 6 23:16:50.085394 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 23:16:50.094573 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 6 23:16:50.094616 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 23:16:50.103284 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 6 23:16:50.103333 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 6 23:16:50.112156 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 6 23:16:50.112196 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 6 23:16:50.121637 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 6 23:16:50.130105 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 6 23:16:50.140462 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 6 23:16:50.141117 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 6 23:16:50.141210 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 6 23:16:50.150425 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 6 23:16:50.150546 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 6 23:16:50.169806 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 6 23:16:50.170182 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 6 23:16:50.171950 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 6 23:16:50.185741 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 6 23:16:50.186869 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 6 23:16:50.186937 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:16:50.194466 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 6 23:16:50.361293 kernel: hv_netvsc 000d3af7-5456-000d-3af7-5456000d3af7 eth0: Data path switched from VF: enP51967s1 Nov 6 23:16:50.194544 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 6 23:16:50.220114 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 6 23:16:50.228417 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 6 23:16:50.228487 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 23:16:50.238462 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 6 23:16:50.238510 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:16:50.252992 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 6 23:16:50.253036 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 6 23:16:50.258447 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 6 23:16:50.258488 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:16:50.273034 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:16:50.283170 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 6 23:16:50.283241 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:16:50.328883 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 6 23:16:50.329065 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:16:50.339308 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 6 23:16:50.339361 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 6 23:16:50.356254 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 6 23:16:50.356289 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:16:50.366426 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 6 23:16:50.366488 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 6 23:16:50.380587 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 6 23:16:50.380636 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 6 23:16:50.390055 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 23:16:50.390105 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:16:50.419138 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 6 23:16:50.431992 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 6 23:16:50.432059 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:16:50.451634 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 6 23:16:50.451695 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 23:16:50.458204 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 6 23:16:50.458252 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:16:50.470014 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:16:50.470055 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:16:50.486835 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Nov 6 23:16:50.486886 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:16:50.487201 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 6 23:16:50.487297 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 6 23:16:50.497389 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 6 23:16:50.497470 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 6 23:16:50.508164 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 6 23:16:50.538146 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 6 23:16:50.570297 systemd[1]: Switching root. Nov 6 23:16:50.673564 systemd-journald[218]: Received SIGTERM from PID 1 (systemd). Nov 6 23:16:50.673601 systemd-journald[218]: Journal stopped Nov 6 23:16:57.672241 kernel: SELinux: policy capability network_peer_controls=1 Nov 6 23:16:57.672269 kernel: SELinux: policy capability open_perms=1 Nov 6 23:16:57.672280 kernel: SELinux: policy capability extended_socket_class=1 Nov 6 23:16:57.672288 kernel: SELinux: policy capability always_check_network=0 Nov 6 23:16:57.672298 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 6 23:16:57.672306 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 6 23:16:57.672315 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 6 23:16:57.672324 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 6 23:16:57.672333 systemd[1]: Successfully loaded SELinux policy in 170.580ms. Nov 6 23:16:57.672343 kernel: audit: type=1403 audit(1762471011.737:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 6 23:16:57.672354 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.197ms. Nov 6 23:16:57.672364 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 23:16:57.672373 systemd[1]: Detected virtualization microsoft. Nov 6 23:16:57.672382 systemd[1]: Detected architecture arm64. Nov 6 23:16:57.672392 systemd[1]: Detected first boot. Nov 6 23:16:57.672403 systemd[1]: Hostname set to . Nov 6 23:16:57.672412 systemd[1]: Initializing machine ID from random generator. Nov 6 23:16:57.672421 zram_generator::config[1159]: No configuration found. Nov 6 23:16:57.672431 kernel: NET: Registered PF_VSOCK protocol family Nov 6 23:16:57.672439 systemd[1]: Populated /etc with preset unit settings. Nov 6 23:16:57.672450 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 6 23:16:57.672459 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 6 23:16:57.672471 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 6 23:16:57.672480 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 6 23:16:57.672490 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 6 23:16:57.672500 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 6 23:16:57.672509 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 6 23:16:57.672519 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 6 23:16:57.672528 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 6 23:16:57.672539 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 6 23:16:57.672549 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 6 23:16:57.672558 systemd[1]: Created slice user.slice - User and Session Slice. Nov 6 23:16:57.672568 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:16:57.672577 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:16:57.672587 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 6 23:16:57.672597 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 6 23:16:57.672606 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 6 23:16:57.672617 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 23:16:57.672627 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 6 23:16:57.672636 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:16:57.672648 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 6 23:16:57.672658 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 6 23:16:57.672667 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 6 23:16:57.672678 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 6 23:16:57.672688 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:16:57.672699 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 23:16:57.672709 systemd[1]: Reached target slices.target - Slice Units. Nov 6 23:16:57.672719 systemd[1]: Reached target swap.target - Swaps. Nov 6 23:16:57.672728 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 6 23:16:57.672738 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 6 23:16:57.672748 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 6 23:16:57.672759 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:16:57.672769 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 23:16:57.672779 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:16:57.672789 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 6 23:16:57.672798 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 6 23:16:57.672808 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 6 23:16:57.672818 systemd[1]: Mounting media.mount - External Media Directory... Nov 6 23:16:57.672829 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 6 23:16:57.672839 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 6 23:16:57.672848 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 6 23:16:57.672859 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). Nov 6 23:16:57.672869 systemd[1]: Reached target machines.target - Containers. Nov 6 23:16:57.672878 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 6 23:16:57.672889 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:16:57.672899 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 23:16:57.672910 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 6 23:16:57.672928 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:16:57.672938 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 23:16:57.672948 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:16:57.672958 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 6 23:16:57.672968 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:16:57.672978 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 6 23:16:57.672987 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 6 23:16:57.672999 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 6 23:16:57.673009 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 6 23:16:57.673019 systemd[1]: Stopped systemd-fsck-usr.service. Nov 6 23:16:57.673029 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). Nov 6 23:16:57.673038 kernel: loop: module loaded Nov 6 23:16:57.673047 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 23:16:57.673057 kernel: fuse: init (API version 7.39) Nov 6 23:16:57.673066 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 23:16:57.673075 kernel: ACPI: bus type drm_connector registered Nov 6 23:16:57.673087 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 6 23:16:57.673096 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 6 23:16:57.673107 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 6 23:16:57.673117 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 23:16:57.673127 systemd[1]: verity-setup.service: Deactivated successfully. Nov 6 23:16:57.673155 systemd-journald[1257]: Collecting audit messages is disabled. Nov 6 23:16:57.673179 systemd[1]: Stopped verity-setup.service. Nov 6 23:16:57.673190 systemd-journald[1257]: Journal started Nov 6 23:16:57.673210 systemd-journald[1257]: Runtime Journal (/run/log/journal/6acd07e1cc324fcd9fc15627a81e5fc4) is 8M, max 78.5M, 70.5M free. Nov 6 23:16:56.741223 systemd[1]: Queued start job for default target multi-user.target. Nov 6 23:16:56.748747 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 6 23:16:56.749118 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 6 23:16:56.749446 systemd[1]: systemd-journald.service: Consumed 2.565s CPU time. Nov 6 23:16:57.690150 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 23:16:57.691120 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 6 23:16:57.698383 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 6 23:16:57.703500 systemd[1]: Mounted media.mount - External Media Directory. Nov 6 23:16:57.707825 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 6 23:16:57.712759 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 6 23:16:57.717879 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 6 23:16:57.722270 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 6 23:16:57.727727 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:16:57.735437 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 6 23:16:57.735600 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 6 23:16:57.741063 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:16:57.741224 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:16:57.746632 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 23:16:57.746772 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 23:16:57.751757 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:16:57.751927 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:16:57.757630 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 6 23:16:57.757783 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 6 23:16:57.762889 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:16:57.763071 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:16:57.768284 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 23:16:57.773736 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 23:16:57.779913 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 6 23:16:57.785845 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 6 23:16:57.791693 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:16:57.807222 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 6 23:16:57.816014 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 6 23:16:57.824059 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 6 23:16:57.829144 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 6 23:16:57.829180 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 23:16:57.834594 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 6 23:16:57.841271 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 6 23:16:57.847318 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 6 23:16:57.851877 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:16:57.873074 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 6 23:16:57.879088 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 6 23:16:57.884635 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 23:16:57.885838 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 6 23:16:57.891397 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 23:16:57.893124 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 23:16:57.901094 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 6 23:16:57.910061 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 23:16:57.918766 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 6 23:16:57.929882 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 6 23:16:57.936087 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 6 23:16:57.943492 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 6 23:16:57.950349 systemd-journald[1257]: Time spent on flushing to /var/log/journal/6acd07e1cc324fcd9fc15627a81e5fc4 is 25.247ms for 896 entries. Nov 6 23:16:57.950349 systemd-journald[1257]: System Journal (/var/log/journal/6acd07e1cc324fcd9fc15627a81e5fc4) is 8M, max 2.6G, 2.6G free. Nov 6 23:16:57.993711 systemd-journald[1257]: Received client request to flush runtime journal. Nov 6 23:16:57.993754 kernel: loop0: detected capacity change from 0 to 200800 Nov 6 23:16:57.955718 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 6 23:16:57.967006 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 6 23:16:57.981318 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 6 23:16:57.991305 udevadm[1303]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 6 23:16:57.995616 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 6 23:16:58.044884 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 6 23:16:58.046878 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 6 23:16:58.055264 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 6 23:16:58.078576 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:16:58.112380 kernel: loop1: detected capacity change from 0 to 113512 Nov 6 23:16:58.166671 systemd-tmpfiles[1302]: ACLs are not supported, ignoring. Nov 6 23:16:58.166686 systemd-tmpfiles[1302]: ACLs are not supported, ignoring. Nov 6 23:16:58.171111 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 23:16:58.184073 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 6 23:16:58.557945 kernel: loop2: detected capacity change from 0 to 28720 Nov 6 23:16:58.800342 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 6 23:16:58.812094 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 23:16:58.826876 systemd-tmpfiles[1323]: ACLs are not supported, ignoring. Nov 6 23:16:58.827221 systemd-tmpfiles[1323]: ACLs are not supported, ignoring. Nov 6 23:16:58.832820 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:16:58.873503 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 6 23:16:58.884136 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:16:58.909023 systemd-udevd[1327]: Using default interface naming scheme 'v255'. Nov 6 23:16:59.093948 kernel: loop3: detected capacity change from 0 to 123192 Nov 6 23:16:59.521704 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:16:59.538319 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 23:16:59.568964 kernel: loop4: detected capacity change from 0 to 200800 Nov 6 23:16:59.591154 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 6 23:16:59.599940 kernel: loop5: detected capacity change from 0 to 113512 Nov 6 23:16:59.610072 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 6 23:16:59.626949 kernel: loop6: detected capacity change from 0 to 28720 Nov 6 23:16:59.643945 kernel: loop7: detected capacity change from 0 to 123192 Nov 6 23:16:59.652015 (sd-merge)[1351]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-azure'. Nov 6 23:16:59.652985 (sd-merge)[1351]: Merged extensions into '/usr'. Nov 6 23:16:59.659616 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 6 23:16:59.666468 systemd[1]: Reload requested from client PID 1300 ('systemd-sysext') (unit systemd-sysext.service)... Nov 6 23:16:59.666483 systemd[1]: Reloading... Nov 6 23:16:59.759948 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#245 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 6 23:16:59.779978 kernel: hv_vmbus: registering driver hv_balloon Nov 6 23:16:59.797942 zram_generator::config[1403]: No configuration found. Nov 6 23:16:59.798043 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 6 23:16:59.798067 kernel: hv_balloon: Memory hot add disabled on ARM64 Nov 6 23:16:59.807100 kernel: hv_vmbus: registering driver hyperv_fb Nov 6 23:16:59.817155 kernel: mousedev: PS/2 mouse device common for all mice Nov 6 23:16:59.829069 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 6 23:16:59.829154 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 6 23:16:59.839470 kernel: Console: switching to colour dummy device 80x25 Nov 6 23:16:59.851777 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 23:16:59.925985 systemd-networkd[1341]: lo: Link UP Nov 6 23:16:59.925993 systemd-networkd[1341]: lo: Gained carrier Nov 6 23:16:59.935461 systemd-networkd[1341]: Enumeration completed Nov 6 23:16:59.935852 systemd-networkd[1341]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:16:59.935855 systemd-networkd[1341]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:16:59.952033 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1333) Nov 6 23:16:59.986063 kernel: mlx5_core caff:00:02.0 enP51967s1: Link up Nov 6 23:17:00.013590 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:00.018944 kernel: hv_netvsc 000d3af7-5456-000d-3af7-5456000d3af7 eth0: Data path switched to VF: enP51967s1 Nov 6 23:17:00.021233 systemd-networkd[1341]: enP51967s1: Link UP Nov 6 23:17:00.021324 systemd-networkd[1341]: eth0: Link UP Nov 6 23:17:00.021327 systemd-networkd[1341]: eth0: Gained carrier Nov 6 23:17:00.021341 systemd-networkd[1341]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:17:00.029155 systemd-networkd[1341]: enP51967s1: Gained carrier Nov 6 23:17:00.040963 systemd-networkd[1341]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 6 23:17:00.113071 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 23:17:00.119291 systemd[1]: Reloading finished in 452 ms. Nov 6 23:17:00.138594 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 23:17:00.145974 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 6 23:17:00.194088 systemd[1]: Starting ensure-sysext.service... Nov 6 23:17:00.202025 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 6 23:17:00.211669 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 6 23:17:00.218646 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 6 23:17:00.225626 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 23:17:00.232114 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:17:00.249988 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 6 23:17:00.271254 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 6 23:17:00.276918 systemd[1]: Reload requested from client PID 1521 ('systemctl') (unit ensure-sysext.service)... Nov 6 23:17:00.276962 systemd[1]: Reloading... Nov 6 23:17:00.296410 systemd-tmpfiles[1525]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 6 23:17:00.297819 systemd-tmpfiles[1525]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 6 23:17:00.299067 systemd-tmpfiles[1525]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 6 23:17:00.299287 systemd-tmpfiles[1525]: ACLs are not supported, ignoring. Nov 6 23:17:00.299335 systemd-tmpfiles[1525]: ACLs are not supported, ignoring. Nov 6 23:17:00.329601 systemd-tmpfiles[1525]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 23:17:00.329611 systemd-tmpfiles[1525]: Skipping /boot Nov 6 23:17:00.341223 systemd-tmpfiles[1525]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 23:17:00.341238 systemd-tmpfiles[1525]: Skipping /boot Nov 6 23:17:00.355955 zram_generator::config[1565]: No configuration found. Nov 6 23:17:00.389636 lvm[1530]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 6 23:17:00.463637 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:00.559695 systemd[1]: Reloading finished in 282 ms. Nov 6 23:17:00.586965 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 6 23:17:00.593570 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 6 23:17:00.600214 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:17:00.606896 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 6 23:17:00.618667 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:17:00.633202 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 6 23:17:00.681480 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 6 23:17:00.688147 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 6 23:17:00.697551 lvm[1634]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 6 23:17:00.699189 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 6 23:17:00.709570 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 23:17:00.718864 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 6 23:17:00.729612 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 6 23:17:00.738890 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:17:00.747170 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:17:00.759255 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:17:00.775539 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:17:00.781047 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:17:00.781176 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). Nov 6 23:17:00.784457 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:17:00.784633 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:17:00.792476 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:17:00.792640 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:17:00.799531 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:17:00.799716 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:17:00.811110 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:17:00.820251 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:17:00.826327 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:17:00.835614 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:17:00.840504 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:17:00.840768 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). Nov 6 23:17:00.842719 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 6 23:17:00.850069 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:17:00.850329 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:17:00.856151 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:17:00.856417 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:17:00.863118 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:17:00.863382 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:17:00.877276 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:17:00.886499 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:17:00.889685 systemd-resolved[1636]: Positive Trust Anchors: Nov 6 23:17:00.890082 systemd-resolved[1636]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 23:17:00.890188 systemd-resolved[1636]: 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 Nov 6 23:17:00.893167 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 23:17:00.902233 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:17:00.909280 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:17:00.914823 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:17:00.914980 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). Nov 6 23:17:00.915120 systemd[1]: Reached target time-set.target - System Time Set. Nov 6 23:17:00.918320 augenrules[1670]: No rules Nov 6 23:17:00.921313 systemd[1]: audit-rules.service: Deactivated successfully. Nov 6 23:17:00.921527 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 6 23:17:00.926883 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:17:00.927967 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:17:00.935526 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 23:17:00.935695 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 23:17:00.941495 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:17:00.941759 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:17:00.948168 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:17:00.949161 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:17:00.950046 systemd-resolved[1636]: Using system hostname 'ci-4230.2.4-n-5fb6643642'. Nov 6 23:17:00.955041 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 23:17:00.964849 systemd[1]: Finished ensure-sysext.service. Nov 6 23:17:00.972829 systemd[1]: Reached target network.target - Network. Nov 6 23:17:00.977293 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:17:00.983189 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 23:17:00.983334 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 23:17:01.016443 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 6 23:17:01.093102 systemd-networkd[1341]: eth0: Gained IPv6LL Nov 6 23:17:01.095343 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 6 23:17:01.101499 systemd[1]: Reached target network-online.target - Network is Online. Nov 6 23:17:01.513076 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:17:03.162336 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 6 23:17:03.168729 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). Nov 6 23:17:06.343929 ldconfig[1295]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 6 23:17:06.358729 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 6 23:17:06.368146 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 6 23:17:06.393897 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 6 23:17:06.399704 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 23:17:06.405148 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 6 23:17:06.410634 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 6 23:17:06.416575 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 6 23:17:06.421341 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 6 23:17:06.426887 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 6 23:17:06.432655 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 6 23:17:06.432685 systemd[1]: Reached target paths.target - Path Units. Nov 6 23:17:06.436946 systemd[1]: Reached target timers.target - Timer Units. Nov 6 23:17:06.457411 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 6 23:17:06.463864 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 6 23:17:06.482584 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 6 23:17:06.488663 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 6 23:17:06.495063 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 6 23:17:06.510568 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 6 23:17:06.515584 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 6 23:17:06.521448 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 6 23:17:06.526626 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 23:17:06.531019 systemd[1]: Reached target basic.target - Basic System. Nov 6 23:17:06.535113 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 6 23:17:06.535139 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 6 23:17:06.555001 systemd[1]: Starting chronyd.service - NTP client/server... Nov 6 23:17:06.561727 systemd[1]: Starting containerd.service - containerd container runtime... Nov 6 23:17:06.579103 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 6 23:17:06.588156 (chronyd)[1692]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Nov 6 23:17:06.589105 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 6 23:17:06.594663 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 6 23:17:06.600414 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 6 23:17:06.605221 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). Nov 6 23:17:06.605366 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Nov 6 23:17:06.606631 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 6 23:17:06.611639 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 6 23:17:06.613289 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:17:06.615905 jq[1699]: false Nov 6 23:17:06.621472 KVP[1701]: KVP starting; pid is:1701 Nov 6 23:17:06.624039 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 6 23:17:06.633343 KVP[1701]: KVP LIC Version: 3.1 Nov 6 23:17:06.633942 kernel: hv_utils: KVP IC version 4.0 Nov 6 23:17:06.636816 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 6 23:17:06.642257 chronyd[1706]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Nov 6 23:17:06.643850 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 6 23:17:06.651117 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 6 23:17:06.661971 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 6 23:17:06.672690 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 6 23:17:06.673396 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 6 23:17:06.678188 systemd[1]: Starting update-engine.service - Update Engine... Nov 6 23:17:06.688016 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 6 23:17:06.693851 chronyd[1706]: Timezone right/UTC failed leap second check, ignoring Nov 6 23:17:06.694205 chronyd[1706]: Loaded seccomp filter (level 2) Nov 6 23:17:06.703450 systemd[1]: Started chronyd.service - NTP client/server. Nov 6 23:17:06.713467 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 6 23:17:06.713694 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 6 23:17:06.715811 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 6 23:17:06.719233 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 6 23:17:06.724153 jq[1716]: true Nov 6 23:17:06.727714 systemd[1]: motdgen.service: Deactivated successfully. Nov 6 23:17:06.730204 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 6 23:17:06.760601 (ntainerd)[1727]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 6 23:17:06.766047 jq[1726]: true Nov 6 23:17:06.768618 extend-filesystems[1700]: Found loop4 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found loop5 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found loop6 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found loop7 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda1 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda2 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda3 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found usr Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda4 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda6 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda7 Nov 6 23:17:06.768618 extend-filesystems[1700]: Found sda9 Nov 6 23:17:06.768618 extend-filesystems[1700]: Checking size of /dev/sda9 Nov 6 23:17:06.777363 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 6 23:17:06.857486 update_engine[1715]: I20251106 23:17:06.778992 1715 main.cc:92] Flatcar Update Engine starting Nov 6 23:17:06.790882 systemd-logind[1712]: New seat seat0. Nov 6 23:17:06.797460 systemd-logind[1712]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 6 23:17:06.797667 systemd[1]: Started systemd-logind.service - User Login Management. Nov 6 23:17:06.876521 extend-filesystems[1700]: Old size kept for /dev/sda9 Nov 6 23:17:06.876521 extend-filesystems[1700]: Found sr0 Nov 6 23:17:06.871162 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 6 23:17:06.871408 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 6 23:17:06.922626 bash[1757]: Updated "/home/core/.ssh/authorized_keys" Nov 6 23:17:06.922167 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 6 23:17:06.921499 dbus-daemon[1698]: [system] SELinux support is enabled Nov 6 23:17:06.932651 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 6 23:17:06.964717 update_engine[1715]: I20251106 23:17:06.939821 1715 update_check_scheduler.cc:74] Next update check in 3m5s Nov 6 23:17:06.947064 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 6 23:17:06.946436 dbus-daemon[1698]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 6 23:17:06.947196 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). Nov 6 23:17:06.947222 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 6 23:17:06.958084 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). Nov 6 23:17:06.958106 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 6 23:17:06.977001 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1764) Nov 6 23:17:06.970757 systemd[1]: Started update-engine.service - Update Engine. Nov 6 23:17:06.992428 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 6 23:17:07.002857 coreos-metadata[1694]: Nov 06 23:17:07.002 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 23:17:07.011661 coreos-metadata[1694]: Nov 06 23:17:07.010 INFO Fetch successful Nov 6 23:17:07.011661 coreos-metadata[1694]: Nov 06 23:17:07.011 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 6 23:17:07.016793 coreos-metadata[1694]: Nov 06 23:17:07.016 INFO Fetch successful Nov 6 23:17:07.016793 coreos-metadata[1694]: Nov 06 23:17:07.016 INFO Fetching http://168.63.129.16/machine/625c89d7-2074-4e1b-bbd2-be6fafa69b22/1ed9737e%2D2940%2D4797%2Daf78%2De72b2b0c1e2b.%5Fci%2D4230.2.4%2Dn%2D5fb6643642?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 6 23:17:07.019400 coreos-metadata[1694]: Nov 06 23:17:07.019 INFO Fetch successful Nov 6 23:17:07.019400 coreos-metadata[1694]: Nov 06 23:17:07.019 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 6 23:17:07.032809 coreos-metadata[1694]: Nov 06 23:17:07.032 INFO Fetch successful Nov 6 23:17:07.086102 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 6 23:17:07.104328 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 6 23:17:07.160054 locksmithd[1791]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 6 23:17:07.343039 containerd[1727]: time="2025-11-06T23:17:07.342944720Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 6 23:17:07.408829 containerd[1727]: time="2025-11-06T23:17:07.408595600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:07.412250 containerd[1727]: time="2025-11-06T23:17:07.412160640Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.413946320Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.413978320Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414146200Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414163160Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414234000Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414245680Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414455720Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414469440Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414482280Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414491880Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414577640Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:07.414955 containerd[1727]: time="2025-11-06T23:17:07.414760920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:07.415229 containerd[1727]: time="2025-11-06T23:17:07.414873200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:07.415229 containerd[1727]: time="2025-11-06T23:17:07.414885480Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 6 23:17:07.415375 containerd[1727]: time="2025-11-06T23:17:07.415355440Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 6 23:17:07.415484 containerd[1727]: time="2025-11-06T23:17:07.415469920Z" level=info msg="metadata content store policy set" policy=shared Nov 6 23:17:07.434842 containerd[1727]: time="2025-11-06T23:17:07.434752920Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 6 23:17:07.434942 containerd[1727]: time="2025-11-06T23:17:07.434844600Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 6 23:17:07.434942 containerd[1727]: time="2025-11-06T23:17:07.434884000Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 6 23:17:07.434942 containerd[1727]: time="2025-11-06T23:17:07.434906440Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 6 23:17:07.434942 containerd[1727]: time="2025-11-06T23:17:07.434932120Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 6 23:17:07.435124 containerd[1727]: time="2025-11-06T23:17:07.435102920Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 6 23:17:07.435367 containerd[1727]: time="2025-11-06T23:17:07.435351120Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 6 23:17:07.435461 containerd[1727]: time="2025-11-06T23:17:07.435446640Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 6 23:17:07.435486 containerd[1727]: time="2025-11-06T23:17:07.435467240Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 6 23:17:07.435486 containerd[1727]: time="2025-11-06T23:17:07.435482160Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 6 23:17:07.435530 containerd[1727]: time="2025-11-06T23:17:07.435497080Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435530 containerd[1727]: time="2025-11-06T23:17:07.435509440Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435530 containerd[1727]: time="2025-11-06T23:17:07.435521520Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435606 containerd[1727]: time="2025-11-06T23:17:07.435535800Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435606 containerd[1727]: time="2025-11-06T23:17:07.435549720Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435606 containerd[1727]: time="2025-11-06T23:17:07.435562080Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435606 containerd[1727]: time="2025-11-06T23:17:07.435575760Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435606 containerd[1727]: time="2025-11-06T23:17:07.435587440Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 6 23:17:07.435688 containerd[1727]: time="2025-11-06T23:17:07.435611520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435688 containerd[1727]: time="2025-11-06T23:17:07.435625320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435688 containerd[1727]: time="2025-11-06T23:17:07.435638160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435688 containerd[1727]: time="2025-11-06T23:17:07.435650920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435688 containerd[1727]: time="2025-11-06T23:17:07.435662120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435688 containerd[1727]: time="2025-11-06T23:17:07.435674520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435688 containerd[1727]: time="2025-11-06T23:17:07.435686120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435699560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435712960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435727160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435738680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435750600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435763360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435777600Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435797480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.435817 containerd[1727]: time="2025-11-06T23:17:07.435810680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.435822520Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.435875200Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.435894440Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.435904440Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.435915720Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.436499560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.436523040Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.436551360Z" level=info msg="NRI interface is disabled by configuration." Nov 6 23:17:07.438055 containerd[1727]: time="2025-11-06T23:17:07.436568800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 6 23:17:07.438245 containerd[1727]: time="2025-11-06T23:17:07.438171720Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 6 23:17:07.438245 containerd[1727]: time="2025-11-06T23:17:07.438232560Z" level=info msg="Connect containerd service" Nov 6 23:17:07.438359 containerd[1727]: time="2025-11-06T23:17:07.438290120Z" level=info msg="using legacy CRI server" Nov 6 23:17:07.438359 containerd[1727]: time="2025-11-06T23:17:07.438298400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 6 23:17:07.439401 containerd[1727]: time="2025-11-06T23:17:07.439371320Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 6 23:17:07.442500 containerd[1727]: time="2025-11-06T23:17:07.442470280Z" 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" Nov 6 23:17:07.442751 containerd[1727]: time="2025-11-06T23:17:07.442709920Z" level=info msg="Start subscribing containerd event" Nov 6 23:17:07.442787 containerd[1727]: time="2025-11-06T23:17:07.442766800Z" level=info msg="Start recovering state" Nov 6 23:17:07.442853 containerd[1727]: time="2025-11-06T23:17:07.442838680Z" level=info msg="Start event monitor" Nov 6 23:17:07.442895 containerd[1727]: time="2025-11-06T23:17:07.442853560Z" level=info msg="Start snapshots syncer" Nov 6 23:17:07.442895 containerd[1727]: time="2025-11-06T23:17:07.442863920Z" level=info msg="Start cni network conf syncer for default" Nov 6 23:17:07.442895 containerd[1727]: time="2025-11-06T23:17:07.442870560Z" level=info msg="Start streaming server" Nov 6 23:17:07.450193 containerd[1727]: time="2025-11-06T23:17:07.445068280Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 6 23:17:07.450193 containerd[1727]: time="2025-11-06T23:17:07.445128800Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 6 23:17:07.450193 containerd[1727]: time="2025-11-06T23:17:07.450161200Z" level=info msg="containerd successfully booted in 0.110558s" Nov 6 23:17:07.445268 systemd[1]: Started containerd.service - containerd container runtime. Nov 6 23:17:07.655092 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:07.662406 (kubelet)[1848]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 6 23:17:08.018004 kubelet[1848]: E1106 23:17:08.017623 1848 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" Nov 6 23:17:08.020453 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 6 23:17:08.020582 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 6 23:17:08.020840 systemd[1]: kubelet.service: Consumed 647ms CPU time, 247.1M memory peak. Nov 6 23:17:08.097232 sshd_keygen[1738]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 6 23:17:08.114693 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 6 23:17:08.127153 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 6 23:17:08.134218 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 6 23:17:08.141205 systemd[1]: issuegen.service: Deactivated successfully. Nov 6 23:17:08.142842 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 6 23:17:08.157499 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 6 23:17:08.164155 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 6 23:17:08.183668 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 6 23:17:08.194463 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 6 23:17:08.200229 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 6 23:17:08.205502 systemd[1]: Reached target getty.target - Login Prompts. Nov 6 23:17:08.209851 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 6 23:17:08.214530 systemd[1]: Startup finished in 627ms (kernel) + 11.638s (initrd) + 16.646s (userspace) = 28.912s. Nov 6 23:17:08.717214 login[1879]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 6 23:17:08.729283 login[1878]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:08.737607 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 6 23:17:08.742159 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 6 23:17:08.745906 systemd-logind[1712]: New session 1 of user core. Nov 6 23:17:08.763666 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 6 23:17:08.771729 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 6 23:17:08.798781 (systemd)[1886]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 6 23:17:08.800762 systemd-logind[1712]: New session c1 of user core. Nov 6 23:17:09.190683 systemd[1886]: Queued start job for default target default.target. Nov 6 23:17:09.202942 systemd[1886]: Created slice app.slice - User Application Slice. Nov 6 23:17:09.202971 systemd[1886]: Reached target paths.target - Paths. Nov 6 23:17:09.203009 systemd[1886]: Reached target timers.target - Timers. Nov 6 23:17:09.204165 systemd[1886]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 6 23:17:09.214098 systemd[1886]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 6 23:17:09.214159 systemd[1886]: Reached target sockets.target - Sockets. Nov 6 23:17:09.214209 systemd[1886]: Reached target basic.target - Basic System. Nov 6 23:17:09.214239 systemd[1886]: Reached target default.target - Main User Target. Nov 6 23:17:09.214266 systemd[1886]: Startup finished in 404ms. Nov 6 23:17:09.214275 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 6 23:17:09.222147 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 6 23:17:09.718646 login[1879]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:09.722847 systemd-logind[1712]: New session 2 of user core. Nov 6 23:17:09.730078 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 6 23:17:10.150827 waagent[1875]: 2025-11-06T23:17:10.150681Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Nov 6 23:17:10.155359 waagent[1875]: 2025-11-06T23:17:10.155295Z INFO Daemon Daemon OS: flatcar 4230.2.4 Nov 6 23:17:10.159028 waagent[1875]: 2025-11-06T23:17:10.158986Z INFO Daemon Daemon Python: 3.11.11 Nov 6 23:17:10.162835 waagent[1875]: 2025-11-06T23:17:10.162784Z INFO Daemon Daemon Run daemon Nov 6 23:17:10.166194 waagent[1875]: 2025-11-06T23:17:10.166153Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.4' Nov 6 23:17:10.173529 waagent[1875]: 2025-11-06T23:17:10.173370Z INFO Daemon Daemon Using waagent for provisioning Nov 6 23:17:10.178223 waagent[1875]: 2025-11-06T23:17:10.178182Z INFO Daemon Daemon Activate resource disk Nov 6 23:17:10.181984 waagent[1875]: 2025-11-06T23:17:10.181943Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 6 23:17:10.192705 waagent[1875]: 2025-11-06T23:17:10.192649Z INFO Daemon Daemon Found device: None Nov 6 23:17:10.196307 waagent[1875]: 2025-11-06T23:17:10.196266Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Nov 6 23:17:10.203426 waagent[1875]: 2025-11-06T23:17:10.203384Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Nov 6 23:17:10.212727 waagent[1875]: 2025-11-06T23:17:10.212684Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 23:17:10.217321 waagent[1875]: 2025-11-06T23:17:10.217281Z INFO Daemon Daemon Running default provisioning handler Nov 6 23:17:10.227794 waagent[1875]: 2025-11-06T23:17:10.227728Z 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. Nov 6 23:17:10.238703 waagent[1875]: 2025-11-06T23:17:10.238648Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 6 23:17:10.246515 waagent[1875]: 2025-11-06T23:17:10.246466Z INFO Daemon Daemon cloud-init is enabled: False Nov 6 23:17:10.250464 waagent[1875]: 2025-11-06T23:17:10.250424Z INFO Daemon Daemon Copying ovf-env.xml Nov 6 23:17:10.363632 waagent[1875]: 2025-11-06T23:17:10.363112Z INFO Daemon Daemon Successfully mounted dvd Nov 6 23:17:10.390089 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 6 23:17:10.392230 waagent[1875]: 2025-11-06T23:17:10.392157Z INFO Daemon Daemon Detect protocol endpoint Nov 6 23:17:10.396653 waagent[1875]: 2025-11-06T23:17:10.396589Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 23:17:10.401624 waagent[1875]: 2025-11-06T23:17:10.401521Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 6 23:17:10.407656 waagent[1875]: 2025-11-06T23:17:10.407590Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 6 23:17:10.412441 waagent[1875]: 2025-11-06T23:17:10.412382Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 6 23:17:10.417229 waagent[1875]: 2025-11-06T23:17:10.417164Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 6 23:17:10.463950 waagent[1875]: 2025-11-06T23:17:10.461181Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 6 23:17:10.467456 waagent[1875]: 2025-11-06T23:17:10.467426Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 6 23:17:10.472036 waagent[1875]: 2025-11-06T23:17:10.471986Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 6 23:17:10.634666 waagent[1875]: 2025-11-06T23:17:10.634569Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 6 23:17:10.640312 waagent[1875]: 2025-11-06T23:17:10.640253Z INFO Daemon Daemon Forcing an update of the goal state. Nov 6 23:17:10.649621 waagent[1875]: 2025-11-06T23:17:10.649573Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 23:17:10.671117 waagent[1875]: 2025-11-06T23:17:10.671029Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 6 23:17:10.676198 waagent[1875]: 2025-11-06T23:17:10.676153Z INFO Daemon Nov 6 23:17:10.678646 waagent[1875]: 2025-11-06T23:17:10.678607Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 24fbd5a9-15e9-4033-bb51-b0711860570a eTag: 68881200960205587 source: Fabric] Nov 6 23:17:10.689060 waagent[1875]: 2025-11-06T23:17:10.688968Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 6 23:17:10.695397 waagent[1875]: 2025-11-06T23:17:10.695351Z INFO Daemon Nov 6 23:17:10.698233 waagent[1875]: 2025-11-06T23:17:10.698190Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 6 23:17:10.708688 waagent[1875]: 2025-11-06T23:17:10.708653Z INFO Daemon Daemon Downloading artifacts profile blob Nov 6 23:17:10.787904 waagent[1875]: 2025-11-06T23:17:10.787811Z INFO Daemon Downloaded certificate {'thumbprint': '0C2C026248E03ED50E0EF8D3C08DA417C9EB7AD9', 'hasPrivateKey': True} Nov 6 23:17:10.796541 waagent[1875]: 2025-11-06T23:17:10.796492Z INFO Daemon Fetch goal state completed Nov 6 23:17:10.850015 waagent[1875]: 2025-11-06T23:17:10.849955Z INFO Daemon Daemon Starting provisioning Nov 6 23:17:10.854774 waagent[1875]: 2025-11-06T23:17:10.854715Z INFO Daemon Daemon Handle ovf-env.xml. Nov 6 23:17:10.858723 waagent[1875]: 2025-11-06T23:17:10.858683Z INFO Daemon Daemon Set hostname [ci-4230.2.4-n-5fb6643642] Nov 6 23:17:10.898946 waagent[1875]: 2025-11-06T23:17:10.893999Z INFO Daemon Daemon Publish hostname [ci-4230.2.4-n-5fb6643642] Nov 6 23:17:10.899398 waagent[1875]: 2025-11-06T23:17:10.899341Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 6 23:17:10.904754 waagent[1875]: 2025-11-06T23:17:10.904707Z INFO Daemon Daemon Primary interface is [eth0] Nov 6 23:17:10.915654 systemd-networkd[1341]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:17:10.915666 systemd-networkd[1341]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:17:10.915708 systemd-networkd[1341]: eth0: DHCP lease lost Nov 6 23:17:10.916637 waagent[1875]: 2025-11-06T23:17:10.916545Z INFO Daemon Daemon Create user account if not exists Nov 6 23:17:10.921308 waagent[1875]: 2025-11-06T23:17:10.921218Z INFO Daemon Daemon User core already exists, skip useradd Nov 6 23:17:10.926051 waagent[1875]: 2025-11-06T23:17:10.925995Z INFO Daemon Daemon Configure sudoer Nov 6 23:17:10.930153 waagent[1875]: 2025-11-06T23:17:10.930096Z INFO Daemon Daemon Configure sshd Nov 6 23:17:10.934515 waagent[1875]: 2025-11-06T23:17:10.934462Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 6 23:17:10.944979 waagent[1875]: 2025-11-06T23:17:10.944877Z INFO Daemon Daemon Deploy ssh public key. Nov 6 23:17:10.954020 systemd-networkd[1341]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 6 23:17:12.097534 waagent[1875]: 2025-11-06T23:17:12.093412Z INFO Daemon Daemon Provisioning complete Nov 6 23:17:12.109135 waagent[1875]: 2025-11-06T23:17:12.109091Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 6 23:17:12.114560 waagent[1875]: 2025-11-06T23:17:12.114506Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 6 23:17:12.123533 waagent[1875]: 2025-11-06T23:17:12.123485Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Nov 6 23:17:12.252951 waagent[1936]: 2025-11-06T23:17:12.252432Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Nov 6 23:17:12.252951 waagent[1936]: 2025-11-06T23:17:12.252576Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.4 Nov 6 23:17:12.252951 waagent[1936]: 2025-11-06T23:17:12.252627Z INFO ExtHandler ExtHandler Python: 3.11.11 Nov 6 23:17:12.633951 waagent[1936]: 2025-11-06T23:17:12.633229Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.4; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Nov 6 23:17:12.633951 waagent[1936]: 2025-11-06T23:17:12.633465Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:17:12.633951 waagent[1936]: 2025-11-06T23:17:12.633523Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:17:12.641636 waagent[1936]: 2025-11-06T23:17:12.641573Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 23:17:12.648584 waagent[1936]: 2025-11-06T23:17:12.648545Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 6 23:17:12.649109 waagent[1936]: 2025-11-06T23:17:12.649071Z INFO ExtHandler Nov 6 23:17:12.649180 waagent[1936]: 2025-11-06T23:17:12.649154Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: eb839aa7-7c6f-4f58-8152-047cbf4e56bb eTag: 68881200960205587 source: Fabric] Nov 6 23:17:12.649453 waagent[1936]: 2025-11-06T23:17:12.649419Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 6 23:17:12.650033 waagent[1936]: 2025-11-06T23:17:12.649993Z INFO ExtHandler Nov 6 23:17:12.650095 waagent[1936]: 2025-11-06T23:17:12.650070Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 6 23:17:12.654487 waagent[1936]: 2025-11-06T23:17:12.654456Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 6 23:17:12.723883 waagent[1936]: 2025-11-06T23:17:12.723792Z INFO ExtHandler Downloaded certificate {'thumbprint': '0C2C026248E03ED50E0EF8D3C08DA417C9EB7AD9', 'hasPrivateKey': True} Nov 6 23:17:12.724399 waagent[1936]: 2025-11-06T23:17:12.724356Z INFO ExtHandler Fetch goal state completed Nov 6 23:17:12.738077 waagent[1936]: 2025-11-06T23:17:12.738016Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1936 Nov 6 23:17:12.738225 waagent[1936]: 2025-11-06T23:17:12.738193Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 6 23:17:12.739779 waagent[1936]: 2025-11-06T23:17:12.739739Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.4', '', 'Flatcar Container Linux by Kinvolk'] Nov 6 23:17:12.740162 waagent[1936]: 2025-11-06T23:17:12.740116Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 6 23:17:12.851478 waagent[1936]: 2025-11-06T23:17:12.851436Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 6 23:17:12.851670 waagent[1936]: 2025-11-06T23:17:12.851636Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 6 23:17:12.857680 waagent[1936]: 2025-11-06T23:17:12.857634Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 6 23:17:12.863767 systemd[1]: Reload requested from client PID 1950 ('systemctl') (unit waagent.service)... Nov 6 23:17:12.863780 systemd[1]: Reloading... Nov 6 23:17:12.945951 zram_generator::config[1986]: No configuration found. Nov 6 23:17:13.045297 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:13.141745 systemd[1]: Reloading finished in 277 ms. Nov 6 23:17:13.160936 waagent[1936]: 2025-11-06T23:17:13.159395Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Nov 6 23:17:13.165520 systemd[1]: Reload requested from client PID 2043 ('systemctl') (unit waagent.service)... Nov 6 23:17:13.165533 systemd[1]: Reloading... Nov 6 23:17:13.255327 zram_generator::config[2082]: No configuration found. Nov 6 23:17:13.354174 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:13.451070 systemd[1]: Reloading finished in 285 ms. Nov 6 23:17:13.465836 waagent[1936]: 2025-11-06T23:17:13.465071Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 6 23:17:13.465836 waagent[1936]: 2025-11-06T23:17:13.465229Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 6 23:17:13.893466 waagent[1936]: 2025-11-06T23:17:13.892299Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 6 23:17:13.893466 waagent[1936]: 2025-11-06T23:17:13.892887Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Nov 6 23:17:13.893743 waagent[1936]: 2025-11-06T23:17:13.893688Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 6 23:17:13.893784 waagent[1936]: 2025-11-06T23:17:13.893749Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:17:13.893900 waagent[1936]: 2025-11-06T23:17:13.893863Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:17:13.894149 waagent[1936]: 2025-11-06T23:17:13.894104Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 6 23:17:13.894609 waagent[1936]: 2025-11-06T23:17:13.894551Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 6 23:17:13.894745 waagent[1936]: 2025-11-06T23:17:13.894698Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 6 23:17:13.894745 waagent[1936]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 6 23:17:13.894745 waagent[1936]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Nov 6 23:17:13.894745 waagent[1936]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 6 23:17:13.894745 waagent[1936]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:17:13.894745 waagent[1936]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:17:13.894745 waagent[1936]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:17:13.894897 waagent[1936]: 2025-11-06T23:17:13.894810Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:17:13.894897 waagent[1936]: 2025-11-06T23:17:13.894879Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:17:13.895232 waagent[1936]: 2025-11-06T23:17:13.895006Z INFO EnvHandler ExtHandler Configure routes Nov 6 23:17:13.895319 waagent[1936]: 2025-11-06T23:17:13.895271Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 6 23:17:13.895929 waagent[1936]: 2025-11-06T23:17:13.895834Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 6 23:17:13.895997 waagent[1936]: 2025-11-06T23:17:13.895948Z INFO EnvHandler ExtHandler Gateway:None Nov 6 23:17:13.896251 waagent[1936]: 2025-11-06T23:17:13.896020Z INFO EnvHandler ExtHandler Routes:None Nov 6 23:17:13.896413 waagent[1936]: 2025-11-06T23:17:13.896316Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 6 23:17:13.896513 waagent[1936]: 2025-11-06T23:17:13.896465Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 6 23:17:13.897319 waagent[1936]: 2025-11-06T23:17:13.897165Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 6 23:17:13.902536 waagent[1936]: 2025-11-06T23:17:13.902482Z INFO ExtHandler ExtHandler Nov 6 23:17:13.902993 waagent[1936]: 2025-11-06T23:17:13.902944Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: e737753e-1bdd-47c2-839e-7d71a23cd5de correlation d569dcf9-d8b5-4faa-9c00-a013ac84e072 created: 2025-11-06T23:15:58.965546Z] Nov 6 23:17:13.903942 waagent[1936]: 2025-11-06T23:17:13.903882Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 6 23:17:13.905226 waagent[1936]: 2025-11-06T23:17:13.904526Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Nov 6 23:17:13.949030 waagent[1936]: 2025-11-06T23:17:13.948879Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 731B3171-166F-412F-8D33-D6F13738DE71;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Nov 6 23:17:13.997098 waagent[1936]: 2025-11-06T23:17:13.997017Z INFO MonitorHandler ExtHandler Network interfaces: Nov 6 23:17:13.997098 waagent[1936]: Executing ['ip', '-a', '-o', 'link']: Nov 6 23:17:13.997098 waagent[1936]: 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 Nov 6 23:17:13.997098 waagent[1936]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f7:54:56 brd ff:ff:ff:ff:ff:ff Nov 6 23:17:13.997098 waagent[1936]: 3: enP51967s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f7:54:56 brd ff:ff:ff:ff:ff:ff\ altname enP51967p0s2 Nov 6 23:17:13.997098 waagent[1936]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 6 23:17:13.997098 waagent[1936]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 6 23:17:13.997098 waagent[1936]: 2: eth0 inet 10.200.20.17/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 6 23:17:13.997098 waagent[1936]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 6 23:17:13.997098 waagent[1936]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 6 23:17:13.997098 waagent[1936]: 2: eth0 inet6 fe80::20d:3aff:fef7:5456/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 6 23:17:14.040889 waagent[1936]: 2025-11-06T23:17:14.040089Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Nov 6 23:17:14.040889 waagent[1936]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:14.040889 waagent[1936]: pkts bytes target prot opt in out source destination Nov 6 23:17:14.040889 waagent[1936]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:14.040889 waagent[1936]: pkts bytes target prot opt in out source destination Nov 6 23:17:14.040889 waagent[1936]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:14.040889 waagent[1936]: pkts bytes target prot opt in out source destination Nov 6 23:17:14.040889 waagent[1936]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 23:17:14.040889 waagent[1936]: 7 702 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 23:17:14.040889 waagent[1936]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 23:17:14.042839 waagent[1936]: 2025-11-06T23:17:14.042792Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 6 23:17:14.042839 waagent[1936]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:14.042839 waagent[1936]: pkts bytes target prot opt in out source destination Nov 6 23:17:14.042839 waagent[1936]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:14.042839 waagent[1936]: pkts bytes target prot opt in out source destination Nov 6 23:17:14.042839 waagent[1936]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:14.042839 waagent[1936]: pkts bytes target prot opt in out source destination Nov 6 23:17:14.042839 waagent[1936]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 23:17:14.042839 waagent[1936]: 12 1348 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 23:17:14.042839 waagent[1936]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 23:17:14.043362 waagent[1936]: 2025-11-06T23:17:14.043331Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 6 23:17:15.385093 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 6 23:17:15.390316 systemd[1]: Started sshd@0-10.200.20.17:22-10.200.16.10:40414.service - OpenSSH per-connection server daemon (10.200.16.10:40414). Nov 6 23:17:16.050394 sshd[2168]: Accepted publickey for core from 10.200.16.10 port 40414 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:17:16.051638 sshd-session[2168]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:16.055977 systemd-logind[1712]: New session 3 of user core. Nov 6 23:17:16.063068 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 6 23:17:16.519185 systemd[1]: Started sshd@1-10.200.20.17:22-10.200.16.10:40428.service - OpenSSH per-connection server daemon (10.200.16.10:40428). Nov 6 23:17:17.012813 sshd[2173]: Accepted publickey for core from 10.200.16.10 port 40428 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:17:17.014636 sshd-session[2173]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:17.019544 systemd-logind[1712]: New session 4 of user core. Nov 6 23:17:17.025083 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 6 23:17:17.379030 sshd[2175]: Connection closed by 10.200.16.10 port 40428 Nov 6 23:17:17.379679 sshd-session[2173]: pam_unix(sshd:session): session closed for user core Nov 6 23:17:17.382913 systemd[1]: sshd@1-10.200.20.17:22-10.200.16.10:40428.service: Deactivated successfully. Nov 6 23:17:17.384351 systemd[1]: session-4.scope: Deactivated successfully. Nov 6 23:17:17.385028 systemd-logind[1712]: Session 4 logged out. Waiting for processes to exit. Nov 6 23:17:17.386040 systemd-logind[1712]: Removed session 4. Nov 6 23:17:17.467912 systemd[1]: Started sshd@2-10.200.20.17:22-10.200.16.10:40440.service - OpenSSH per-connection server daemon (10.200.16.10:40440). Nov 6 23:17:17.964647 sshd[2181]: Accepted publickey for core from 10.200.16.10 port 40440 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:17:17.965884 sshd-session[2181]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:17.970054 systemd-logind[1712]: New session 5 of user core. Nov 6 23:17:17.976059 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 6 23:17:18.271227 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Nov 6 23:17:18.282090 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:17:18.308122 sshd[2183]: Connection closed by 10.200.16.10 port 40440 Nov 6 23:17:18.308470 sshd-session[2181]: pam_unix(sshd:session): session closed for user core Nov 6 23:17:18.312701 systemd[1]: sshd@2-10.200.20.17:22-10.200.16.10:40440.service: Deactivated successfully. Nov 6 23:17:18.314781 systemd[1]: session-5.scope: Deactivated successfully. Nov 6 23:17:18.316811 systemd-logind[1712]: Session 5 logged out. Waiting for processes to exit. Nov 6 23:17:18.318083 systemd-logind[1712]: Removed session 5. Nov 6 23:17:18.395023 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:18.397565 (kubelet)[2196]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 6 23:17:18.402175 systemd[1]: Started sshd@3-10.200.20.17:22-10.200.16.10:40456.service - OpenSSH per-connection server daemon (10.200.16.10:40456). Nov 6 23:17:18.507034 kubelet[2196]: E1106 23:17:18.506982 2196 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" Nov 6 23:17:18.510607 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 6 23:17:18.510871 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 6 23:17:18.511342 systemd[1]: kubelet.service: Consumed 130ms CPU time, 107.1M memory peak. Nov 6 23:17:18.901373 sshd[2198]: Accepted publickey for core from 10.200.16.10 port 40456 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:17:18.902681 sshd-session[2198]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:18.907024 systemd-logind[1712]: New session 6 of user core. Nov 6 23:17:18.910134 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 6 23:17:19.262011 sshd[2206]: Connection closed by 10.200.16.10 port 40456 Nov 6 23:17:19.262630 sshd-session[2198]: pam_unix(sshd:session): session closed for user core Nov 6 23:17:19.266122 systemd[1]: sshd@3-10.200.20.17:22-10.200.16.10:40456.service: Deactivated successfully. Nov 6 23:17:19.268959 systemd[1]: session-6.scope: Deactivated successfully. Nov 6 23:17:19.269671 systemd-logind[1712]: Session 6 logged out. Waiting for processes to exit. Nov 6 23:17:19.270739 systemd-logind[1712]: Removed session 6. Nov 6 23:17:19.359743 systemd[1]: Started sshd@4-10.200.20.17:22-10.200.16.10:40460.service - OpenSSH per-connection server daemon (10.200.16.10:40460). Nov 6 23:17:19.880098 sshd[2212]: Accepted publickey for core from 10.200.16.10 port 40460 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:17:19.881307 sshd-session[2212]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:19.886426 systemd-logind[1712]: New session 7 of user core. Nov 6 23:17:19.892086 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 6 23:17:20.307014 sudo[2215]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 6 23:17:20.307286 sudo[2215]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 6 23:17:20.757230 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:20.757615 systemd[1]: kubelet.service: Consumed 130ms CPU time, 107.1M memory peak. Nov 6 23:17:20.764299 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:17:20.792860 systemd[1]: Reload requested from client PID 2247 ('systemctl') (unit session-7.scope)... Nov 6 23:17:20.792879 systemd[1]: Reloading... Nov 6 23:17:20.901975 zram_generator::config[2299]: No configuration found. Nov 6 23:17:20.998641 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:21.099223 systemd[1]: Reloading finished in 305 ms. Nov 6 23:17:21.144153 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:21.148438 (kubelet)[2350]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 6 23:17:21.152156 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:17:21.152627 systemd[1]: kubelet.service: Deactivated successfully. Nov 6 23:17:21.152832 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:21.152873 systemd[1]: kubelet.service: Consumed 86ms CPU time, 96.5M memory peak. Nov 6 23:17:21.159236 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:17:21.335992 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:21.341197 (kubelet)[2367]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 6 23:17:21.482843 kubelet[2367]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 6 23:17:21.482843 kubelet[2367]: 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. Nov 6 23:17:21.483276 kubelet[2367]: I1106 23:17:21.483230 2367 server.go:213] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 6 23:17:22.611700 kubelet[2367]: I1106 23:17:22.611661 2367 server.go:529] "Kubelet version" kubeletVersion="v1.34.1" Nov 6 23:17:22.611700 kubelet[2367]: I1106 23:17:22.611691 2367 server.go:531] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 6 23:17:22.612910 kubelet[2367]: I1106 23:17:22.612887 2367 watchdog_linux.go:95] "Systemd watchdog is not enabled" Nov 6 23:17:22.612910 kubelet[2367]: I1106 23:17:22.612910 2367 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 6 23:17:22.613184 kubelet[2367]: I1106 23:17:22.613167 2367 server.go:956] "Client rotation is on, will bootstrap in background" Nov 6 23:17:22.616759 kubelet[2367]: I1106 23:17:22.616687 2367 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 6 23:17:22.622326 kubelet[2367]: E1106 23:17:22.621191 2367 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Nov 6 23:17:22.622326 kubelet[2367]: I1106 23:17:22.621252 2367 server.go:1400] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Nov 6 23:17:22.625397 kubelet[2367]: I1106 23:17:22.625371 2367 server.go:781] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Nov 6 23:17:22.625572 kubelet[2367]: I1106 23:17:22.625545 2367 container_manager_linux.go:270] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 6 23:17:22.625709 kubelet[2367]: I1106 23:17:22.625570 2367 container_manager_linux.go:275] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.20.17","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} Nov 6 23:17:22.625790 kubelet[2367]: I1106 23:17:22.625709 2367 topology_manager.go:138] "Creating topology manager with none policy" Nov 6 23:17:22.625790 kubelet[2367]: I1106 23:17:22.625718 2367 container_manager_linux.go:306] "Creating device plugin manager" Nov 6 23:17:22.625834 kubelet[2367]: I1106 23:17:22.625811 2367 container_manager_linux.go:315] "Creating Dynamic Resource Allocation (DRA) manager" Nov 6 23:17:22.632132 kubelet[2367]: I1106 23:17:22.632101 2367 state_mem.go:36] "Initialized new in-memory state store" Nov 6 23:17:22.633277 kubelet[2367]: I1106 23:17:22.633258 2367 kubelet.go:475] "Attempting to sync node with API server" Nov 6 23:17:22.633322 kubelet[2367]: I1106 23:17:22.633286 2367 kubelet.go:376] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 6 23:17:22.633322 kubelet[2367]: I1106 23:17:22.633309 2367 kubelet.go:387] "Adding apiserver pod source" Nov 6 23:17:22.633322 kubelet[2367]: I1106 23:17:22.633321 2367 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 6 23:17:22.635120 kubelet[2367]: E1106 23:17:22.633957 2367 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:22.635120 kubelet[2367]: E1106 23:17:22.633979 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:22.635120 kubelet[2367]: I1106 23:17:22.634077 2367 kuberuntime_manager.go:291] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Nov 6 23:17:22.635120 kubelet[2367]: I1106 23:17:22.634679 2367 kubelet.go:940] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Nov 6 23:17:22.635120 kubelet[2367]: I1106 23:17:22.634708 2367 kubelet.go:964] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Nov 6 23:17:22.635120 kubelet[2367]: W1106 23:17:22.634747 2367 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 6 23:17:22.637357 kubelet[2367]: I1106 23:17:22.637339 2367 server.go:1262] "Started kubelet" Nov 6 23:17:22.638422 kubelet[2367]: I1106 23:17:22.638403 2367 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 6 23:17:22.643589 kubelet[2367]: E1106 23:17:22.643559 2367 kubelet.go:1615] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 6 23:17:22.643726 kubelet[2367]: I1106 23:17:22.643703 2367 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Nov 6 23:17:22.644486 kubelet[2367]: I1106 23:17:22.644462 2367 server.go:310] "Adding debug handlers to kubelet server" Nov 6 23:17:22.645339 kubelet[2367]: I1106 23:17:22.645321 2367 volume_manager.go:313] "Starting Kubelet Volume Manager" Nov 6 23:17:22.645634 kubelet[2367]: E1106 23:17:22.645611 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:22.647225 kubelet[2367]: I1106 23:17:22.647174 2367 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 6 23:17:22.647279 kubelet[2367]: I1106 23:17:22.647240 2367 server_v1.go:49] "podresources" method="list" useActivePods=true Nov 6 23:17:22.647432 kubelet[2367]: I1106 23:17:22.647412 2367 server.go:249] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 6 23:17:22.647660 kubelet[2367]: I1106 23:17:22.647629 2367 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 6 23:17:22.648096 kubelet[2367]: I1106 23:17:22.648082 2367 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Nov 6 23:17:22.648211 kubelet[2367]: I1106 23:17:22.648202 2367 reconciler.go:29] "Reconciler: start to sync state" Nov 6 23:17:22.649617 kubelet[2367]: I1106 23:17:22.649593 2367 factory.go:223] Registration of the systemd container factory successfully Nov 6 23:17:22.649761 kubelet[2367]: I1106 23:17:22.649732 2367 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 Nov 6 23:17:22.652352 kubelet[2367]: E1106 23:17:22.652316 2367 reflector.go:205] "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" Nov 6 23:17:22.653407 kubelet[2367]: E1106 23:17:22.652365 2367 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.200.20.17.18758e13474f0ad0 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.200.20.17,UID:10.200.20.17,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.200.20.17,},FirstTimestamp:2025-11-06 23:17:22.63730248 +0000 UTC m=+1.290921681,LastTimestamp:2025-11-06 23:17:22.63730248 +0000 UTC m=+1.290921681,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.200.20.17,}" Nov 6 23:17:22.653751 kubelet[2367]: E1106 23:17:22.653727 2367 reflector.go:205] "Failed to watch" err="failed to list *v1.Node: nodes \"10.200.20.17\" 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" Nov 6 23:17:22.653916 kubelet[2367]: E1106 23:17:22.653790 2367 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.200.20.17\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Nov 6 23:17:22.656037 kubelet[2367]: E1106 23:17:22.655966 2367 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.200.20.17.18758e1347ae4220 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.200.20.17,UID:10.200.20.17,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.200.20.17,},FirstTimestamp:2025-11-06 23:17:22.64354256 +0000 UTC m=+1.297161721,LastTimestamp:2025-11-06 23:17:22.64354256 +0000 UTC m=+1.297161721,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.200.20.17,}" Nov 6 23:17:22.656673 kubelet[2367]: I1106 23:17:22.656652 2367 factory.go:223] Registration of the containerd container factory successfully Nov 6 23:17:22.668251 kubelet[2367]: I1106 23:17:22.667581 2367 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 6 23:17:22.668251 kubelet[2367]: I1106 23:17:22.667599 2367 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 6 23:17:22.668251 kubelet[2367]: I1106 23:17:22.667618 2367 state_mem.go:36] "Initialized new in-memory state store" Nov 6 23:17:22.673743 kubelet[2367]: I1106 23:17:22.673716 2367 policy_none.go:49] "None policy: Start" Nov 6 23:17:22.673743 kubelet[2367]: I1106 23:17:22.673748 2367 memory_manager.go:187] "Starting memorymanager" policy="None" Nov 6 23:17:22.673873 kubelet[2367]: I1106 23:17:22.673765 2367 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Nov 6 23:17:22.678687 kubelet[2367]: I1106 23:17:22.678659 2367 policy_none.go:47] "Start" Nov 6 23:17:22.683008 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 6 23:17:22.698290 kubelet[2367]: I1106 23:17:22.697573 2367 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Nov 6 23:17:22.701913 kubelet[2367]: I1106 23:17:22.701215 2367 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Nov 6 23:17:22.701913 kubelet[2367]: I1106 23:17:22.701241 2367 status_manager.go:244] "Starting to sync pod status with apiserver" Nov 6 23:17:22.701913 kubelet[2367]: I1106 23:17:22.701261 2367 kubelet.go:2427] "Starting kubelet main sync loop" Nov 6 23:17:22.701913 kubelet[2367]: E1106 23:17:22.701299 2367 kubelet.go:2451] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 6 23:17:22.702453 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 6 23:17:22.709153 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 6 23:17:22.714657 kubelet[2367]: E1106 23:17:22.714623 2367 manager.go:513] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Nov 6 23:17:22.714844 kubelet[2367]: I1106 23:17:22.714827 2367 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 6 23:17:22.714875 kubelet[2367]: I1106 23:17:22.714843 2367 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 6 23:17:22.715563 kubelet[2367]: I1106 23:17:22.715547 2367 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 6 23:17:22.717440 kubelet[2367]: E1106 23:17:22.716856 2367 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 6 23:17:22.717440 kubelet[2367]: E1106 23:17:22.716891 2367 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.20.17\" not found" Nov 6 23:17:22.816410 kubelet[2367]: I1106 23:17:22.815979 2367 kubelet_node_status.go:75] "Attempting to register node" node="10.200.20.17" Nov 6 23:17:22.822629 kubelet[2367]: I1106 23:17:22.822607 2367 kubelet_node_status.go:78] "Successfully registered node" node="10.200.20.17" Nov 6 23:17:22.822726 kubelet[2367]: E1106 23:17:22.822716 2367 kubelet_node_status.go:486] "Error updating node status, will retry" err="error getting node \"10.200.20.17\": node \"10.200.20.17\" not found" Nov 6 23:17:22.834127 kubelet[2367]: E1106 23:17:22.834102 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:22.854958 sudo[2215]: pam_unix(sudo:session): session closed for user root Nov 6 23:17:22.934847 kubelet[2367]: E1106 23:17:22.934704 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:22.935158 sshd[2214]: Connection closed by 10.200.16.10 port 40460 Nov 6 23:17:22.936207 sshd-session[2212]: pam_unix(sshd:session): session closed for user core Nov 6 23:17:22.938869 systemd[1]: sshd@4-10.200.20.17:22-10.200.16.10:40460.service: Deactivated successfully. Nov 6 23:17:22.940853 systemd[1]: session-7.scope: Deactivated successfully. Nov 6 23:17:22.941113 systemd[1]: session-7.scope: Consumed 356ms CPU time, 74.4M memory peak. Nov 6 23:17:22.943192 systemd-logind[1712]: Session 7 logged out. Waiting for processes to exit. Nov 6 23:17:22.944266 systemd-logind[1712]: Removed session 7. Nov 6 23:17:23.035255 kubelet[2367]: E1106 23:17:23.035207 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:23.135873 kubelet[2367]: E1106 23:17:23.135840 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:23.236459 kubelet[2367]: E1106 23:17:23.236365 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:23.336951 kubelet[2367]: E1106 23:17:23.336912 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:23.437508 kubelet[2367]: E1106 23:17:23.437478 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:23.538106 kubelet[2367]: E1106 23:17:23.538022 2367 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Nov 6 23:17:23.614880 kubelet[2367]: I1106 23:17:23.614836 2367 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 6 23:17:23.615360 kubelet[2367]: I1106 23:17:23.614981 2367 reflector.go:568] "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" Nov 6 23:17:23.615360 kubelet[2367]: I1106 23:17:23.615024 2367 reflector.go:568] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" 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" Nov 6 23:17:23.615523 kubelet[2367]: I1106 23:17:23.615480 2367 reflector.go:568] "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" Nov 6 23:17:23.634054 kubelet[2367]: I1106 23:17:23.634030 2367 apiserver.go:52] "Watching apiserver" Nov 6 23:17:23.634138 kubelet[2367]: E1106 23:17:23.634032 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:23.638951 kubelet[2367]: I1106 23:17:23.638844 2367 kuberuntime_manager.go:1828] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 6 23:17:23.639166 containerd[1727]: time="2025-11-06T23:17:23.639129600Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 6 23:17:23.639416 kubelet[2367]: I1106 23:17:23.639294 2367 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 6 23:17:23.655522 kubelet[2367]: I1106 23:17:23.654700 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/e7e4f44f-ac73-4c51-a5fd-741239c018d9-run\") pod \"kube-flannel-ds-7gq84\" (UID: \"e7e4f44f-ac73-4c51-a5fd-741239c018d9\") " pod="kube-flannel/kube-flannel-ds-7gq84" Nov 6 23:17:23.655522 kubelet[2367]: I1106 23:17:23.654734 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/e7e4f44f-ac73-4c51-a5fd-741239c018d9-cni-plugin\") pod \"kube-flannel-ds-7gq84\" (UID: \"e7e4f44f-ac73-4c51-a5fd-741239c018d9\") " pod="kube-flannel/kube-flannel-ds-7gq84" Nov 6 23:17:23.655522 kubelet[2367]: I1106 23:17:23.654751 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/e7e4f44f-ac73-4c51-a5fd-741239c018d9-cni\") pod \"kube-flannel-ds-7gq84\" (UID: \"e7e4f44f-ac73-4c51-a5fd-741239c018d9\") " pod="kube-flannel/kube-flannel-ds-7gq84" Nov 6 23:17:23.655522 kubelet[2367]: I1106 23:17:23.654770 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/e7e4f44f-ac73-4c51-a5fd-741239c018d9-flannel-cfg\") pod \"kube-flannel-ds-7gq84\" (UID: \"e7e4f44f-ac73-4c51-a5fd-741239c018d9\") " pod="kube-flannel/kube-flannel-ds-7gq84" Nov 6 23:17:23.655522 kubelet[2367]: I1106 23:17:23.654784 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/e7e4f44f-ac73-4c51-a5fd-741239c018d9-xtables-lock\") pod \"kube-flannel-ds-7gq84\" (UID: \"e7e4f44f-ac73-4c51-a5fd-741239c018d9\") " pod="kube-flannel/kube-flannel-ds-7gq84" Nov 6 23:17:23.655851 kubelet[2367]: I1106 23:17:23.654799 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-shn8w\" (UniqueName: \"kubernetes.io/projected/e7e4f44f-ac73-4c51-a5fd-741239c018d9-kube-api-access-shn8w\") pod \"kube-flannel-ds-7gq84\" (UID: \"e7e4f44f-ac73-4c51-a5fd-741239c018d9\") " pod="kube-flannel/kube-flannel-ds-7gq84" Nov 6 23:17:23.659868 systemd[1]: Created slice kubepods-burstable-pode7e4f44f_ac73_4c51_a5fd_741239c018d9.slice - libcontainer container kubepods-burstable-pode7e4f44f_ac73_4c51_a5fd_741239c018d9.slice. Nov 6 23:17:23.672669 systemd[1]: Created slice kubepods-besteffort-pod3d15a641_6fb6_4b64_bfd8_82b364acec72.slice - libcontainer container kubepods-besteffort-pod3d15a641_6fb6_4b64_bfd8_82b364acec72.slice. Nov 6 23:17:23.749078 kubelet[2367]: I1106 23:17:23.749040 2367 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Nov 6 23:17:23.756037 kubelet[2367]: I1106 23:17:23.755138 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/3d15a641-6fb6-4b64-bfd8-82b364acec72-kube-proxy\") pod \"kube-proxy-9xwt7\" (UID: \"3d15a641-6fb6-4b64-bfd8-82b364acec72\") " pod="kube-system/kube-proxy-9xwt7" Nov 6 23:17:23.756037 kubelet[2367]: I1106 23:17:23.755169 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3d15a641-6fb6-4b64-bfd8-82b364acec72-xtables-lock\") pod \"kube-proxy-9xwt7\" (UID: \"3d15a641-6fb6-4b64-bfd8-82b364acec72\") " pod="kube-system/kube-proxy-9xwt7" Nov 6 23:17:23.756037 kubelet[2367]: I1106 23:17:23.755191 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/3d15a641-6fb6-4b64-bfd8-82b364acec72-lib-modules\") pod \"kube-proxy-9xwt7\" (UID: \"3d15a641-6fb6-4b64-bfd8-82b364acec72\") " pod="kube-system/kube-proxy-9xwt7" Nov 6 23:17:23.756037 kubelet[2367]: I1106 23:17:23.755212 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5b688\" (UniqueName: \"kubernetes.io/projected/3d15a641-6fb6-4b64-bfd8-82b364acec72-kube-api-access-5b688\") pod \"kube-proxy-9xwt7\" (UID: \"3d15a641-6fb6-4b64-bfd8-82b364acec72\") " pod="kube-system/kube-proxy-9xwt7" Nov 6 23:17:23.985283 containerd[1727]: time="2025-11-06T23:17:23.983083320Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7gq84,Uid:e7e4f44f-ac73-4c51-a5fd-741239c018d9,Namespace:kube-flannel,Attempt:0,}" Nov 6 23:17:24.001661 containerd[1727]: time="2025-11-06T23:17:24.001547960Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9xwt7,Uid:3d15a641-6fb6-4b64-bfd8-82b364acec72,Namespace:kube-system,Attempt:0,}" Nov 6 23:17:24.634719 kubelet[2367]: E1106 23:17:24.634689 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:24.714557 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1708699912.mount: Deactivated successfully. Nov 6 23:17:24.739277 containerd[1727]: time="2025-11-06T23:17:24.739227560Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:17:24.744187 containerd[1727]: time="2025-11-06T23:17:24.744140720Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Nov 6 23:17:24.746788 containerd[1727]: time="2025-11-06T23:17:24.746751400Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:17:24.750997 containerd[1727]: time="2025-11-06T23:17:24.750205000Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:17:24.752440 containerd[1727]: time="2025-11-06T23:17:24.752404600Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 6 23:17:24.756158 containerd[1727]: time="2025-11-06T23:17:24.756112200Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:17:24.757653 containerd[1727]: time="2025-11-06T23:17:24.757455360Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 774.28304ms" Nov 6 23:17:24.761129 containerd[1727]: time="2025-11-06T23:17:24.761099120Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 759.46924ms" Nov 6 23:17:25.248055 containerd[1727]: time="2025-11-06T23:17:25.247962200Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:17:25.248327 containerd[1727]: time="2025-11-06T23:17:25.248026800Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:17:25.248327 containerd[1727]: time="2025-11-06T23:17:25.248207440Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:25.248494 containerd[1727]: time="2025-11-06T23:17:25.248449560Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:25.254028 containerd[1727]: time="2025-11-06T23:17:25.252972160Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:17:25.254028 containerd[1727]: time="2025-11-06T23:17:25.253728480Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:17:25.254028 containerd[1727]: time="2025-11-06T23:17:25.253752120Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:25.254028 containerd[1727]: time="2025-11-06T23:17:25.253857520Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:25.541173 systemd[1]: Started cri-containerd-526c0288817533508442bd8d9ae2d6c3fcf5753c0613e6d1f4340eb55b2bac1a.scope - libcontainer container 526c0288817533508442bd8d9ae2d6c3fcf5753c0613e6d1f4340eb55b2bac1a. Nov 6 23:17:25.543361 systemd[1]: Started cri-containerd-5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e.scope - libcontainer container 5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e. Nov 6 23:17:25.577535 containerd[1727]: time="2025-11-06T23:17:25.577387400Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9xwt7,Uid:3d15a641-6fb6-4b64-bfd8-82b364acec72,Namespace:kube-system,Attempt:0,} returns sandbox id \"526c0288817533508442bd8d9ae2d6c3fcf5753c0613e6d1f4340eb55b2bac1a\"" Nov 6 23:17:25.582289 containerd[1727]: time="2025-11-06T23:17:25.582204360Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.1\"" Nov 6 23:17:25.586742 containerd[1727]: time="2025-11-06T23:17:25.586641960Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7gq84,Uid:e7e4f44f-ac73-4c51-a5fd-741239c018d9,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e\"" Nov 6 23:17:25.634961 kubelet[2367]: E1106 23:17:25.634912 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:26.560140 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3719472141.mount: Deactivated successfully. Nov 6 23:17:26.635890 kubelet[2367]: E1106 23:17:26.635855 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:26.812942 containerd[1727]: time="2025-11-06T23:17:26.812221560Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.34.1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:26.814838 containerd[1727]: time="2025-11-06T23:17:26.814797240Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.34.1: active requests=0, bytes read=22789028" Nov 6 23:17:26.817489 containerd[1727]: time="2025-11-06T23:17:26.817444720Z" level=info msg="ImageCreate event name:\"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:26.821531 containerd[1727]: time="2025-11-06T23:17:26.821483920Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:913cc83ca0b5588a81d86ce8eedeb3ed1e9c1326e81852a1ea4f622b74ff749a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:26.822334 containerd[1727]: time="2025-11-06T23:17:26.822190400Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.34.1\" with image id \"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\", repo tag \"registry.k8s.io/kube-proxy:v1.34.1\", repo digest \"registry.k8s.io/kube-proxy@sha256:913cc83ca0b5588a81d86ce8eedeb3ed1e9c1326e81852a1ea4f622b74ff749a\", size \"22788047\" in 1.23993168s" Nov 6 23:17:26.822334 containerd[1727]: time="2025-11-06T23:17:26.822227680Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.1\" returns image reference \"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\"" Nov 6 23:17:26.823818 containerd[1727]: time="2025-11-06T23:17:26.823433280Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Nov 6 23:17:26.830891 containerd[1727]: time="2025-11-06T23:17:26.830855600Z" level=info msg="CreateContainer within sandbox \"526c0288817533508442bd8d9ae2d6c3fcf5753c0613e6d1f4340eb55b2bac1a\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 6 23:17:26.856127 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3275455445.mount: Deactivated successfully. Nov 6 23:17:26.868637 containerd[1727]: time="2025-11-06T23:17:26.868596160Z" level=info msg="CreateContainer within sandbox \"526c0288817533508442bd8d9ae2d6c3fcf5753c0613e6d1f4340eb55b2bac1a\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"5be607e0c95bccaa43c0f535a2fcaf6cbb73335c88ed54190a56d9c390c2ba40\"" Nov 6 23:17:26.869508 containerd[1727]: time="2025-11-06T23:17:26.869484760Z" level=info msg="StartContainer for \"5be607e0c95bccaa43c0f535a2fcaf6cbb73335c88ed54190a56d9c390c2ba40\"" Nov 6 23:17:26.895222 systemd[1]: Started cri-containerd-5be607e0c95bccaa43c0f535a2fcaf6cbb73335c88ed54190a56d9c390c2ba40.scope - libcontainer container 5be607e0c95bccaa43c0f535a2fcaf6cbb73335c88ed54190a56d9c390c2ba40. Nov 6 23:17:26.926603 containerd[1727]: time="2025-11-06T23:17:26.926562880Z" level=info msg="StartContainer for \"5be607e0c95bccaa43c0f535a2fcaf6cbb73335c88ed54190a56d9c390c2ba40\" returns successfully" Nov 6 23:17:27.636045 kubelet[2367]: E1106 23:17:27.635990 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:28.035859 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4182107189.mount: Deactivated successfully. Nov 6 23:17:28.125516 containerd[1727]: time="2025-11-06T23:17:28.125468400Z" 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\"}" Nov 6 23:17:28.129841 containerd[1727]: time="2025-11-06T23:17:28.129792120Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Nov 6 23:17:28.133952 containerd[1727]: time="2025-11-06T23:17:28.133117880Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:28.138087 containerd[1727]: time="2025-11-06T23:17:28.138046760Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:28.139437 containerd[1727]: time="2025-11-06T23:17:28.139408160Z" 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 1.31594772s" Nov 6 23:17:28.139497 containerd[1727]: time="2025-11-06T23:17:28.139438440Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Nov 6 23:17:28.155833 containerd[1727]: time="2025-11-06T23:17:28.155794720Z" level=info msg="CreateContainer within sandbox \"5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 6 23:17:28.183846 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3171874119.mount: Deactivated successfully. Nov 6 23:17:28.193932 containerd[1727]: time="2025-11-06T23:17:28.193880720Z" level=info msg="CreateContainer within sandbox \"5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf\"" Nov 6 23:17:28.195126 containerd[1727]: time="2025-11-06T23:17:28.194361600Z" level=info msg="StartContainer for \"9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf\"" Nov 6 23:17:28.220083 systemd[1]: Started cri-containerd-9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf.scope - libcontainer container 9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf. Nov 6 23:17:28.241589 systemd[1]: cri-containerd-9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf.scope: Deactivated successfully. Nov 6 23:17:28.246692 containerd[1727]: time="2025-11-06T23:17:28.246654640Z" level=info msg="StartContainer for \"9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf\" returns successfully" Nov 6 23:17:28.636549 kubelet[2367]: E1106 23:17:28.636497 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:28.735358 kubelet[2367]: I1106 23:17:28.735194 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-9xwt7" podStartSLOduration=5.49323368 podStartE2EDuration="6.73517784s" podCreationTimestamp="2025-11-06 23:17:22 +0000 UTC" firstStartedPulling="2025-11-06 23:17:25.58127356 +0000 UTC m=+4.234892681" lastFinishedPulling="2025-11-06 23:17:26.82321772 +0000 UTC m=+5.476836841" observedRunningTime="2025-11-06 23:17:27.7314244 +0000 UTC m=+6.385043561" watchObservedRunningTime="2025-11-06 23:17:28.73517784 +0000 UTC m=+7.388797001" Nov 6 23:17:28.795412 containerd[1727]: time="2025-11-06T23:17:28.795351800Z" level=info msg="shim disconnected" id=9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf namespace=k8s.io Nov 6 23:17:28.795412 containerd[1727]: time="2025-11-06T23:17:28.795402240Z" level=warning msg="cleaning up after shim disconnected" id=9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf namespace=k8s.io Nov 6 23:17:28.795412 containerd[1727]: time="2025-11-06T23:17:28.795410520Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 6 23:17:28.961157 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9251db9b8d6b89af80179ae3627ec3664b0f3a0c8a028f77828b5a3fa2f798cf-rootfs.mount: Deactivated successfully. Nov 6 23:17:29.636982 kubelet[2367]: E1106 23:17:29.636941 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:29.725256 containerd[1727]: time="2025-11-06T23:17:29.725021240Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Nov 6 23:17:30.483035 chronyd[1706]: Selected source PHC0 Nov 6 23:17:30.637524 kubelet[2367]: E1106 23:17:30.637492 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:31.638580 kubelet[2367]: E1106 23:17:31.638542 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:31.774937 containerd[1727]: time="2025-11-06T23:17:31.774870849Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:31.778620 containerd[1727]: time="2025-11-06T23:17:31.778316169Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Nov 6 23:17:31.781584 containerd[1727]: time="2025-11-06T23:17:31.781535729Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:31.786961 containerd[1727]: time="2025-11-06T23:17:31.786903769Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:31.788431 containerd[1727]: time="2025-11-06T23:17:31.788316049Z" 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.063249089s" Nov 6 23:17:31.788431 containerd[1727]: time="2025-11-06T23:17:31.788347249Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Nov 6 23:17:31.799804 containerd[1727]: time="2025-11-06T23:17:31.799752689Z" level=info msg="CreateContainer within sandbox \"5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 6 23:17:31.830260 containerd[1727]: time="2025-11-06T23:17:31.830151849Z" level=info msg="CreateContainer within sandbox \"5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023\"" Nov 6 23:17:31.830847 containerd[1727]: time="2025-11-06T23:17:31.830726289Z" level=info msg="StartContainer for \"2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023\"" Nov 6 23:17:31.859147 systemd[1]: Started cri-containerd-2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023.scope - libcontainer container 2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023. Nov 6 23:17:31.882053 systemd[1]: cri-containerd-2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023.scope: Deactivated successfully. Nov 6 23:17:31.885693 containerd[1727]: time="2025-11-06T23:17:31.885475290Z" level=info msg="StartContainer for \"2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023\" returns successfully" Nov 6 23:17:31.900499 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023-rootfs.mount: Deactivated successfully. Nov 6 23:17:31.936388 kubelet[2367]: I1106 23:17:31.936358 2367 kubelet_node_status.go:439] "Fast updating node status as it just became ready" Nov 6 23:17:32.440406 containerd[1727]: time="2025-11-06T23:17:32.440315093Z" level=info msg="shim disconnected" id=2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023 namespace=k8s.io Nov 6 23:17:32.440406 containerd[1727]: time="2025-11-06T23:17:32.440366613Z" level=warning msg="cleaning up after shim disconnected" id=2ec90d4ccd5fbaee782e45cf9c3c6b5eb6e1122515974f2ded158ed3614a6023 namespace=k8s.io Nov 6 23:17:32.440406 containerd[1727]: time="2025-11-06T23:17:32.440375933Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 6 23:17:32.450691 containerd[1727]: time="2025-11-06T23:17:32.449907653Z" level=warning msg="cleanup warnings time=\"2025-11-06T23:17:32Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Nov 6 23:17:32.638863 kubelet[2367]: E1106 23:17:32.638818 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:32.735760 containerd[1727]: time="2025-11-06T23:17:32.735663213Z" level=info msg="CreateContainer within sandbox \"5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 6 23:17:32.770290 containerd[1727]: time="2025-11-06T23:17:32.770170013Z" level=info msg="CreateContainer within sandbox \"5a0987406a9fe388c569faa074481be93831196313d3ebe88b5fe871041a250e\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"8b5d964303c17488884dde4e79dd277eff91cf776f189aa9b57ce9ca5aa55f73\"" Nov 6 23:17:32.770653 containerd[1727]: time="2025-11-06T23:17:32.770635093Z" level=info msg="StartContainer for \"8b5d964303c17488884dde4e79dd277eff91cf776f189aa9b57ce9ca5aa55f73\"" Nov 6 23:17:32.791159 systemd[1]: Started cri-containerd-8b5d964303c17488884dde4e79dd277eff91cf776f189aa9b57ce9ca5aa55f73.scope - libcontainer container 8b5d964303c17488884dde4e79dd277eff91cf776f189aa9b57ce9ca5aa55f73. Nov 6 23:17:32.821498 containerd[1727]: time="2025-11-06T23:17:32.821193053Z" level=info msg="StartContainer for \"8b5d964303c17488884dde4e79dd277eff91cf776f189aa9b57ce9ca5aa55f73\" returns successfully" Nov 6 23:17:33.639409 kubelet[2367]: E1106 23:17:33.639376 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:33.745875 kubelet[2367]: I1106 23:17:33.745725 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7gq84" podStartSLOduration=5.544823604 podStartE2EDuration="11.745707453s" podCreationTimestamp="2025-11-06 23:17:22 +0000 UTC" firstStartedPulling="2025-11-06 23:17:25.58846144 +0000 UTC m=+4.242080601" lastFinishedPulling="2025-11-06 23:17:31.789345289 +0000 UTC m=+10.442964450" observedRunningTime="2025-11-06 23:17:33.745686533 +0000 UTC m=+12.399305694" watchObservedRunningTime="2025-11-06 23:17:33.745707453 +0000 UTC m=+12.399326614" Nov 6 23:17:33.954475 systemd-networkd[1341]: flannel.1: Link UP Nov 6 23:17:33.954482 systemd-networkd[1341]: flannel.1: Gained carrier Nov 6 23:17:34.640161 kubelet[2367]: E1106 23:17:34.640129 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:35.640565 kubelet[2367]: E1106 23:17:35.640524 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:35.909128 systemd-networkd[1341]: flannel.1: Gained IPv6LL Nov 6 23:17:36.640961 kubelet[2367]: E1106 23:17:36.640914 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:36.649403 systemd[1]: Created slice kubepods-besteffort-pod9528a8f8_277e_4ee1_9749_1453033d8dd6.slice - libcontainer container kubepods-besteffort-pod9528a8f8_277e_4ee1_9749_1453033d8dd6.slice. Nov 6 23:17:36.725480 kubelet[2367]: I1106 23:17:36.725439 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-v447t\" (UniqueName: \"kubernetes.io/projected/9528a8f8-277e-4ee1-9749-1453033d8dd6-kube-api-access-v447t\") pod \"nginx-deployment-bb8f74bfb-znzpr\" (UID: \"9528a8f8-277e-4ee1-9749-1453033d8dd6\") " pod="default/nginx-deployment-bb8f74bfb-znzpr" Nov 6 23:17:36.958931 containerd[1727]: time="2025-11-06T23:17:36.958560733Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-znzpr,Uid:9528a8f8-277e-4ee1-9749-1453033d8dd6,Namespace:default,Attempt:0,}" Nov 6 23:17:37.023074 systemd-networkd[1341]: cni0: Link UP Nov 6 23:17:37.023083 systemd-networkd[1341]: cni0: Gained carrier Nov 6 23:17:37.026086 systemd-networkd[1341]: cni0: Lost carrier Nov 6 23:17:37.051804 systemd-networkd[1341]: veth880b228e: Link UP Nov 6 23:17:37.059785 kernel: cni0: port 1(veth880b228e) entered blocking state Nov 6 23:17:37.059886 kernel: cni0: port 1(veth880b228e) entered disabled state Nov 6 23:17:37.064788 kernel: veth880b228e: entered allmulticast mode Nov 6 23:17:37.068184 kernel: veth880b228e: entered promiscuous mode Nov 6 23:17:37.072033 kernel: cni0: port 1(veth880b228e) entered blocking state Nov 6 23:17:37.072109 kernel: cni0: port 1(veth880b228e) entered forwarding state Nov 6 23:17:37.078867 kernel: cni0: port 1(veth880b228e) entered disabled state Nov 6 23:17:37.089855 kernel: cni0: port 1(veth880b228e) entered blocking state Nov 6 23:17:37.089973 kernel: cni0: port 1(veth880b228e) entered forwarding state Nov 6 23:17:37.090050 systemd-networkd[1341]: veth880b228e: Gained carrier Nov 6 23:17:37.090844 systemd-networkd[1341]: cni0: Gained carrier Nov 6 23:17:37.093279 containerd[1727]: 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)(0x4000084950), "name":"cbr0", "type":"bridge"} Nov 6 23:17:37.093279 containerd[1727]: delegateAdd: netconf sent to delegate plugin: Nov 6 23:17:37.112773 containerd[1727]: {"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-11-06T23:17:37.112412173Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:17:37.112773 containerd[1727]: time="2025-11-06T23:17:37.112459253Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:17:37.112773 containerd[1727]: time="2025-11-06T23:17:37.112473173Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:37.112773 containerd[1727]: time="2025-11-06T23:17:37.112543013Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:37.136155 systemd[1]: Started cri-containerd-d9935f3bbfae861ac60ca79a4aa4bd87434e578f0cd3b0e9adf3edf7359dda29.scope - libcontainer container d9935f3bbfae861ac60ca79a4aa4bd87434e578f0cd3b0e9adf3edf7359dda29. Nov 6 23:17:37.162914 containerd[1727]: time="2025-11-06T23:17:37.162874093Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-znzpr,Uid:9528a8f8-277e-4ee1-9749-1453033d8dd6,Namespace:default,Attempt:0,} returns sandbox id \"d9935f3bbfae861ac60ca79a4aa4bd87434e578f0cd3b0e9adf3edf7359dda29\"" Nov 6 23:17:37.164365 containerd[1727]: time="2025-11-06T23:17:37.164333893Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 6 23:17:37.641405 kubelet[2367]: E1106 23:17:37.641369 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:37.836282 systemd[1]: run-containerd-runc-k8s.io-d9935f3bbfae861ac60ca79a4aa4bd87434e578f0cd3b0e9adf3edf7359dda29-runc.lpVXHv.mount: Deactivated successfully. Nov 6 23:17:38.405051 systemd-networkd[1341]: veth880b228e: Gained IPv6LL Nov 6 23:17:38.642134 kubelet[2367]: E1106 23:17:38.642097 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:38.918094 systemd-networkd[1341]: cni0: Gained IPv6LL Nov 6 23:17:39.567294 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2232495385.mount: Deactivated successfully. Nov 6 23:17:39.642756 kubelet[2367]: E1106 23:17:39.642720 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:40.384410 containerd[1727]: time="2025-11-06T23:17:40.384366213Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:40.386838 containerd[1727]: time="2025-11-06T23:17:40.386776975Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 6 23:17:40.390239 containerd[1727]: time="2025-11-06T23:17:40.390214217Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:40.394645 containerd[1727]: time="2025-11-06T23:17:40.394615140Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:40.395487 containerd[1727]: time="2025-11-06T23:17:40.395454221Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 3.231088768s" Nov 6 23:17:40.395545 containerd[1727]: time="2025-11-06T23:17:40.395489381Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 6 23:17:40.405356 containerd[1727]: time="2025-11-06T23:17:40.405304228Z" level=info msg="CreateContainer within sandbox \"d9935f3bbfae861ac60ca79a4aa4bd87434e578f0cd3b0e9adf3edf7359dda29\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 6 23:17:40.432794 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2563533623.mount: Deactivated successfully. Nov 6 23:17:40.441124 containerd[1727]: time="2025-11-06T23:17:40.441082295Z" level=info msg="CreateContainer within sandbox \"d9935f3bbfae861ac60ca79a4aa4bd87434e578f0cd3b0e9adf3edf7359dda29\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"03ef0cfd2dccb64597096d3138d5c56fc9dd28bdcd995aa79bf9f63e7c881717\"" Nov 6 23:17:40.441882 containerd[1727]: time="2025-11-06T23:17:40.441841455Z" level=info msg="StartContainer for \"03ef0cfd2dccb64597096d3138d5c56fc9dd28bdcd995aa79bf9f63e7c881717\"" Nov 6 23:17:40.471136 systemd[1]: Started cri-containerd-03ef0cfd2dccb64597096d3138d5c56fc9dd28bdcd995aa79bf9f63e7c881717.scope - libcontainer container 03ef0cfd2dccb64597096d3138d5c56fc9dd28bdcd995aa79bf9f63e7c881717. Nov 6 23:17:40.499201 containerd[1727]: time="2025-11-06T23:17:40.499154138Z" level=info msg="StartContainer for \"03ef0cfd2dccb64597096d3138d5c56fc9dd28bdcd995aa79bf9f63e7c881717\" returns successfully" Nov 6 23:17:40.566471 systemd[1]: run-containerd-runc-k8s.io-03ef0cfd2dccb64597096d3138d5c56fc9dd28bdcd995aa79bf9f63e7c881717-runc.HW5Dfi.mount: Deactivated successfully. Nov 6 23:17:40.643468 kubelet[2367]: E1106 23:17:40.643363 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:40.756391 kubelet[2367]: I1106 23:17:40.756332 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-bb8f74bfb-znzpr" podStartSLOduration=1.522051318 podStartE2EDuration="4.756316768s" podCreationTimestamp="2025-11-06 23:17:36 +0000 UTC" firstStartedPulling="2025-11-06 23:17:37.163819653 +0000 UTC m=+15.817438814" lastFinishedPulling="2025-11-06 23:17:40.398085103 +0000 UTC m=+19.051704264" observedRunningTime="2025-11-06 23:17:40.755971448 +0000 UTC m=+19.409590609" watchObservedRunningTime="2025-11-06 23:17:40.756316768 +0000 UTC m=+19.409935929" Nov 6 23:17:41.644181 kubelet[2367]: E1106 23:17:41.644137 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:42.634122 kubelet[2367]: E1106 23:17:42.634088 2367 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:42.644457 kubelet[2367]: E1106 23:17:42.644438 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:43.645474 kubelet[2367]: E1106 23:17:43.645437 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:44.645679 kubelet[2367]: E1106 23:17:44.645638 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:45.646131 kubelet[2367]: E1106 23:17:45.646094 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:45.875663 systemd[1]: Created slice kubepods-besteffort-podc7a7d27c_e70e_4d6f_9c2e_a4eb4a9bdbdc.slice - libcontainer container kubepods-besteffort-podc7a7d27c_e70e_4d6f_9c2e_a4eb4a9bdbdc.slice. Nov 6 23:17:45.968453 kubelet[2367]: I1106 23:17:45.968240 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/c7a7d27c-e70e-4d6f-9c2e-a4eb4a9bdbdc-data\") pod \"nfs-server-provisioner-0\" (UID: \"c7a7d27c-e70e-4d6f-9c2e-a4eb4a9bdbdc\") " pod="default/nfs-server-provisioner-0" Nov 6 23:17:45.968453 kubelet[2367]: I1106 23:17:45.968299 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9w5dc\" (UniqueName: \"kubernetes.io/projected/c7a7d27c-e70e-4d6f-9c2e-a4eb4a9bdbdc-kube-api-access-9w5dc\") pod \"nfs-server-provisioner-0\" (UID: \"c7a7d27c-e70e-4d6f-9c2e-a4eb4a9bdbdc\") " pod="default/nfs-server-provisioner-0" Nov 6 23:17:46.184408 containerd[1727]: time="2025-11-06T23:17:46.184366646Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c7a7d27c-e70e-4d6f-9c2e-a4eb4a9bdbdc,Namespace:default,Attempt:0,}" Nov 6 23:17:46.235186 systemd-networkd[1341]: veth08c4f240: Link UP Nov 6 23:17:46.243602 kernel: cni0: port 2(veth08c4f240) entered blocking state Nov 6 23:17:46.243663 kernel: cni0: port 2(veth08c4f240) entered disabled state Nov 6 23:17:46.247010 kernel: veth08c4f240: entered allmulticast mode Nov 6 23:17:46.250432 kernel: veth08c4f240: entered promiscuous mode Nov 6 23:17:46.261402 kernel: cni0: port 2(veth08c4f240) entered blocking state Nov 6 23:17:46.261488 kernel: cni0: port 2(veth08c4f240) entered forwarding state Nov 6 23:17:46.261546 systemd-networkd[1341]: veth08c4f240: Gained carrier Nov 6 23:17:46.263624 containerd[1727]: 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"} Nov 6 23:17:46.263624 containerd[1727]: delegateAdd: netconf sent to delegate plugin: Nov 6 23:17:46.286590 containerd[1727]: {"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-11-06T23:17:46.286493167Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:17:46.286742 containerd[1727]: time="2025-11-06T23:17:46.286570447Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:17:46.286742 containerd[1727]: time="2025-11-06T23:17:46.286601847Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:46.287352 containerd[1727]: time="2025-11-06T23:17:46.287271927Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:46.307099 systemd[1]: Started cri-containerd-488cf62ee904d39dc3cb23057aa281f631d58c627e546fb445c1ca27f5df1555.scope - libcontainer container 488cf62ee904d39dc3cb23057aa281f631d58c627e546fb445c1ca27f5df1555. Nov 6 23:17:46.334806 containerd[1727]: time="2025-11-06T23:17:46.334764567Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c7a7d27c-e70e-4d6f-9c2e-a4eb4a9bdbdc,Namespace:default,Attempt:0,} returns sandbox id \"488cf62ee904d39dc3cb23057aa281f631d58c627e546fb445c1ca27f5df1555\"" Nov 6 23:17:46.336901 containerd[1727]: time="2025-11-06T23:17:46.336867127Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 6 23:17:46.647041 kubelet[2367]: E1106 23:17:46.646992 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:47.647494 kubelet[2367]: E1106 23:17:47.647439 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:47.921018 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Nov 6 23:17:48.069035 systemd-networkd[1341]: veth08c4f240: Gained IPv6LL Nov 6 23:17:48.521484 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount336494618.mount: Deactivated successfully. Nov 6 23:17:48.648850 kubelet[2367]: E1106 23:17:48.648812 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:49.649700 kubelet[2367]: E1106 23:17:49.649664 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:50.409452 containerd[1727]: time="2025-11-06T23:17:50.409402360Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:50.412715 containerd[1727]: time="2025-11-06T23:17:50.412509600Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 6 23:17:50.415448 containerd[1727]: time="2025-11-06T23:17:50.415425280Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:50.422528 containerd[1727]: time="2025-11-06T23:17:50.422470281Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:50.423587 containerd[1727]: time="2025-11-06T23:17:50.423465201Z" 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 4.086557194s" Nov 6 23:17:50.423587 containerd[1727]: time="2025-11-06T23:17:50.423495401Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 6 23:17:50.430402 containerd[1727]: time="2025-11-06T23:17:50.430364081Z" level=info msg="CreateContainer within sandbox \"488cf62ee904d39dc3cb23057aa281f631d58c627e546fb445c1ca27f5df1555\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 6 23:17:50.479848 containerd[1727]: time="2025-11-06T23:17:50.479725762Z" level=info msg="CreateContainer within sandbox \"488cf62ee904d39dc3cb23057aa281f631d58c627e546fb445c1ca27f5df1555\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"1e8a6c8a29af82325467c4962480420af4c99970982ccde96293fbef543a90a6\"" Nov 6 23:17:50.480542 containerd[1727]: time="2025-11-06T23:17:50.480515602Z" level=info msg="StartContainer for \"1e8a6c8a29af82325467c4962480420af4c99970982ccde96293fbef543a90a6\"" Nov 6 23:17:50.508079 systemd[1]: Started cri-containerd-1e8a6c8a29af82325467c4962480420af4c99970982ccde96293fbef543a90a6.scope - libcontainer container 1e8a6c8a29af82325467c4962480420af4c99970982ccde96293fbef543a90a6. Nov 6 23:17:50.534449 containerd[1727]: time="2025-11-06T23:17:50.534207923Z" level=info msg="StartContainer for \"1e8a6c8a29af82325467c4962480420af4c99970982ccde96293fbef543a90a6\" returns successfully" Nov 6 23:17:50.650793 kubelet[2367]: E1106 23:17:50.650720 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:50.777058 kubelet[2367]: I1106 23:17:50.776980 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.689068853 podStartE2EDuration="5.776961527s" podCreationTimestamp="2025-11-06 23:17:45 +0000 UTC" firstStartedPulling="2025-11-06 23:17:46.336370447 +0000 UTC m=+24.989989608" lastFinishedPulling="2025-11-06 23:17:50.424263161 +0000 UTC m=+29.077882282" observedRunningTime="2025-11-06 23:17:50.776949407 +0000 UTC m=+29.430568568" watchObservedRunningTime="2025-11-06 23:17:50.776961527 +0000 UTC m=+29.430580688" Nov 6 23:17:51.651434 kubelet[2367]: E1106 23:17:51.651398 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:52.304302 update_engine[1715]: I20251106 23:17:52.304224 1715 update_attempter.cc:509] Updating boot flags... Nov 6 23:17:52.385718 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (3345) Nov 6 23:17:52.498944 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (3347) Nov 6 23:17:52.652596 kubelet[2367]: E1106 23:17:52.652497 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:53.653703 kubelet[2367]: E1106 23:17:53.653654 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:54.653835 kubelet[2367]: E1106 23:17:54.653778 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:55.654716 kubelet[2367]: E1106 23:17:55.654682 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:55.977735 systemd[1]: Created slice kubepods-besteffort-pode2f83390_f417_49e6_b899_6bea48cf6a7d.slice - libcontainer container kubepods-besteffort-pode2f83390_f417_49e6_b899_6bea48cf6a7d.slice. Nov 6 23:17:56.120944 kubelet[2367]: I1106 23:17:56.120675 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-11833a0a-5e0b-4721-b0ff-7cfd3f47b1ac\" (UniqueName: \"kubernetes.io/nfs/e2f83390-f417-49e6-b899-6bea48cf6a7d-pvc-11833a0a-5e0b-4721-b0ff-7cfd3f47b1ac\") pod \"test-pod-1\" (UID: \"e2f83390-f417-49e6-b899-6bea48cf6a7d\") " pod="default/test-pod-1" Nov 6 23:17:56.120944 kubelet[2367]: I1106 23:17:56.120723 2367 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8h7wt\" (UniqueName: \"kubernetes.io/projected/e2f83390-f417-49e6-b899-6bea48cf6a7d-kube-api-access-8h7wt\") pod \"test-pod-1\" (UID: \"e2f83390-f417-49e6-b899-6bea48cf6a7d\") " pod="default/test-pod-1" Nov 6 23:17:56.447944 kernel: FS-Cache: Loaded Nov 6 23:17:56.536605 kernel: RPC: Registered named UNIX socket transport module. Nov 6 23:17:56.536707 kernel: RPC: Registered udp transport module. Nov 6 23:17:56.539710 kernel: RPC: Registered tcp transport module. Nov 6 23:17:56.539765 kernel: RPC: Registered tcp-with-tls transport module. Nov 6 23:17:56.547034 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 6 23:17:56.655523 kubelet[2367]: E1106 23:17:56.655484 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:56.808973 kernel: NFS: Registering the id_resolver key type Nov 6 23:17:56.809127 kernel: Key type id_resolver registered Nov 6 23:17:56.809162 kernel: Key type id_legacy registered Nov 6 23:17:57.108565 nfsidmap[3481]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.4-n-5fb6643642' Nov 6 23:17:57.142593 nfsidmap[3482]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.4-n-5fb6643642' Nov 6 23:17:57.190964 containerd[1727]: time="2025-11-06T23:17:57.190907506Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e2f83390-f417-49e6-b899-6bea48cf6a7d,Namespace:default,Attempt:0,}" Nov 6 23:17:57.243028 systemd-networkd[1341]: vethe3f016bc: Link UP Nov 6 23:17:57.252024 kernel: cni0: port 3(vethe3f016bc) entered blocking state Nov 6 23:17:57.252106 kernel: cni0: port 3(vethe3f016bc) entered disabled state Nov 6 23:17:57.256044 kernel: vethe3f016bc: entered allmulticast mode Nov 6 23:17:57.259080 kernel: vethe3f016bc: entered promiscuous mode Nov 6 23:17:57.269451 kernel: cni0: port 3(vethe3f016bc) entered blocking state Nov 6 23:17:57.269533 kernel: cni0: port 3(vethe3f016bc) entered forwarding state Nov 6 23:17:57.269516 systemd-networkd[1341]: vethe3f016bc: Gained carrier Nov 6 23:17:57.271748 containerd[1727]: 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)(0x40001047f0), "name":"cbr0", "type":"bridge"} Nov 6 23:17:57.271748 containerd[1727]: delegateAdd: netconf sent to delegate plugin: Nov 6 23:17:57.292091 containerd[1727]: {"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-11-06T23:17:57.292009710Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:17:57.292091 containerd[1727]: time="2025-11-06T23:17:57.292065790Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:17:57.292408 containerd[1727]: time="2025-11-06T23:17:57.292266470Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:57.292526 containerd[1727]: time="2025-11-06T23:17:57.292464030Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:17:57.316075 systemd[1]: Started cri-containerd-193d21f45927da7e538c6d8e1e266db2f88536590fae6b645850e6eef07b8374.scope - libcontainer container 193d21f45927da7e538c6d8e1e266db2f88536590fae6b645850e6eef07b8374. Nov 6 23:17:57.344045 containerd[1727]: time="2025-11-06T23:17:57.343898713Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e2f83390-f417-49e6-b899-6bea48cf6a7d,Namespace:default,Attempt:0,} returns sandbox id \"193d21f45927da7e538c6d8e1e266db2f88536590fae6b645850e6eef07b8374\"" Nov 6 23:17:57.345944 containerd[1727]: time="2025-11-06T23:17:57.345862353Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 6 23:17:57.656110 kubelet[2367]: E1106 23:17:57.656073 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:57.690472 containerd[1727]: time="2025-11-06T23:17:57.690423847Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:17:57.693834 containerd[1727]: time="2025-11-06T23:17:57.693253927Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 6 23:17:57.696006 containerd[1727]: time="2025-11-06T23:17:57.695974887Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 350.017054ms" Nov 6 23:17:57.696064 containerd[1727]: time="2025-11-06T23:17:57.696010807Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 6 23:17:57.703377 containerd[1727]: time="2025-11-06T23:17:57.703205567Z" level=info msg="CreateContainer within sandbox \"193d21f45927da7e538c6d8e1e266db2f88536590fae6b645850e6eef07b8374\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 6 23:17:57.748716 containerd[1727]: time="2025-11-06T23:17:57.748142729Z" level=info msg="CreateContainer within sandbox \"193d21f45927da7e538c6d8e1e266db2f88536590fae6b645850e6eef07b8374\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"8c9da7c91205abc7c5d4e731f8ec3a39b14ddc6667e18613f9ce683674ef0417\"" Nov 6 23:17:57.748844 containerd[1727]: time="2025-11-06T23:17:57.748794489Z" level=info msg="StartContainer for \"8c9da7c91205abc7c5d4e731f8ec3a39b14ddc6667e18613f9ce683674ef0417\"" Nov 6 23:17:57.772102 systemd[1]: Started cri-containerd-8c9da7c91205abc7c5d4e731f8ec3a39b14ddc6667e18613f9ce683674ef0417.scope - libcontainer container 8c9da7c91205abc7c5d4e731f8ec3a39b14ddc6667e18613f9ce683674ef0417. Nov 6 23:17:57.803210 containerd[1727]: time="2025-11-06T23:17:57.803095052Z" level=info msg="StartContainer for \"8c9da7c91205abc7c5d4e731f8ec3a39b14ddc6667e18613f9ce683674ef0417\" returns successfully" Nov 6 23:17:58.657051 kubelet[2367]: E1106 23:17:58.657015 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:17:58.949054 systemd-networkd[1341]: vethe3f016bc: Gained IPv6LL Nov 6 23:17:59.657144 kubelet[2367]: E1106 23:17:59.657106 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:00.658019 kubelet[2367]: E1106 23:18:00.657977 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:01.658463 kubelet[2367]: E1106 23:18:01.658434 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:02.634205 kubelet[2367]: E1106 23:18:02.634170 2367 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:02.659486 kubelet[2367]: E1106 23:18:02.659470 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"