Dec 12 17:40:11.028148 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Dec 12 17:40:11.028166 kernel: Linux version 6.12.61-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT Fri Dec 12 15:20:48 -00 2025 Dec 12 17:40:11.028172 kernel: KASLR enabled Dec 12 17:40:11.028176 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Dec 12 17:40:11.028180 kernel: printk: legacy bootconsole [pl11] enabled Dec 12 17:40:11.028185 kernel: efi: EFI v2.7 by EDK II Dec 12 17:40:11.028190 kernel: efi: ACPI 2.0=0x3f979018 SMBIOS=0x3f8a0000 SMBIOS 3.0=0x3f880000 MEMATTR=0x3e89d018 RNG=0x3f979998 MEMRESERVE=0x3db7d598 Dec 12 17:40:11.028194 kernel: random: crng init done Dec 12 17:40:11.028198 kernel: secureboot: Secure boot disabled Dec 12 17:40:11.028202 kernel: ACPI: Early table checksum verification disabled Dec 12 17:40:11.028206 kernel: ACPI: RSDP 0x000000003F979018 000024 (v02 VRTUAL) Dec 12 17:40:11.028209 kernel: ACPI: XSDT 0x000000003F979F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028213 kernel: ACPI: FACP 0x000000003F979C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028217 kernel: ACPI: DSDT 0x000000003F95A018 01E046 (v02 MSFTVM DSDT01 00000001 INTL 20230628) Dec 12 17:40:11.028223 kernel: ACPI: DBG2 0x000000003F979B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028228 kernel: ACPI: GTDT 0x000000003F979D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028232 kernel: ACPI: OEM0 0x000000003F979098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028236 kernel: ACPI: SPCR 0x000000003F979A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028240 kernel: ACPI: APIC 0x000000003F979818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028245 kernel: ACPI: SRAT 0x000000003F979198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028250 kernel: ACPI: PPTT 0x000000003F979418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Dec 12 17:40:11.028254 kernel: ACPI: BGRT 0x000000003F979E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 17:40:11.028258 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Dec 12 17:40:11.028262 kernel: ACPI: Use ACPI SPCR as default console: Yes Dec 12 17:40:11.028266 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Dec 12 17:40:11.028271 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Dec 12 17:40:11.028275 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Dec 12 17:40:11.028279 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Dec 12 17:40:11.028283 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Dec 12 17:40:11.028287 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Dec 12 17:40:11.028292 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Dec 12 17:40:11.028297 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Dec 12 17:40:11.028301 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Dec 12 17:40:11.028305 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Dec 12 17:40:11.028309 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Dec 12 17:40:11.028313 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Dec 12 17:40:11.028318 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Dec 12 17:40:11.028322 kernel: NODE_DATA(0) allocated [mem 0x1bf7ffa00-0x1bf806fff] Dec 12 17:40:11.028326 kernel: Zone ranges: Dec 12 17:40:11.028330 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Dec 12 17:40:11.028337 kernel: DMA32 empty Dec 12 17:40:11.028341 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Dec 12 17:40:11.028346 kernel: Device empty Dec 12 17:40:11.028350 kernel: Movable zone start for each node Dec 12 17:40:11.028354 kernel: Early memory node ranges Dec 12 17:40:11.028359 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Dec 12 17:40:11.028364 kernel: node 0: [mem 0x0000000000824000-0x000000003f38ffff] Dec 12 17:40:11.028368 kernel: node 0: [mem 0x000000003f390000-0x000000003f93ffff] Dec 12 17:40:11.028372 kernel: node 0: [mem 0x000000003f940000-0x000000003f9effff] Dec 12 17:40:11.028377 kernel: node 0: [mem 0x000000003f9f0000-0x000000003fdeffff] Dec 12 17:40:11.028381 kernel: node 0: [mem 0x000000003fdf0000-0x000000003fffffff] Dec 12 17:40:11.028386 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Dec 12 17:40:11.028390 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Dec 12 17:40:11.028394 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Dec 12 17:40:11.028399 kernel: cma: Reserved 16 MiB at 0x000000003ca00000 on node -1 Dec 12 17:40:11.028403 kernel: psci: probing for conduit method from ACPI. Dec 12 17:40:11.028407 kernel: psci: PSCIv1.3 detected in firmware. Dec 12 17:40:11.028412 kernel: psci: Using standard PSCI v0.2 function IDs Dec 12 17:40:11.028417 kernel: psci: MIGRATE_INFO_TYPE not supported. Dec 12 17:40:11.028421 kernel: psci: SMC Calling Convention v1.4 Dec 12 17:40:11.028425 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Dec 12 17:40:11.028430 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Dec 12 17:40:11.028434 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Dec 12 17:40:11.028438 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Dec 12 17:40:11.028443 kernel: pcpu-alloc: [0] 0 [0] 1 Dec 12 17:40:11.028447 kernel: Detected PIPT I-cache on CPU0 Dec 12 17:40:11.028452 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Dec 12 17:40:11.028456 kernel: CPU features: detected: GIC system register CPU interface Dec 12 17:40:11.028460 kernel: CPU features: detected: Spectre-v4 Dec 12 17:40:11.028465 kernel: CPU features: detected: Spectre-BHB Dec 12 17:40:11.028470 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 12 17:40:11.028474 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 12 17:40:11.028479 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Dec 12 17:40:11.028483 kernel: CPU features: detected: SSBS not fully self-synchronizing Dec 12 17:40:11.028487 kernel: alternatives: applying boot alternatives Dec 12 17:40:11.028493 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=361f5baddf90aee3bc7ee7e9be879bc0cc94314f224faa1e2791d9b44cd3ec52 Dec 12 17:40:11.028497 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 12 17:40:11.028502 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 12 17:40:11.028506 kernel: Fallback order for Node 0: 0 Dec 12 17:40:11.028510 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Dec 12 17:40:11.028515 kernel: Policy zone: Normal Dec 12 17:40:11.028520 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 12 17:40:11.028524 kernel: software IO TLB: area num 2. Dec 12 17:40:11.028529 kernel: software IO TLB: mapped [mem 0x0000000035900000-0x0000000039900000] (64MB) Dec 12 17:40:11.028533 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Dec 12 17:40:11.028537 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 12 17:40:11.028542 kernel: rcu: RCU event tracing is enabled. Dec 12 17:40:11.028547 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Dec 12 17:40:11.028551 kernel: Trampoline variant of Tasks RCU enabled. Dec 12 17:40:11.028555 kernel: Tracing variant of Tasks RCU enabled. Dec 12 17:40:11.028560 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 12 17:40:11.028564 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Dec 12 17:40:11.028569 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Dec 12 17:40:11.028574 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Dec 12 17:40:11.028578 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 12 17:40:11.028583 kernel: GICv3: 960 SPIs implemented Dec 12 17:40:11.028587 kernel: GICv3: 0 Extended SPIs implemented Dec 12 17:40:11.028591 kernel: Root IRQ handler: gic_handle_irq Dec 12 17:40:11.028596 kernel: GICv3: GICv3 features: 16 PPIs, RSS Dec 12 17:40:11.028600 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Dec 12 17:40:11.028605 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Dec 12 17:40:11.028609 kernel: ITS: No ITS available, not enabling LPIs Dec 12 17:40:11.028613 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 12 17:40:11.028619 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Dec 12 17:40:11.028623 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Dec 12 17:40:11.028628 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Dec 12 17:40:11.028632 kernel: Console: colour dummy device 80x25 Dec 12 17:40:11.028637 kernel: printk: legacy console [tty1] enabled Dec 12 17:40:11.028641 kernel: ACPI: Core revision 20240827 Dec 12 17:40:11.028646 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Dec 12 17:40:11.028651 kernel: pid_max: default: 32768 minimum: 301 Dec 12 17:40:11.028655 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Dec 12 17:40:11.028660 kernel: landlock: Up and running. Dec 12 17:40:11.028665 kernel: SELinux: Initializing. Dec 12 17:40:11.028670 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 12 17:40:11.028674 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 12 17:40:11.028679 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0xa0000e, misc 0x31e1 Dec 12 17:40:11.028684 kernel: Hyper-V: Host Build 10.0.26102.1141-1-0 Dec 12 17:40:11.028691 kernel: Hyper-V: enabling crash_kexec_post_notifiers Dec 12 17:40:11.028697 kernel: rcu: Hierarchical SRCU implementation. Dec 12 17:40:11.028701 kernel: rcu: Max phase no-delay instances is 400. Dec 12 17:40:11.028706 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Dec 12 17:40:11.028711 kernel: Remapping and enabling EFI services. Dec 12 17:40:11.028715 kernel: smp: Bringing up secondary CPUs ... Dec 12 17:40:11.028720 kernel: Detected PIPT I-cache on CPU1 Dec 12 17:40:11.028726 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Dec 12 17:40:11.028730 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Dec 12 17:40:11.028735 kernel: smp: Brought up 1 node, 2 CPUs Dec 12 17:40:11.028740 kernel: SMP: Total of 2 processors activated. Dec 12 17:40:11.028745 kernel: CPU: All CPU(s) started at EL1 Dec 12 17:40:11.028750 kernel: CPU features: detected: 32-bit EL0 Support Dec 12 17:40:11.028755 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Dec 12 17:40:11.028760 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Dec 12 17:40:11.028765 kernel: CPU features: detected: Common not Private translations Dec 12 17:40:11.028769 kernel: CPU features: detected: CRC32 instructions Dec 12 17:40:11.028774 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Dec 12 17:40:11.028779 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Dec 12 17:40:11.028784 kernel: CPU features: detected: LSE atomic instructions Dec 12 17:40:11.028789 kernel: CPU features: detected: Privileged Access Never Dec 12 17:40:11.028794 kernel: CPU features: detected: Speculation barrier (SB) Dec 12 17:40:11.028799 kernel: CPU features: detected: TLB range maintenance instructions Dec 12 17:40:11.028803 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Dec 12 17:40:11.028808 kernel: CPU features: detected: Scalable Vector Extension Dec 12 17:40:11.028813 kernel: alternatives: applying system-wide alternatives Dec 12 17:40:11.028818 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Dec 12 17:40:11.028822 kernel: SVE: maximum available vector length 16 bytes per vector Dec 12 17:40:11.028827 kernel: SVE: default vector length 16 bytes per vector Dec 12 17:40:11.028832 kernel: Memory: 3952828K/4194160K available (11200K kernel code, 2456K rwdata, 9084K rodata, 39552K init, 1038K bss, 220144K reserved, 16384K cma-reserved) Dec 12 17:40:11.028838 kernel: devtmpfs: initialized Dec 12 17:40:11.028842 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 12 17:40:11.028847 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Dec 12 17:40:11.028852 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Dec 12 17:40:11.028857 kernel: 0 pages in range for non-PLT usage Dec 12 17:40:11.028861 kernel: 508400 pages in range for PLT usage Dec 12 17:40:11.028866 kernel: pinctrl core: initialized pinctrl subsystem Dec 12 17:40:11.028871 kernel: SMBIOS 3.1.0 present. Dec 12 17:40:11.028876 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 06/10/2025 Dec 12 17:40:11.028881 kernel: DMI: Memory slots populated: 2/2 Dec 12 17:40:11.028886 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 12 17:40:11.028890 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Dec 12 17:40:11.028895 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 12 17:40:11.028900 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 12 17:40:11.028905 kernel: audit: initializing netlink subsys (disabled) Dec 12 17:40:11.028910 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Dec 12 17:40:11.028914 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 12 17:40:11.028920 kernel: cpuidle: using governor menu Dec 12 17:40:11.028924 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 12 17:40:11.028929 kernel: ASID allocator initialised with 32768 entries Dec 12 17:40:11.028934 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 12 17:40:11.028939 kernel: Serial: AMBA PL011 UART driver Dec 12 17:40:11.028943 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Dec 12 17:40:11.028948 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Dec 12 17:40:11.028953 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Dec 12 17:40:11.028958 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Dec 12 17:40:11.028963 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Dec 12 17:40:11.028968 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Dec 12 17:40:11.028973 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Dec 12 17:40:11.028977 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Dec 12 17:40:11.028982 kernel: ACPI: Added _OSI(Module Device) Dec 12 17:40:11.028987 kernel: ACPI: Added _OSI(Processor Device) Dec 12 17:40:11.028991 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 12 17:40:11.028996 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 12 17:40:11.029001 kernel: ACPI: Interpreter enabled Dec 12 17:40:11.029006 kernel: ACPI: Using GIC for interrupt routing Dec 12 17:40:11.029011 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Dec 12 17:40:11.029016 kernel: printk: legacy console [ttyAMA0] enabled Dec 12 17:40:11.029021 kernel: printk: legacy bootconsole [pl11] disabled Dec 12 17:40:11.029025 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Dec 12 17:40:11.029030 kernel: ACPI: CPU0 has been hot-added Dec 12 17:40:11.029035 kernel: ACPI: CPU1 has been hot-added Dec 12 17:40:11.029039 kernel: iommu: Default domain type: Translated Dec 12 17:40:11.029044 kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 12 17:40:11.029050 kernel: efivars: Registered efivars operations Dec 12 17:40:11.029054 kernel: vgaarb: loaded Dec 12 17:40:11.029059 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 12 17:40:11.029064 kernel: VFS: Disk quotas dquot_6.6.0 Dec 12 17:40:11.029068 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 12 17:40:11.029073 kernel: pnp: PnP ACPI init Dec 12 17:40:11.029077 kernel: pnp: PnP ACPI: found 0 devices Dec 12 17:40:11.029082 kernel: NET: Registered PF_INET protocol family Dec 12 17:40:11.029087 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 12 17:40:11.029092 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 12 17:40:11.029097 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 12 17:40:11.029102 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 12 17:40:11.029107 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Dec 12 17:40:11.029111 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 12 17:40:11.029116 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 12 17:40:11.029121 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 12 17:40:11.029125 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 12 17:40:11.029130 kernel: PCI: CLS 0 bytes, default 64 Dec 12 17:40:11.029135 kernel: kvm [1]: HYP mode not available Dec 12 17:40:11.029146 kernel: Initialise system trusted keyrings Dec 12 17:40:11.029150 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Dec 12 17:40:11.029155 kernel: Key type asymmetric registered Dec 12 17:40:11.029160 kernel: Asymmetric key parser 'x509' registered Dec 12 17:40:11.029165 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Dec 12 17:40:11.029169 kernel: io scheduler mq-deadline registered Dec 12 17:40:11.029174 kernel: io scheduler kyber registered Dec 12 17:40:11.029179 kernel: io scheduler bfq registered Dec 12 17:40:11.029183 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 12 17:40:11.029189 kernel: thunder_xcv, ver 1.0 Dec 12 17:40:11.029194 kernel: thunder_bgx, ver 1.0 Dec 12 17:40:11.029199 kernel: nicpf, ver 1.0 Dec 12 17:40:11.029203 kernel: nicvf, ver 1.0 Dec 12 17:40:11.029296 kernel: rtc-efi rtc-efi.0: registered as rtc0 Dec 12 17:40:11.029345 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-12-12T17:40:10 UTC (1765561210) Dec 12 17:40:11.029351 kernel: efifb: probing for efifb Dec 12 17:40:11.029358 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Dec 12 17:40:11.029362 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Dec 12 17:40:11.029367 kernel: efifb: scrolling: redraw Dec 12 17:40:11.029372 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Dec 12 17:40:11.029377 kernel: Console: switching to colour frame buffer device 128x48 Dec 12 17:40:11.029381 kernel: fb0: EFI VGA frame buffer device Dec 12 17:40:11.029386 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Dec 12 17:40:11.029391 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 12 17:40:11.029396 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Dec 12 17:40:11.029402 kernel: NET: Registered PF_INET6 protocol family Dec 12 17:40:11.029406 kernel: watchdog: NMI not fully supported Dec 12 17:40:11.029411 kernel: watchdog: Hard watchdog permanently disabled Dec 12 17:40:11.029416 kernel: Segment Routing with IPv6 Dec 12 17:40:11.029420 kernel: In-situ OAM (IOAM) with IPv6 Dec 12 17:40:11.029425 kernel: NET: Registered PF_PACKET protocol family Dec 12 17:40:11.029430 kernel: Key type dns_resolver registered Dec 12 17:40:11.029434 kernel: registered taskstats version 1 Dec 12 17:40:11.029439 kernel: Loading compiled-in X.509 certificates Dec 12 17:40:11.029444 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.61-flatcar: 92f3a94fb747a7ba7cbcfde1535be91b86f9429a' Dec 12 17:40:11.029449 kernel: Demotion targets for Node 0: null Dec 12 17:40:11.029454 kernel: Key type .fscrypt registered Dec 12 17:40:11.029459 kernel: Key type fscrypt-provisioning registered Dec 12 17:40:11.029463 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 12 17:40:11.029468 kernel: ima: Allocated hash algorithm: sha1 Dec 12 17:40:11.029473 kernel: ima: No architecture policies found Dec 12 17:40:11.029477 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Dec 12 17:40:11.029482 kernel: clk: Disabling unused clocks Dec 12 17:40:11.029487 kernel: PM: genpd: Disabling unused power domains Dec 12 17:40:11.029492 kernel: Warning: unable to open an initial console. Dec 12 17:40:11.029497 kernel: Freeing unused kernel memory: 39552K Dec 12 17:40:11.029502 kernel: Run /init as init process Dec 12 17:40:11.029506 kernel: with arguments: Dec 12 17:40:11.029511 kernel: /init Dec 12 17:40:11.029516 kernel: with environment: Dec 12 17:40:11.029520 kernel: HOME=/ Dec 12 17:40:11.029525 kernel: TERM=linux Dec 12 17:40:11.029530 systemd[1]: Successfully made /usr/ read-only. Dec 12 17:40:11.029538 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) Dec 12 17:40:11.029543 systemd[1]: Detected virtualization microsoft. Dec 12 17:40:11.029548 systemd[1]: Detected architecture arm64. Dec 12 17:40:11.029553 systemd[1]: Running in initrd. Dec 12 17:40:11.029558 systemd[1]: No hostname configured, using default hostname. Dec 12 17:40:11.029563 systemd[1]: Hostname set to . Dec 12 17:40:11.029568 systemd[1]: Initializing machine ID from random generator. Dec 12 17:40:11.029574 systemd[1]: Queued start job for default target initrd.target. Dec 12 17:40:11.029580 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 17:40:11.029585 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 17:40:11.029590 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Dec 12 17:40:11.029596 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 12 17:40:11.029601 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Dec 12 17:40:11.029606 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Dec 12 17:40:11.029613 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Dec 12 17:40:11.029618 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Dec 12 17:40:11.029623 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 17:40:11.029628 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 12 17:40:11.029633 systemd[1]: Reached target paths.target - Path Units. Dec 12 17:40:11.029639 systemd[1]: Reached target slices.target - Slice Units. Dec 12 17:40:11.029644 systemd[1]: Reached target swap.target - Swaps. Dec 12 17:40:11.029649 systemd[1]: Reached target timers.target - Timer Units. Dec 12 17:40:11.029655 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Dec 12 17:40:11.029660 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 12 17:40:11.029665 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 12 17:40:11.029670 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Dec 12 17:40:11.029675 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 12 17:40:11.029680 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 12 17:40:11.029686 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 17:40:11.029691 systemd[1]: Reached target sockets.target - Socket Units. Dec 12 17:40:11.029696 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Dec 12 17:40:11.029702 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 12 17:40:11.029707 systemd[1]: Finished network-cleanup.service - Network Cleanup. Dec 12 17:40:11.029712 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Dec 12 17:40:11.029717 systemd[1]: Starting systemd-fsck-usr.service... Dec 12 17:40:11.029723 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 12 17:40:11.029728 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 12 17:40:11.029742 systemd-journald[225]: Collecting audit messages is disabled. Dec 12 17:40:11.029757 systemd-journald[225]: Journal started Dec 12 17:40:11.029770 systemd-journald[225]: Runtime Journal (/run/log/journal/507670268ce5446081f5b82b5a4072d5) is 8M, max 78.3M, 70.3M free. Dec 12 17:40:11.048931 systemd-modules-load[227]: Inserted module 'overlay' Dec 12 17:40:11.053323 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 17:40:11.070206 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 12 17:40:11.070229 systemd[1]: Started systemd-journald.service - Journal Service. Dec 12 17:40:11.077022 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Dec 12 17:40:11.085442 kernel: Bridge firewalling registered Dec 12 17:40:11.077461 systemd-modules-load[227]: Inserted module 'br_netfilter' Dec 12 17:40:11.088877 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 17:40:11.094215 systemd[1]: Finished systemd-fsck-usr.service. Dec 12 17:40:11.102086 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 12 17:40:11.109348 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 17:40:11.118809 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 12 17:40:11.141710 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 12 17:40:11.151219 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 12 17:40:11.160546 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 12 17:40:11.171988 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 12 17:40:11.186258 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 12 17:40:11.192191 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 12 17:40:11.205184 systemd-tmpfiles[250]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Dec 12 17:40:11.208131 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 17:40:11.220341 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Dec 12 17:40:11.247830 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 12 17:40:11.253986 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 12 17:40:11.274884 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 17:40:11.285237 dracut-cmdline[262]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=361f5baddf90aee3bc7ee7e9be879bc0cc94314f224faa1e2791d9b44cd3ec52 Dec 12 17:40:11.317855 systemd-resolved[264]: Positive Trust Anchors: Dec 12 17:40:11.317870 systemd-resolved[264]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 12 17:40:11.317889 systemd-resolved[264]: 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 Dec 12 17:40:11.319426 systemd-resolved[264]: Defaulting to hostname 'linux'. Dec 12 17:40:11.321036 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 12 17:40:11.328244 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 12 17:40:11.418153 kernel: SCSI subsystem initialized Dec 12 17:40:11.426154 kernel: Loading iSCSI transport class v2.0-870. Dec 12 17:40:11.431153 kernel: iscsi: registered transport (tcp) Dec 12 17:40:11.443761 kernel: iscsi: registered transport (qla4xxx) Dec 12 17:40:11.443787 kernel: QLogic iSCSI HBA Driver Dec 12 17:40:11.455738 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 12 17:40:11.473017 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 17:40:11.484598 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 12 17:40:11.524215 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Dec 12 17:40:11.532239 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Dec 12 17:40:11.585369 kernel: raid6: neonx8 gen() 18528 MB/s Dec 12 17:40:11.603165 kernel: raid6: neonx4 gen() 18048 MB/s Dec 12 17:40:11.622147 kernel: raid6: neonx2 gen() 17085 MB/s Dec 12 17:40:11.642146 kernel: raid6: neonx1 gen() 15070 MB/s Dec 12 17:40:11.661145 kernel: raid6: int64x8 gen() 10521 MB/s Dec 12 17:40:11.680145 kernel: raid6: int64x4 gen() 10614 MB/s Dec 12 17:40:11.700221 kernel: raid6: int64x2 gen() 8988 MB/s Dec 12 17:40:11.721325 kernel: raid6: int64x1 gen() 7025 MB/s Dec 12 17:40:11.721379 kernel: raid6: using algorithm neonx8 gen() 18528 MB/s Dec 12 17:40:11.744494 kernel: raid6: .... xor() 14909 MB/s, rmw enabled Dec 12 17:40:11.744536 kernel: raid6: using neon recovery algorithm Dec 12 17:40:11.752099 kernel: xor: measuring software checksum speed Dec 12 17:40:11.752157 kernel: 8regs : 28643 MB/sec Dec 12 17:40:11.754545 kernel: 32regs : 28797 MB/sec Dec 12 17:40:11.757052 kernel: arm64_neon : 37714 MB/sec Dec 12 17:40:11.759968 kernel: xor: using function: arm64_neon (37714 MB/sec) Dec 12 17:40:11.798208 kernel: Btrfs loaded, zoned=no, fsverity=no Dec 12 17:40:11.802644 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Dec 12 17:40:11.812267 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 17:40:11.837585 systemd-udevd[475]: Using default interface naming scheme 'v255'. Dec 12 17:40:11.841435 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 17:40:11.851244 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Dec 12 17:40:11.884910 dracut-pre-trigger[485]: rd.md=0: removing MD RAID activation Dec 12 17:40:11.902656 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Dec 12 17:40:11.908235 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 12 17:40:11.960710 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 17:40:11.973591 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Dec 12 17:40:12.028165 kernel: hv_vmbus: Vmbus version:5.3 Dec 12 17:40:12.048456 kernel: hv_vmbus: registering driver hyperv_keyboard Dec 12 17:40:12.048485 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 12 17:40:12.048493 kernel: hv_vmbus: registering driver hid_hyperv Dec 12 17:40:12.048500 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 12 17:40:12.040558 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 17:40:12.064415 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Dec 12 17:40:12.040637 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 17:40:12.068540 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Dec 12 17:40:12.081185 kernel: hv_vmbus: registering driver hv_netvsc Dec 12 17:40:12.058542 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 17:40:12.097504 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Dec 12 17:40:12.097520 kernel: PTP clock support registered Dec 12 17:40:12.089615 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 17:40:12.320210 kernel: hv_utils: Registering HyperV Utility Driver Dec 12 17:40:12.320223 kernel: hv_vmbus: registering driver hv_utils Dec 12 17:40:12.320230 kernel: hv_utils: Heartbeat IC version 3.0 Dec 12 17:40:12.320236 kernel: hv_utils: Shutdown IC version 3.2 Dec 12 17:40:12.320243 kernel: hv_utils: TimeSync IC version 4.0 Dec 12 17:40:12.320249 kernel: hv_vmbus: registering driver hv_storvsc Dec 12 17:40:12.320255 kernel: scsi host1: storvsc_host_t Dec 12 17:40:12.320372 kernel: scsi host0: storvsc_host_t Dec 12 17:40:12.320437 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Dec 12 17:40:12.320504 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Dec 12 17:40:12.104806 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 12 17:40:12.115208 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 17:40:12.367640 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Dec 12 17:40:12.367769 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Dec 12 17:40:12.367838 kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 12 17:40:12.367900 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Dec 12 17:40:12.367960 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Dec 12 17:40:12.368033 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#61 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 17:40:12.368104 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#4 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 17:40:12.368157 kernel: hv_netvsc 00224876-ff13-0022-4876-ff1300224876 eth0: VF slot 1 added Dec 12 17:40:12.368211 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 12 17:40:12.115282 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 17:40:12.377415 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Dec 12 17:40:12.300177 systemd-resolved[264]: Clock change detected. Flushing caches. Dec 12 17:40:12.342188 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 17:40:12.397835 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Dec 12 17:40:12.397963 kernel: hv_vmbus: registering driver hv_pci Dec 12 17:40:12.397975 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Dec 12 17:40:12.397982 kernel: hv_pci 1ddeef5a-dc98-4f4e-b196-5737b7594940: PCI VMBus probing: Using version 0x10004 Dec 12 17:40:12.404027 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Dec 12 17:40:12.424597 kernel: hv_pci 1ddeef5a-dc98-4f4e-b196-5737b7594940: PCI host bridge to bus dc98:00 Dec 12 17:40:12.424705 kernel: pci_bus dc98:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Dec 12 17:40:12.425280 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 17:40:12.448104 kernel: pci_bus dc98:00: No busn resource found for root bus, will use [bus 00-ff] Dec 12 17:40:12.450100 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#192 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Dec 12 17:40:12.450175 kernel: pci dc98:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Dec 12 17:40:12.450193 kernel: pci dc98:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Dec 12 17:40:12.463055 kernel: pci dc98:00:02.0: enabling Extended Tags Dec 12 17:40:12.471031 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#228 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Dec 12 17:40:12.471153 kernel: pci dc98:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at dc98:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Dec 12 17:40:12.489729 kernel: pci_bus dc98:00: busn_res: [bus 00-ff] end is updated to 00 Dec 12 17:40:12.489848 kernel: pci dc98:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Dec 12 17:40:12.546529 kernel: mlx5_core dc98:00:02.0: enabling device (0000 -> 0002) Dec 12 17:40:12.554355 kernel: mlx5_core dc98:00:02.0: PTM is not supported by PCIe Dec 12 17:40:12.554504 kernel: mlx5_core dc98:00:02.0: firmware version: 16.30.5006 Dec 12 17:40:12.720936 kernel: hv_netvsc 00224876-ff13-0022-4876-ff1300224876 eth0: VF registering: eth1 Dec 12 17:40:12.721101 kernel: mlx5_core dc98:00:02.0 eth1: joined to eth0 Dec 12 17:40:12.726377 kernel: mlx5_core dc98:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Dec 12 17:40:12.736034 kernel: mlx5_core dc98:00:02.0 enP56472s1: renamed from eth1 Dec 12 17:40:12.988103 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Dec 12 17:40:13.151733 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Dec 12 17:40:13.161721 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Dec 12 17:40:13.180170 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Dec 12 17:40:13.185276 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Dec 12 17:40:13.200499 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 12 17:40:13.214076 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 12 17:40:13.223693 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 17:40:13.228534 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 12 17:40:13.237255 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Dec 12 17:40:13.260553 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Dec 12 17:40:13.280052 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#44 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 17:40:13.283740 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Dec 12 17:40:13.297054 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 12 17:40:14.306584 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#199 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 17:40:14.319047 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 12 17:40:14.319516 disk-uuid[654]: The operation has completed successfully. Dec 12 17:40:14.382649 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 12 17:40:14.382727 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Dec 12 17:40:14.411767 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Dec 12 17:40:14.426885 sh[819]: Success Dec 12 17:40:14.460677 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 12 17:40:14.460706 kernel: device-mapper: uevent: version 1.0.3 Dec 12 17:40:14.465298 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Dec 12 17:40:14.474070 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Dec 12 17:40:14.739996 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Dec 12 17:40:14.749320 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Dec 12 17:40:14.754351 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Dec 12 17:40:14.788032 kernel: BTRFS: device fsid 6d6d314d-b8a1-4727-8a34-8525e276a248 devid 1 transid 38 /dev/mapper/usr (254:0) scanned by mount (837) Dec 12 17:40:14.797026 kernel: BTRFS info (device dm-0): first mount of filesystem 6d6d314d-b8a1-4727-8a34-8525e276a248 Dec 12 17:40:14.797057 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Dec 12 17:40:15.123891 kernel: BTRFS info (device dm-0): disabling log replay at mount time Dec 12 17:40:15.123973 kernel: BTRFS info (device dm-0): enabling free space tree Dec 12 17:40:15.160281 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Dec 12 17:40:15.164134 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Dec 12 17:40:15.171268 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Dec 12 17:40:15.171856 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Dec 12 17:40:15.191884 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Dec 12 17:40:15.220048 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (863) Dec 12 17:40:15.230432 kernel: BTRFS info (device sda6): first mount of filesystem 4b8ce5a5-a2aa-4c44-bc9b-80e30d06d25f Dec 12 17:40:15.230460 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Dec 12 17:40:15.256932 kernel: BTRFS info (device sda6): turning on async discard Dec 12 17:40:15.256962 kernel: BTRFS info (device sda6): enabling free space tree Dec 12 17:40:15.265058 kernel: BTRFS info (device sda6): last unmount of filesystem 4b8ce5a5-a2aa-4c44-bc9b-80e30d06d25f Dec 12 17:40:15.265678 systemd[1]: Finished ignition-setup.service - Ignition (setup). Dec 12 17:40:15.270858 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Dec 12 17:40:15.296824 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 12 17:40:15.307191 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 12 17:40:15.339740 systemd-networkd[1006]: lo: Link UP Dec 12 17:40:15.339749 systemd-networkd[1006]: lo: Gained carrier Dec 12 17:40:15.340461 systemd-networkd[1006]: Enumeration completed Dec 12 17:40:15.342528 systemd-networkd[1006]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 17:40:15.342530 systemd-networkd[1006]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 17:40:15.345156 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 12 17:40:15.349875 systemd[1]: Reached target network.target - Network. Dec 12 17:40:15.416031 kernel: mlx5_core dc98:00:02.0 enP56472s1: Link up Dec 12 17:40:15.448767 systemd-networkd[1006]: enP56472s1: Link UP Dec 12 17:40:15.451765 kernel: hv_netvsc 00224876-ff13-0022-4876-ff1300224876 eth0: Data path switched to VF: enP56472s1 Dec 12 17:40:15.448825 systemd-networkd[1006]: eth0: Link UP Dec 12 17:40:15.448890 systemd-networkd[1006]: eth0: Gained carrier Dec 12 17:40:15.448898 systemd-networkd[1006]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 17:40:15.466159 systemd-networkd[1006]: enP56472s1: Gained carrier Dec 12 17:40:15.475039 systemd-networkd[1006]: eth0: DHCPv4 address 10.200.20.21/24, gateway 10.200.20.1 acquired from 168.63.129.16 Dec 12 17:40:16.596796 ignition[981]: Ignition 2.22.0 Dec 12 17:40:16.596809 ignition[981]: Stage: fetch-offline Dec 12 17:40:16.599834 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Dec 12 17:40:16.596893 ignition[981]: no configs at "/usr/lib/ignition/base.d" Dec 12 17:40:16.607730 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Dec 12 17:40:16.596899 ignition[981]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 17:40:16.596966 ignition[981]: parsed url from cmdline: "" Dec 12 17:40:16.596968 ignition[981]: no config URL provided Dec 12 17:40:16.596971 ignition[981]: reading system config file "/usr/lib/ignition/user.ign" Dec 12 17:40:16.596976 ignition[981]: no config at "/usr/lib/ignition/user.ign" Dec 12 17:40:16.596979 ignition[981]: failed to fetch config: resource requires networking Dec 12 17:40:16.597105 ignition[981]: Ignition finished successfully Dec 12 17:40:16.648663 ignition[1018]: Ignition 2.22.0 Dec 12 17:40:16.648667 ignition[1018]: Stage: fetch Dec 12 17:40:16.648839 ignition[1018]: no configs at "/usr/lib/ignition/base.d" Dec 12 17:40:16.648847 ignition[1018]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 17:40:16.648926 ignition[1018]: parsed url from cmdline: "" Dec 12 17:40:16.648929 ignition[1018]: no config URL provided Dec 12 17:40:16.648932 ignition[1018]: reading system config file "/usr/lib/ignition/user.ign" Dec 12 17:40:16.648938 ignition[1018]: no config at "/usr/lib/ignition/user.ign" Dec 12 17:40:16.648953 ignition[1018]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Dec 12 17:40:16.712404 ignition[1018]: GET result: OK Dec 12 17:40:16.712437 ignition[1018]: config has been read from IMDS userdata Dec 12 17:40:16.714511 unknown[1018]: fetched base config from "system" Dec 12 17:40:16.712451 ignition[1018]: parsing config with SHA512: 5362488a9ac23576d96557de14e6f2deeb17068d672cc12e5163f284eb6187a9c7e9af325d492e8f4f86e2dcae465e5a574d9fa21fcaecbb3d9cdeb0f6b19fd2 Dec 12 17:40:16.714519 unknown[1018]: fetched base config from "system" Dec 12 17:40:16.714745 ignition[1018]: fetch: fetch complete Dec 12 17:40:16.714522 unknown[1018]: fetched user config from "azure" Dec 12 17:40:16.714749 ignition[1018]: fetch: fetch passed Dec 12 17:40:16.718180 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Dec 12 17:40:16.714785 ignition[1018]: Ignition finished successfully Dec 12 17:40:16.723501 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Dec 12 17:40:16.760479 ignition[1025]: Ignition 2.22.0 Dec 12 17:40:16.762762 ignition[1025]: Stage: kargs Dec 12 17:40:16.762932 ignition[1025]: no configs at "/usr/lib/ignition/base.d" Dec 12 17:40:16.765838 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Dec 12 17:40:16.762939 ignition[1025]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 17:40:16.772283 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Dec 12 17:40:16.763365 ignition[1025]: kargs: kargs passed Dec 12 17:40:16.763406 ignition[1025]: Ignition finished successfully Dec 12 17:40:16.798829 ignition[1031]: Ignition 2.22.0 Dec 12 17:40:16.798843 ignition[1031]: Stage: disks Dec 12 17:40:16.798977 ignition[1031]: no configs at "/usr/lib/ignition/base.d" Dec 12 17:40:16.798985 ignition[1031]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 17:40:16.806247 systemd[1]: Finished ignition-disks.service - Ignition (disks). Dec 12 17:40:16.799334 ignition[1031]: disks: disks passed Dec 12 17:40:16.811734 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Dec 12 17:40:16.799362 ignition[1031]: Ignition finished successfully Dec 12 17:40:16.820130 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 12 17:40:16.828717 systemd-networkd[1006]: eth0: Gained IPv6LL Dec 12 17:40:16.828950 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 12 17:40:16.839505 systemd[1]: Reached target sysinit.target - System Initialization. Dec 12 17:40:16.846595 systemd[1]: Reached target basic.target - Basic System. Dec 12 17:40:16.855435 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Dec 12 17:40:16.935072 systemd-fsck[1039]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Dec 12 17:40:16.943773 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Dec 12 17:40:16.949705 systemd[1]: Mounting sysroot.mount - /sysroot... Dec 12 17:40:17.235799 systemd[1]: Mounted sysroot.mount - /sysroot. Dec 12 17:40:17.241895 kernel: EXT4-fs (sda9): mounted filesystem 895d7845-d0e8-43ae-a778-7804b473b868 r/w with ordered data mode. Quota mode: none. Dec 12 17:40:17.239346 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Dec 12 17:40:17.262517 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 12 17:40:17.266517 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Dec 12 17:40:17.280512 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Dec 12 17:40:17.290665 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 12 17:40:17.290692 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Dec 12 17:40:17.324109 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1053) Dec 12 17:40:17.324125 kernel: BTRFS info (device sda6): first mount of filesystem 4b8ce5a5-a2aa-4c44-bc9b-80e30d06d25f Dec 12 17:40:17.296070 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Dec 12 17:40:17.335530 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Dec 12 17:40:17.317772 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Dec 12 17:40:17.347731 kernel: BTRFS info (device sda6): turning on async discard Dec 12 17:40:17.347753 kernel: BTRFS info (device sda6): enabling free space tree Dec 12 17:40:17.350401 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 12 17:40:17.778447 coreos-metadata[1055]: Dec 12 17:40:17.778 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 12 17:40:17.784141 coreos-metadata[1055]: Dec 12 17:40:17.783 INFO Fetch successful Dec 12 17:40:17.784141 coreos-metadata[1055]: Dec 12 17:40:17.784 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Dec 12 17:40:17.795892 coreos-metadata[1055]: Dec 12 17:40:17.795 INFO Fetch successful Dec 12 17:40:17.811221 coreos-metadata[1055]: Dec 12 17:40:17.811 INFO wrote hostname ci-4459.2.2-a-8645460de3 to /sysroot/etc/hostname Dec 12 17:40:17.817506 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 12 17:40:18.061373 initrd-setup-root[1083]: cut: /sysroot/etc/passwd: No such file or directory Dec 12 17:40:18.101624 initrd-setup-root[1090]: cut: /sysroot/etc/group: No such file or directory Dec 12 17:40:18.123987 initrd-setup-root[1097]: cut: /sysroot/etc/shadow: No such file or directory Dec 12 17:40:18.144752 initrd-setup-root[1104]: cut: /sysroot/etc/gshadow: No such file or directory Dec 12 17:40:19.201629 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Dec 12 17:40:19.207398 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Dec 12 17:40:19.223443 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Dec 12 17:40:19.233083 systemd[1]: sysroot-oem.mount: Deactivated successfully. Dec 12 17:40:19.243044 kernel: BTRFS info (device sda6): last unmount of filesystem 4b8ce5a5-a2aa-4c44-bc9b-80e30d06d25f Dec 12 17:40:19.253693 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Dec 12 17:40:19.264702 ignition[1172]: INFO : Ignition 2.22.0 Dec 12 17:40:19.264702 ignition[1172]: INFO : Stage: mount Dec 12 17:40:19.274621 ignition[1172]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 17:40:19.274621 ignition[1172]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 17:40:19.274621 ignition[1172]: INFO : mount: mount passed Dec 12 17:40:19.274621 ignition[1172]: INFO : Ignition finished successfully Dec 12 17:40:19.270517 systemd[1]: Finished ignition-mount.service - Ignition (mount). Dec 12 17:40:19.277087 systemd[1]: Starting ignition-files.service - Ignition (files)... Dec 12 17:40:19.300105 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 12 17:40:19.332732 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1184) Dec 12 17:40:19.332761 kernel: BTRFS info (device sda6): first mount of filesystem 4b8ce5a5-a2aa-4c44-bc9b-80e30d06d25f Dec 12 17:40:19.336901 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Dec 12 17:40:19.345713 kernel: BTRFS info (device sda6): turning on async discard Dec 12 17:40:19.345741 kernel: BTRFS info (device sda6): enabling free space tree Dec 12 17:40:19.346990 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 12 17:40:19.375533 ignition[1201]: INFO : Ignition 2.22.0 Dec 12 17:40:19.375533 ignition[1201]: INFO : Stage: files Dec 12 17:40:19.381743 ignition[1201]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 17:40:19.381743 ignition[1201]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 17:40:19.381743 ignition[1201]: DEBUG : files: compiled without relabeling support, skipping Dec 12 17:40:19.395791 ignition[1201]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 12 17:40:19.395791 ignition[1201]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 12 17:40:19.481315 ignition[1201]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 12 17:40:19.487350 ignition[1201]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 12 17:40:19.487350 ignition[1201]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 12 17:40:19.481617 unknown[1201]: wrote ssh authorized keys file for user: core Dec 12 17:40:19.572699 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Dec 12 17:40:19.580167 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Dec 12 17:40:19.594586 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 12 17:40:19.601740 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 12 17:40:19.601740 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Dec 12 17:40:19.619062 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Dec 12 17:40:19.619062 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Dec 12 17:40:19.619062 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Dec 12 17:40:20.034423 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Dec 12 17:40:20.272697 ignition[1201]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Dec 12 17:40:20.283420 ignition[1201]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 12 17:40:20.283420 ignition[1201]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 12 17:40:20.283420 ignition[1201]: INFO : files: files passed Dec 12 17:40:20.283420 ignition[1201]: INFO : Ignition finished successfully Dec 12 17:40:20.282974 systemd[1]: Finished ignition-files.service - Ignition (files). Dec 12 17:40:20.295494 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Dec 12 17:40:20.319091 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Dec 12 17:40:20.326229 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 12 17:40:20.328039 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Dec 12 17:40:20.355741 initrd-setup-root-after-ignition[1231]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 12 17:40:20.355741 initrd-setup-root-after-ignition[1231]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Dec 12 17:40:20.369218 initrd-setup-root-after-ignition[1235]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 12 17:40:20.368920 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 12 17:40:20.381009 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Dec 12 17:40:20.390596 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Dec 12 17:40:20.421688 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 12 17:40:20.421775 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Dec 12 17:40:20.430273 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Dec 12 17:40:20.438889 systemd[1]: Reached target initrd.target - Initrd Default Target. Dec 12 17:40:20.446457 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Dec 12 17:40:20.446936 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Dec 12 17:40:20.474554 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 12 17:40:20.480931 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Dec 12 17:40:20.502897 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Dec 12 17:40:20.507540 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 17:40:20.516478 systemd[1]: Stopped target timers.target - Timer Units. Dec 12 17:40:20.524270 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 12 17:40:20.524343 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 12 17:40:20.536098 systemd[1]: Stopped target initrd.target - Initrd Default Target. Dec 12 17:40:20.540344 systemd[1]: Stopped target basic.target - Basic System. Dec 12 17:40:20.548446 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Dec 12 17:40:20.556339 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Dec 12 17:40:20.564196 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Dec 12 17:40:20.572369 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Dec 12 17:40:20.581079 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Dec 12 17:40:20.588917 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Dec 12 17:40:20.598036 systemd[1]: Stopped target sysinit.target - System Initialization. Dec 12 17:40:20.605968 systemd[1]: Stopped target local-fs.target - Local File Systems. Dec 12 17:40:20.614450 systemd[1]: Stopped target swap.target - Swaps. Dec 12 17:40:20.621302 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 12 17:40:20.621377 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Dec 12 17:40:20.631673 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Dec 12 17:40:20.635932 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 17:40:20.644300 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Dec 12 17:40:20.647980 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 17:40:20.652982 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 12 17:40:20.653061 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Dec 12 17:40:20.665447 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 12 17:40:20.665516 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 12 17:40:20.671569 systemd[1]: ignition-files.service: Deactivated successfully. Dec 12 17:40:20.671634 systemd[1]: Stopped ignition-files.service - Ignition (files). Dec 12 17:40:20.734576 ignition[1255]: INFO : Ignition 2.22.0 Dec 12 17:40:20.734576 ignition[1255]: INFO : Stage: umount Dec 12 17:40:20.734576 ignition[1255]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 17:40:20.734576 ignition[1255]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 17:40:20.734576 ignition[1255]: INFO : umount: umount passed Dec 12 17:40:20.734576 ignition[1255]: INFO : Ignition finished successfully Dec 12 17:40:20.679025 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Dec 12 17:40:20.679087 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 12 17:40:20.689715 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Dec 12 17:40:20.702399 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 12 17:40:20.702509 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 17:40:20.720288 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Dec 12 17:40:20.730778 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 12 17:40:20.734119 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 17:40:20.739074 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 12 17:40:20.739144 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Dec 12 17:40:20.749631 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 12 17:40:20.749690 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Dec 12 17:40:20.759349 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 12 17:40:20.760054 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 12 17:40:20.760119 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Dec 12 17:40:20.767833 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 12 17:40:20.767864 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Dec 12 17:40:20.775059 systemd[1]: ignition-fetch.service: Deactivated successfully. Dec 12 17:40:20.775084 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Dec 12 17:40:20.779238 systemd[1]: Stopped target network.target - Network. Dec 12 17:40:20.786346 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 12 17:40:20.786383 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Dec 12 17:40:20.800069 systemd[1]: Stopped target paths.target - Path Units. Dec 12 17:40:20.803454 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 12 17:40:20.807143 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 17:40:20.812923 systemd[1]: Stopped target slices.target - Slice Units. Dec 12 17:40:20.820607 systemd[1]: Stopped target sockets.target - Socket Units. Dec 12 17:40:20.829523 systemd[1]: iscsid.socket: Deactivated successfully. Dec 12 17:40:20.829554 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Dec 12 17:40:20.837238 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 12 17:40:20.837270 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 12 17:40:20.845344 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 12 17:40:20.845386 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Dec 12 17:40:20.854083 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Dec 12 17:40:20.854109 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Dec 12 17:40:20.862296 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Dec 12 17:40:20.870467 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Dec 12 17:40:20.883562 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 12 17:40:21.081972 kernel: hv_netvsc 00224876-ff13-0022-4876-ff1300224876 eth0: Data path switched from VF: enP56472s1 Dec 12 17:40:20.883631 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Dec 12 17:40:20.892583 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 12 17:40:20.892672 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Dec 12 17:40:20.905268 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Dec 12 17:40:20.905421 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 12 17:40:20.905508 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Dec 12 17:40:20.916476 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Dec 12 17:40:20.918667 systemd[1]: Stopped target network-pre.target - Preparation for Network. Dec 12 17:40:20.924408 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 12 17:40:20.924444 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Dec 12 17:40:20.934048 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Dec 12 17:40:20.941291 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 12 17:40:20.941336 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 12 17:40:20.946380 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 12 17:40:20.946417 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Dec 12 17:40:20.959468 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 12 17:40:20.959501 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Dec 12 17:40:20.963858 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Dec 12 17:40:20.963889 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 17:40:20.976112 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 17:40:20.983716 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Dec 12 17:40:20.983761 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Dec 12 17:40:20.984041 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 12 17:40:20.984101 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Dec 12 17:40:20.988657 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 12 17:40:20.988735 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Dec 12 17:40:21.004372 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 12 17:40:21.011793 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 17:40:21.019898 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 12 17:40:21.019956 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Dec 12 17:40:21.027223 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 12 17:40:21.027247 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 17:40:21.035182 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 12 17:40:21.035217 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Dec 12 17:40:21.046462 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 12 17:40:21.046492 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Dec 12 17:40:21.058011 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 12 17:40:21.058043 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 12 17:40:21.082114 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Dec 12 17:40:21.091219 systemd[1]: systemd-network-generator.service: Deactivated successfully. Dec 12 17:40:21.091265 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 17:40:21.103943 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 12 17:40:21.301031 systemd-journald[225]: Received SIGTERM from PID 1 (systemd). Dec 12 17:40:21.103976 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 17:40:21.109338 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 17:40:21.109377 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 17:40:21.129297 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Dec 12 17:40:21.129333 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Dec 12 17:40:21.129359 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 12 17:40:21.129580 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 12 17:40:21.129643 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Dec 12 17:40:21.172192 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 12 17:40:21.172307 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Dec 12 17:40:21.179834 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Dec 12 17:40:21.188131 systemd[1]: Starting initrd-switch-root.service - Switch Root... Dec 12 17:40:21.206293 systemd[1]: Switching root. Dec 12 17:40:21.356747 systemd-journald[225]: Journal stopped Dec 12 17:40:25.722008 kernel: SELinux: policy capability network_peer_controls=1 Dec 12 17:40:25.722083 kernel: SELinux: policy capability open_perms=1 Dec 12 17:40:25.722092 kernel: SELinux: policy capability extended_socket_class=1 Dec 12 17:40:25.722097 kernel: SELinux: policy capability always_check_network=0 Dec 12 17:40:25.722103 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 12 17:40:25.722110 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 12 17:40:25.722116 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 12 17:40:25.722121 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 12 17:40:25.722126 kernel: SELinux: policy capability userspace_initial_context=0 Dec 12 17:40:25.722132 kernel: audit: type=1403 audit(1765561222.450:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 12 17:40:25.722138 systemd[1]: Successfully loaded SELinux policy in 225.580ms. Dec 12 17:40:25.722148 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 4.195ms. Dec 12 17:40:25.722155 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) Dec 12 17:40:25.722160 systemd[1]: Detected virtualization microsoft. Dec 12 17:40:25.722167 systemd[1]: Detected architecture arm64. Dec 12 17:40:25.722173 systemd[1]: Detected first boot. Dec 12 17:40:25.722180 systemd[1]: Hostname set to . Dec 12 17:40:25.722185 systemd[1]: Initializing machine ID from random generator. Dec 12 17:40:25.722191 zram_generator::config[1298]: No configuration found. Dec 12 17:40:25.722198 kernel: NET: Registered PF_VSOCK protocol family Dec 12 17:40:25.722203 systemd[1]: Populated /etc with preset unit settings. Dec 12 17:40:25.722210 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Dec 12 17:40:25.722216 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 12 17:40:25.722223 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Dec 12 17:40:25.722229 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 12 17:40:25.722235 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Dec 12 17:40:25.722241 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Dec 12 17:40:25.722248 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 12 17:40:25.722254 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 12 17:40:25.722260 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 12 17:40:25.722267 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Dec 12 17:40:25.722273 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 12 17:40:25.722279 systemd[1]: Created slice user.slice - User and Session Slice. Dec 12 17:40:25.722285 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 17:40:25.722291 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 17:40:25.722297 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 12 17:40:25.722303 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Dec 12 17:40:25.722309 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 12 17:40:25.722317 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 12 17:40:25.722323 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Dec 12 17:40:25.722330 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 17:40:25.722337 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 12 17:40:25.722343 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Dec 12 17:40:25.722349 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Dec 12 17:40:25.722355 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Dec 12 17:40:25.722362 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 12 17:40:25.722369 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 17:40:25.722375 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 12 17:40:25.722381 systemd[1]: Reached target slices.target - Slice Units. Dec 12 17:40:25.722387 systemd[1]: Reached target swap.target - Swaps. Dec 12 17:40:25.722395 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 12 17:40:25.722401 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Dec 12 17:40:25.722408 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Dec 12 17:40:25.722415 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 12 17:40:25.722421 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 12 17:40:25.722428 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 17:40:25.722434 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Dec 12 17:40:25.722440 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Dec 12 17:40:25.722446 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 12 17:40:25.722453 systemd[1]: Mounting media.mount - External Media Directory... Dec 12 17:40:25.722460 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 12 17:40:25.722466 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 12 17:40:25.722472 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Dec 12 17:40:25.722479 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). Dec 12 17:40:25.722485 systemd[1]: Reached target machines.target - Containers. Dec 12 17:40:25.722492 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Dec 12 17:40:25.722498 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 17:40:25.722505 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 12 17:40:25.722513 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 12 17:40:25.722519 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 17:40:25.722526 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 12 17:40:25.722532 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 17:40:25.722539 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 12 17:40:25.722545 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 17:40:25.722551 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 12 17:40:25.722558 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 12 17:40:25.722565 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Dec 12 17:40:25.722571 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 12 17:40:25.722578 systemd[1]: Stopped systemd-fsck-usr.service. Dec 12 17:40:25.722584 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). Dec 12 17:40:25.722591 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 12 17:40:25.722597 kernel: fuse: init (API version 7.41) Dec 12 17:40:25.722602 kernel: loop: module loaded Dec 12 17:40:25.722608 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 12 17:40:25.722615 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 12 17:40:25.722621 kernel: ACPI: bus type drm_connector registered Dec 12 17:40:25.722628 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 12 17:40:25.722647 systemd-journald[1388]: Collecting audit messages is disabled. Dec 12 17:40:25.722662 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Dec 12 17:40:25.722669 systemd-journald[1388]: Journal started Dec 12 17:40:25.722682 systemd-journald[1388]: Runtime Journal (/run/log/journal/713f9156770b4a979c2112dd789966fa) is 8M, max 78.3M, 70.3M free. Dec 12 17:40:25.007383 systemd[1]: Queued start job for default target multi-user.target. Dec 12 17:40:25.013362 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Dec 12 17:40:25.013686 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 12 17:40:25.013900 systemd[1]: systemd-journald.service: Consumed 2.136s CPU time. Dec 12 17:40:25.749064 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 12 17:40:25.756253 systemd[1]: verity-setup.service: Deactivated successfully. Dec 12 17:40:25.756270 systemd[1]: Stopped verity-setup.service. Dec 12 17:40:25.770121 systemd[1]: Started systemd-journald.service - Journal Service. Dec 12 17:40:25.770695 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Dec 12 17:40:25.774939 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 12 17:40:25.779349 systemd[1]: Mounted media.mount - External Media Directory. Dec 12 17:40:25.783211 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 12 17:40:25.787479 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 12 17:40:25.792006 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Dec 12 17:40:25.795888 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Dec 12 17:40:25.800648 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 17:40:25.805804 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 12 17:40:25.805920 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 12 17:40:25.810996 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 17:40:25.811117 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 17:40:25.815713 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 12 17:40:25.815830 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 12 17:40:25.820331 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 17:40:25.820436 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 17:40:25.825767 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 12 17:40:25.825882 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 12 17:40:25.830455 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 17:40:25.830556 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 17:40:25.835118 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 12 17:40:25.840088 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 17:40:25.845212 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 12 17:40:25.850468 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Dec 12 17:40:25.855554 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 17:40:25.867605 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 12 17:40:25.872895 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 12 17:40:25.883490 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 12 17:40:25.889258 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 12 17:40:25.889331 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 12 17:40:25.894102 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Dec 12 17:40:25.904692 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Dec 12 17:40:25.908865 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 17:40:25.909587 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Dec 12 17:40:25.920503 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 12 17:40:25.925100 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 12 17:40:25.927120 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Dec 12 17:40:25.931403 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 12 17:40:25.933126 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 12 17:40:25.940556 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Dec 12 17:40:25.946225 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 12 17:40:25.953169 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 12 17:40:25.958584 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 12 17:40:25.964327 systemd-journald[1388]: Time spent on flushing to /var/log/journal/713f9156770b4a979c2112dd789966fa is 9.053ms for 918 entries. Dec 12 17:40:25.964327 systemd-journald[1388]: System Journal (/var/log/journal/713f9156770b4a979c2112dd789966fa) is 8M, max 2.6G, 2.6G free. Dec 12 17:40:26.016342 systemd-journald[1388]: Received client request to flush runtime journal. Dec 12 17:40:26.016378 kernel: loop0: detected capacity change from 0 to 119840 Dec 12 17:40:25.985739 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Dec 12 17:40:25.992872 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Dec 12 17:40:25.998405 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Dec 12 17:40:26.017527 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 12 17:40:26.027301 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 12 17:40:26.068082 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 12 17:40:26.068575 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Dec 12 17:40:26.108296 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 12 17:40:26.114127 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 12 17:40:26.215181 systemd-tmpfiles[1451]: ACLs are not supported, ignoring. Dec 12 17:40:26.215196 systemd-tmpfiles[1451]: ACLs are not supported, ignoring. Dec 12 17:40:26.217444 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 17:40:26.457036 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 12 17:40:26.490120 kernel: loop1: detected capacity change from 0 to 100632 Dec 12 17:40:26.774413 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Dec 12 17:40:26.780825 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 17:40:26.808078 systemd-udevd[1458]: Using default interface naming scheme 'v255'. Dec 12 17:40:27.003039 kernel: loop2: detected capacity change from 0 to 27936 Dec 12 17:40:27.015957 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 17:40:27.025136 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 12 17:40:27.089246 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Dec 12 17:40:27.098626 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Dec 12 17:40:27.158165 systemd[1]: Started systemd-userdbd.service - User Database Manager. Dec 12 17:40:27.211793 kernel: hv_vmbus: registering driver hv_balloon Dec 12 17:40:27.211865 kernel: mousedev: PS/2 mouse device common for all mice Dec 12 17:40:27.211879 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#22 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Dec 12 17:40:27.216338 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Dec 12 17:40:27.220001 kernel: hv_balloon: Memory hot add disabled on ARM64 Dec 12 17:40:27.225227 kernel: hv_vmbus: registering driver hyperv_fb Dec 12 17:40:27.229415 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Dec 12 17:40:27.234835 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Dec 12 17:40:27.240028 kernel: Console: switching to colour dummy device 80x25 Dec 12 17:40:27.243031 kernel: Console: switching to colour frame buffer device 128x48 Dec 12 17:40:27.283216 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 17:40:27.297405 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 17:40:27.297547 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 17:40:27.302949 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 17:40:27.341036 kernel: MACsec IEEE 802.1AE Dec 12 17:40:27.371861 systemd-networkd[1474]: lo: Link UP Dec 12 17:40:27.371866 systemd-networkd[1474]: lo: Gained carrier Dec 12 17:40:27.372812 systemd-networkd[1474]: Enumeration completed Dec 12 17:40:27.372891 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 12 17:40:27.377670 systemd-networkd[1474]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 17:40:27.377676 systemd-networkd[1474]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 17:40:27.380890 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Dec 12 17:40:27.387183 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Dec 12 17:40:27.415046 kernel: loop3: detected capacity change from 0 to 211168 Dec 12 17:40:27.438039 kernel: mlx5_core dc98:00:02.0 enP56472s1: Link up Dec 12 17:40:27.448052 kernel: loop4: detected capacity change from 0 to 119840 Dec 12 17:40:27.466669 kernel: hv_netvsc 00224876-ff13-0022-4876-ff1300224876 eth0: Data path switched to VF: enP56472s1 Dec 12 17:40:27.466866 kernel: loop5: detected capacity change from 0 to 100632 Dec 12 17:40:27.465170 systemd-networkd[1474]: enP56472s1: Link UP Dec 12 17:40:27.465282 systemd-networkd[1474]: eth0: Link UP Dec 12 17:40:27.465284 systemd-networkd[1474]: eth0: Gained carrier Dec 12 17:40:27.465295 systemd-networkd[1474]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 17:40:27.468649 systemd-networkd[1474]: enP56472s1: Gained carrier Dec 12 17:40:27.469696 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Dec 12 17:40:27.483076 systemd-networkd[1474]: eth0: DHCPv4 address 10.200.20.21/24, gateway 10.200.20.1 acquired from 168.63.129.16 Dec 12 17:40:27.485056 kernel: loop6: detected capacity change from 0 to 27936 Dec 12 17:40:27.497530 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 12 17:40:27.500038 kernel: loop7: detected capacity change from 0 to 211168 Dec 12 17:40:27.506799 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Dec 12 17:40:27.525530 (sd-merge)[1581]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-azure'. Dec 12 17:40:27.526169 (sd-merge)[1581]: Merged extensions into '/usr'. Dec 12 17:40:27.528965 systemd[1]: Reload requested from client PID 1438 ('systemd-sysext') (unit systemd-sysext.service)... Dec 12 17:40:27.528976 systemd[1]: Reloading... Dec 12 17:40:27.584066 zram_generator::config[1631]: No configuration found. Dec 12 17:40:27.754749 systemd[1]: Reloading finished in 225 ms. Dec 12 17:40:27.778865 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 17:40:27.783732 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Dec 12 17:40:27.788951 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Dec 12 17:40:27.799826 systemd[1]: Starting ensure-sysext.service... Dec 12 17:40:27.805129 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 12 17:40:27.815685 systemd[1]: Reload requested from client PID 1690 ('systemctl') (unit ensure-sysext.service)... Dec 12 17:40:27.815695 systemd[1]: Reloading... Dec 12 17:40:27.849965 systemd-tmpfiles[1691]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Dec 12 17:40:27.850238 systemd-tmpfiles[1691]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Dec 12 17:40:27.850488 systemd-tmpfiles[1691]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 12 17:40:27.850932 systemd-tmpfiles[1691]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Dec 12 17:40:27.851455 systemd-tmpfiles[1691]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 12 17:40:27.853124 systemd-tmpfiles[1691]: ACLs are not supported, ignoring. Dec 12 17:40:27.853243 systemd-tmpfiles[1691]: ACLs are not supported, ignoring. Dec 12 17:40:27.862047 zram_generator::config[1722]: No configuration found. Dec 12 17:40:27.873450 systemd-tmpfiles[1691]: Detected autofs mount point /boot during canonicalization of boot. Dec 12 17:40:27.873457 systemd-tmpfiles[1691]: Skipping /boot Dec 12 17:40:27.878691 systemd-tmpfiles[1691]: Detected autofs mount point /boot during canonicalization of boot. Dec 12 17:40:27.878701 systemd-tmpfiles[1691]: Skipping /boot Dec 12 17:40:28.011012 systemd[1]: Reloading finished in 195 ms. Dec 12 17:40:28.018817 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 17:40:28.035132 systemd[1]: Starting audit-rules.service - Load Audit Rules... Dec 12 17:40:28.046555 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Dec 12 17:40:28.054112 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Dec 12 17:40:28.071669 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 12 17:40:28.078116 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 12 17:40:28.091637 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 17:40:28.094245 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 17:40:28.100266 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 12 17:40:28.110164 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 17:40:28.117453 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 17:40:28.121554 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 17:40:28.121636 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). Dec 12 17:40:28.121730 systemd[1]: Reached target time-set.target - System Time Set. Dec 12 17:40:28.127605 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 17:40:28.129047 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 17:40:28.134310 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 12 17:40:28.134508 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 12 17:40:28.139264 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 17:40:28.139440 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 17:40:28.144512 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 17:40:28.144721 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 17:40:28.154620 systemd[1]: Finished ensure-sysext.service. Dec 12 17:40:28.160650 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 12 17:40:28.167349 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 12 17:40:28.167521 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 12 17:40:28.177224 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Dec 12 17:40:28.196784 systemd-resolved[1782]: Positive Trust Anchors: Dec 12 17:40:28.196800 systemd-resolved[1782]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 12 17:40:28.196820 systemd-resolved[1782]: 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 Dec 12 17:40:28.199213 systemd-resolved[1782]: Using system hostname 'ci-4459.2.2-a-8645460de3'. Dec 12 17:40:28.200212 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 12 17:40:28.204772 systemd[1]: Reached target network.target - Network. Dec 12 17:40:28.208607 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 12 17:40:28.259530 augenrules[1815]: No rules Dec 12 17:40:28.260492 systemd[1]: audit-rules.service: Deactivated successfully. Dec 12 17:40:28.262074 systemd[1]: Finished audit-rules.service - Load Audit Rules. Dec 12 17:40:28.831208 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Dec 12 17:40:28.836857 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). Dec 12 17:40:28.923226 systemd-networkd[1474]: eth0: Gained IPv6LL Dec 12 17:40:28.925190 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Dec 12 17:40:28.931354 systemd[1]: Reached target network-online.target - Network is Online. Dec 12 17:40:32.032804 ldconfig[1432]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 12 17:40:32.044701 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Dec 12 17:40:32.050688 systemd[1]: Starting systemd-update-done.service - Update is Completed... Dec 12 17:40:32.064052 systemd[1]: Finished systemd-update-done.service - Update is Completed. Dec 12 17:40:32.069236 systemd[1]: Reached target sysinit.target - System Initialization. Dec 12 17:40:32.073710 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Dec 12 17:40:32.078323 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Dec 12 17:40:32.083342 systemd[1]: Started logrotate.timer - Daily rotation of log files. Dec 12 17:40:32.087408 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Dec 12 17:40:32.092305 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 12 17:40:32.097385 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 12 17:40:32.097404 systemd[1]: Reached target paths.target - Path Units. Dec 12 17:40:32.101033 systemd[1]: Reached target timers.target - Timer Units. Dec 12 17:40:32.121293 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 12 17:40:32.126353 systemd[1]: Starting docker.socket - Docker Socket for the API... Dec 12 17:40:32.131230 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Dec 12 17:40:32.136424 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Dec 12 17:40:32.141230 systemd[1]: Reached target ssh-access.target - SSH Access Available. Dec 12 17:40:32.146855 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Dec 12 17:40:32.150862 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Dec 12 17:40:32.155742 systemd[1]: Listening on docker.socket - Docker Socket for the API. Dec 12 17:40:32.160141 systemd[1]: Reached target sockets.target - Socket Units. Dec 12 17:40:32.163915 systemd[1]: Reached target basic.target - Basic System. Dec 12 17:40:32.167526 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Dec 12 17:40:32.167547 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Dec 12 17:40:32.170129 systemd[1]: Starting chronyd.service - NTP client/server... Dec 12 17:40:32.184093 systemd[1]: Starting containerd.service - containerd container runtime... Dec 12 17:40:32.189092 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Dec 12 17:40:32.195124 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 12 17:40:32.201582 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Dec 12 17:40:32.215670 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Dec 12 17:40:32.220369 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Dec 12 17:40:32.224419 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). Dec 12 17:40:32.227118 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Dec 12 17:40:32.231261 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Dec 12 17:40:32.232028 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 12 17:40:32.238121 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Dec 12 17:40:32.241815 jq[1836]: false Dec 12 17:40:32.242925 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Dec 12 17:40:32.248122 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Dec 12 17:40:32.254310 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Dec 12 17:40:32.261209 KVP[1838]: KVP starting; pid is:1838 Dec 12 17:40:32.261766 extend-filesystems[1837]: Found /dev/sda6 Dec 12 17:40:32.272802 kernel: hv_utils: KVP IC version 4.0 Dec 12 17:40:32.267249 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 12 17:40:32.263550 chronyd[1828]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Dec 12 17:40:32.267438 KVP[1838]: KVP LIC Version: 3.1 Dec 12 17:40:32.274793 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 12 17:40:32.275376 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 12 17:40:32.276204 systemd[1]: Starting update-engine.service - Update Engine... Dec 12 17:40:32.283168 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Dec 12 17:40:32.289006 extend-filesystems[1837]: Found /dev/sda9 Dec 12 17:40:32.307615 extend-filesystems[1837]: Checking size of /dev/sda9 Dec 12 17:40:32.292352 systemd[1]: Started chronyd.service - NTP client/server. Dec 12 17:40:32.314948 jq[1858]: true Dec 12 17:40:32.290918 chronyd[1828]: Timezone right/UTC failed leap second check, ignoring Dec 12 17:40:32.299312 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Dec 12 17:40:32.291470 chronyd[1828]: Loaded seccomp filter (level 2) Dec 12 17:40:32.311891 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 12 17:40:32.312314 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Dec 12 17:40:32.312920 systemd[1]: motdgen.service: Deactivated successfully. Dec 12 17:40:32.313056 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Dec 12 17:40:32.319178 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 12 17:40:32.319497 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Dec 12 17:40:32.327103 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Dec 12 17:40:32.344758 jq[1870]: true Dec 12 17:40:32.349893 extend-filesystems[1837]: Old size kept for /dev/sda9 Dec 12 17:40:32.358249 (ntainerd)[1872]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Dec 12 17:40:32.359365 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 12 17:40:32.359495 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Dec 12 17:40:32.369695 update_engine[1855]: I20251212 17:40:32.369464 1855 main.cc:92] Flatcar Update Engine starting Dec 12 17:40:32.384056 systemd-logind[1850]: New seat seat0. Dec 12 17:40:32.386220 systemd-logind[1850]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Dec 12 17:40:32.386347 systemd[1]: Started systemd-logind.service - User Login Management. Dec 12 17:40:32.473232 bash[1909]: Updated "/home/core/.ssh/authorized_keys" Dec 12 17:40:32.476032 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Dec 12 17:40:32.484621 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Dec 12 17:40:32.491781 dbus-daemon[1831]: [system] SELinux support is enabled Dec 12 17:40:32.492103 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 12 17:40:32.499923 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). Dec 12 17:40:32.500740 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Dec 12 17:40:32.506160 update_engine[1855]: I20251212 17:40:32.505978 1855 update_check_scheduler.cc:74] Next update check in 4m32s Dec 12 17:40:32.510061 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). Dec 12 17:40:32.510075 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Dec 12 17:40:32.520717 systemd[1]: Started update-engine.service - Update Engine. Dec 12 17:40:32.520933 dbus-daemon[1831]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 12 17:40:32.530243 systemd[1]: Started locksmithd.service - Cluster reboot manager. Dec 12 17:40:32.565098 coreos-metadata[1830]: Dec 12 17:40:32.565 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 12 17:40:32.570725 coreos-metadata[1830]: Dec 12 17:40:32.570 INFO Fetch successful Dec 12 17:40:32.570725 coreos-metadata[1830]: Dec 12 17:40:32.570 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Dec 12 17:40:32.574919 coreos-metadata[1830]: Dec 12 17:40:32.574 INFO Fetch successful Dec 12 17:40:32.574980 coreos-metadata[1830]: Dec 12 17:40:32.574 INFO Fetching http://168.63.129.16/machine/163417fc-82d6-4469-b01f-f209576babf0/caade8dd%2D7114%2D4ce9%2Dada7%2D402a3ab10342.%5Fci%2D4459.2.2%2Da%2D8645460de3?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Dec 12 17:40:32.577166 coreos-metadata[1830]: Dec 12 17:40:32.577 INFO Fetch successful Dec 12 17:40:32.577166 coreos-metadata[1830]: Dec 12 17:40:32.577 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Dec 12 17:40:32.584895 coreos-metadata[1830]: Dec 12 17:40:32.584 INFO Fetch successful Dec 12 17:40:32.592652 sshd_keygen[1857]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 12 17:40:32.608458 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Dec 12 17:40:32.614206 systemd[1]: Starting issuegen.service - Generate /run/issue... Dec 12 17:40:32.620718 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Dec 12 17:40:32.638754 systemd[1]: issuegen.service: Deactivated successfully. Dec 12 17:40:32.640032 systemd[1]: Finished issuegen.service - Generate /run/issue. Dec 12 17:40:32.645454 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Dec 12 17:40:32.652151 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Dec 12 17:40:32.657392 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Dec 12 17:40:32.659187 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 12 17:40:32.677123 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 12 17:40:32.687304 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 12 17:40:32.694237 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Dec 12 17:40:32.699472 systemd[1]: Reached target getty.target - Login Prompts. Dec 12 17:40:32.706914 locksmithd[1968]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 12 17:40:33.071604 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Dec 12 17:40:33.076705 (kubelet)[2012]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Dec 12 17:40:33.125639 containerd[1872]: time="2025-12-12T17:40:33Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Dec 12 17:40:33.126507 containerd[1872]: time="2025-12-12T17:40:33.126471856Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Dec 12 17:40:33.132542 containerd[1872]: time="2025-12-12T17:40:33.132513864Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.088µs" Dec 12 17:40:33.132542 containerd[1872]: time="2025-12-12T17:40:33.132537288Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Dec 12 17:40:33.132607 containerd[1872]: time="2025-12-12T17:40:33.132550888Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Dec 12 17:40:33.132694 containerd[1872]: time="2025-12-12T17:40:33.132675600Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Dec 12 17:40:33.132694 containerd[1872]: time="2025-12-12T17:40:33.132691920Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Dec 12 17:40:33.132725 containerd[1872]: time="2025-12-12T17:40:33.132709200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Dec 12 17:40:33.132762 containerd[1872]: time="2025-12-12T17:40:33.132747496Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Dec 12 17:40:33.132762 containerd[1872]: time="2025-12-12T17:40:33.132757776Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Dec 12 17:40:33.132929 containerd[1872]: time="2025-12-12T17:40:33.132910824Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Dec 12 17:40:33.132929 containerd[1872]: time="2025-12-12T17:40:33.132927104Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 12 17:40:33.132953 containerd[1872]: time="2025-12-12T17:40:33.132939560Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 12 17:40:33.132953 containerd[1872]: time="2025-12-12T17:40:33.132945728Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Dec 12 17:40:33.133020 containerd[1872]: time="2025-12-12T17:40:33.133006816Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Dec 12 17:40:33.133430 containerd[1872]: time="2025-12-12T17:40:33.133409008Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Dec 12 17:40:33.133455 containerd[1872]: time="2025-12-12T17:40:33.133442488Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Dec 12 17:40:33.133468 containerd[1872]: time="2025-12-12T17:40:33.133454152Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Dec 12 17:40:33.133493 containerd[1872]: time="2025-12-12T17:40:33.133479952Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Dec 12 17:40:33.134310 containerd[1872]: time="2025-12-12T17:40:33.134119944Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Dec 12 17:40:33.134310 containerd[1872]: time="2025-12-12T17:40:33.134206952Z" level=info msg="metadata content store policy set" policy=shared Dec 12 17:40:33.154200 containerd[1872]: time="2025-12-12T17:40:33.154177592Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Dec 12 17:40:33.154306 containerd[1872]: time="2025-12-12T17:40:33.154293160Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Dec 12 17:40:33.154401 containerd[1872]: time="2025-12-12T17:40:33.154389128Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Dec 12 17:40:33.154476 containerd[1872]: time="2025-12-12T17:40:33.154445512Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Dec 12 17:40:33.154554 containerd[1872]: time="2025-12-12T17:40:33.154543632Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Dec 12 17:40:33.154596 containerd[1872]: time="2025-12-12T17:40:33.154587832Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Dec 12 17:40:33.154702 containerd[1872]: time="2025-12-12T17:40:33.154636576Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Dec 12 17:40:33.154702 containerd[1872]: time="2025-12-12T17:40:33.154649512Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Dec 12 17:40:33.154702 containerd[1872]: time="2025-12-12T17:40:33.154657448Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Dec 12 17:40:33.154702 containerd[1872]: time="2025-12-12T17:40:33.154663848Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Dec 12 17:40:33.154702 containerd[1872]: time="2025-12-12T17:40:33.154669544Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Dec 12 17:40:33.154702 containerd[1872]: time="2025-12-12T17:40:33.154677840Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Dec 12 17:40:33.154961 containerd[1872]: time="2025-12-12T17:40:33.154893280Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Dec 12 17:40:33.154961 containerd[1872]: time="2025-12-12T17:40:33.154920224Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Dec 12 17:40:33.154961 containerd[1872]: time="2025-12-12T17:40:33.154934040Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Dec 12 17:40:33.155065 containerd[1872]: time="2025-12-12T17:40:33.154941016Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Dec 12 17:40:33.155114 containerd[1872]: time="2025-12-12T17:40:33.155102624Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155137808Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155149808Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155166760Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155175536Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155187080Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155193848Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155229800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155242888Z" level=info msg="Start snapshots syncer" Dec 12 17:40:33.155888 containerd[1872]: time="2025-12-12T17:40:33.155257896Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Dec 12 17:40:33.156063 containerd[1872]: time="2025-12-12T17:40:33.155421832Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Dec 12 17:40:33.156063 containerd[1872]: time="2025-12-12T17:40:33.155455344Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155482904Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155585320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155601624Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155608928Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155616280Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155624048Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155631288Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155638152Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155653920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155661672Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155673256Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155688392Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155702680Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Dec 12 17:40:33.156147 containerd[1872]: time="2025-12-12T17:40:33.155707576Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155712960Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155717904Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155724456Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155730808Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155741880Z" level=info msg="runtime interface created" Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155745144Z" level=info msg="created NRI interface" Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155753000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155760136Z" level=info msg="Connect containerd service" Dec 12 17:40:33.156300 containerd[1872]: time="2025-12-12T17:40:33.155772936Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Dec 12 17:40:33.157069 containerd[1872]: time="2025-12-12T17:40:33.157005408Z" 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" Dec 12 17:40:33.438461 kubelet[2012]: E1212 17:40:33.438350 2012 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" Dec 12 17:40:33.440586 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Dec 12 17:40:33.440783 systemd[1]: kubelet.service: Failed with result 'exit-code'. Dec 12 17:40:33.441268 systemd[1]: kubelet.service: Consumed 537ms CPU time, 256.9M memory peak. Dec 12 17:40:33.461753 containerd[1872]: time="2025-12-12T17:40:33.461722832Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 12 17:40:33.462782 containerd[1872]: time="2025-12-12T17:40:33.462764608Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 12 17:40:33.463007 containerd[1872]: time="2025-12-12T17:40:33.462104120Z" level=info msg="Start subscribing containerd event" Dec 12 17:40:33.465078 containerd[1872]: time="2025-12-12T17:40:33.465060576Z" level=info msg="Start recovering state" Dec 12 17:40:33.465219 containerd[1872]: time="2025-12-12T17:40:33.465206496Z" level=info msg="Start event monitor" Dec 12 17:40:33.465281 containerd[1872]: time="2025-12-12T17:40:33.465268656Z" level=info msg="Start cni network conf syncer for default" Dec 12 17:40:33.465438 containerd[1872]: time="2025-12-12T17:40:33.465307784Z" level=info msg="Start streaming server" Dec 12 17:40:33.465438 containerd[1872]: time="2025-12-12T17:40:33.465322032Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Dec 12 17:40:33.465438 containerd[1872]: time="2025-12-12T17:40:33.465328944Z" level=info msg="runtime interface starting up..." Dec 12 17:40:33.465438 containerd[1872]: time="2025-12-12T17:40:33.465333256Z" level=info msg="starting plugins..." Dec 12 17:40:33.465438 containerd[1872]: time="2025-12-12T17:40:33.465346312Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Dec 12 17:40:33.470112 containerd[1872]: time="2025-12-12T17:40:33.465642448Z" level=info msg="containerd successfully booted in 0.340486s" Dec 12 17:40:33.465718 systemd[1]: Started containerd.service - containerd container runtime. Dec 12 17:40:33.471281 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 12 17:40:33.476901 systemd[1]: Startup finished in 1.680s (kernel) + 11.430s (initrd) + 11.250s (userspace) = 24.361s. Dec 12 17:40:33.728164 login[1999]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Dec 12 17:40:33.728688 login[1996]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Dec 12 17:40:33.734802 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Dec 12 17:40:33.735772 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Dec 12 17:40:33.740077 systemd-logind[1850]: New session 2 of user core. Dec 12 17:40:33.761041 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Dec 12 17:40:33.762776 systemd[1]: Starting user@500.service - User Manager for UID 500... Dec 12 17:40:33.772590 (systemd)[2040]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 12 17:40:33.774382 systemd-logind[1850]: New session c1 of user core. Dec 12 17:40:33.906238 systemd[2040]: Queued start job for default target default.target. Dec 12 17:40:33.915986 systemd[2040]: Created slice app.slice - User Application Slice. Dec 12 17:40:33.916127 systemd[2040]: Reached target paths.target - Paths. Dec 12 17:40:33.916213 systemd[2040]: Reached target timers.target - Timers. Dec 12 17:40:33.917166 systemd[2040]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 12 17:40:33.923564 systemd[2040]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 12 17:40:33.923603 systemd[2040]: Reached target sockets.target - Sockets. Dec 12 17:40:33.923706 systemd[2040]: Reached target basic.target - Basic System. Dec 12 17:40:33.923867 systemd[1]: Started user@500.service - User Manager for UID 500. Dec 12 17:40:33.924400 systemd[2040]: Reached target default.target - Main User Target. Dec 12 17:40:33.925075 systemd[2040]: Startup finished in 145ms. Dec 12 17:40:33.930149 systemd[1]: Started session-2.scope - Session 2 of User core. Dec 12 17:40:34.364556 waagent[1991]: 2025-12-12T17:40:34.364490Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Dec 12 17:40:34.369034 waagent[1991]: 2025-12-12T17:40:34.368850Z INFO Daemon Daemon OS: flatcar 4459.2.2 Dec 12 17:40:34.372299 waagent[1991]: 2025-12-12T17:40:34.372262Z INFO Daemon Daemon Python: 3.11.13 Dec 12 17:40:34.375519 waagent[1991]: 2025-12-12T17:40:34.375483Z INFO Daemon Daemon Run daemon Dec 12 17:40:34.378336 waagent[1991]: 2025-12-12T17:40:34.378303Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.2' Dec 12 17:40:34.384486 waagent[1991]: 2025-12-12T17:40:34.384448Z INFO Daemon Daemon Using waagent for provisioning Dec 12 17:40:34.388091 waagent[1991]: 2025-12-12T17:40:34.388059Z INFO Daemon Daemon Activate resource disk Dec 12 17:40:34.391436 waagent[1991]: 2025-12-12T17:40:34.391406Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Dec 12 17:40:34.399239 waagent[1991]: 2025-12-12T17:40:34.399207Z INFO Daemon Daemon Found device: None Dec 12 17:40:34.402306 waagent[1991]: 2025-12-12T17:40:34.402279Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Dec 12 17:40:34.408261 waagent[1991]: 2025-12-12T17:40:34.408238Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Dec 12 17:40:34.416212 waagent[1991]: 2025-12-12T17:40:34.416178Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 12 17:40:34.420309 waagent[1991]: 2025-12-12T17:40:34.420281Z INFO Daemon Daemon Running default provisioning handler Dec 12 17:40:34.428762 waagent[1991]: 2025-12-12T17:40:34.428723Z 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. Dec 12 17:40:34.438011 waagent[1991]: 2025-12-12T17:40:34.437975Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Dec 12 17:40:34.444668 waagent[1991]: 2025-12-12T17:40:34.444640Z INFO Daemon Daemon cloud-init is enabled: False Dec 12 17:40:34.448141 waagent[1991]: 2025-12-12T17:40:34.448116Z INFO Daemon Daemon Copying ovf-env.xml Dec 12 17:40:34.501206 waagent[1991]: 2025-12-12T17:40:34.500688Z INFO Daemon Daemon Successfully mounted dvd Dec 12 17:40:34.527562 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Dec 12 17:40:34.529446 waagent[1991]: 2025-12-12T17:40:34.529405Z INFO Daemon Daemon Detect protocol endpoint Dec 12 17:40:34.532998 waagent[1991]: 2025-12-12T17:40:34.532969Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 12 17:40:34.537126 waagent[1991]: 2025-12-12T17:40:34.537099Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Dec 12 17:40:34.541690 waagent[1991]: 2025-12-12T17:40:34.541668Z INFO Daemon Daemon Test for route to 168.63.129.16 Dec 12 17:40:34.545561 waagent[1991]: 2025-12-12T17:40:34.545533Z INFO Daemon Daemon Route to 168.63.129.16 exists Dec 12 17:40:34.549149 waagent[1991]: 2025-12-12T17:40:34.549125Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Dec 12 17:40:34.635495 waagent[1991]: 2025-12-12T17:40:34.635424Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Dec 12 17:40:34.640318 waagent[1991]: 2025-12-12T17:40:34.640291Z INFO Daemon Daemon Wire protocol version:2012-11-30 Dec 12 17:40:34.644071 waagent[1991]: 2025-12-12T17:40:34.644047Z INFO Daemon Daemon Server preferred version:2015-04-05 Dec 12 17:40:34.729582 login[1999]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Dec 12 17:40:34.733207 systemd-logind[1850]: New session 1 of user core. Dec 12 17:40:34.739101 systemd[1]: Started session-1.scope - Session 1 of User core. Dec 12 17:40:34.925609 waagent[1991]: 2025-12-12T17:40:34.925505Z INFO Daemon Daemon Initializing goal state during protocol detection Dec 12 17:40:34.930161 waagent[1991]: 2025-12-12T17:40:34.930127Z INFO Daemon Daemon Forcing an update of the goal state. Dec 12 17:40:34.937138 waagent[1991]: 2025-12-12T17:40:34.937106Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 12 17:40:34.952981 waagent[1991]: 2025-12-12T17:40:34.952946Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Dec 12 17:40:34.957287 waagent[1991]: 2025-12-12T17:40:34.957258Z INFO Daemon Dec 12 17:40:34.959462 waagent[1991]: 2025-12-12T17:40:34.959437Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: de6d5a3f-03e3-4992-a6d8-a4bee3df865c eTag: 16795568939557247876 source: Fabric] Dec 12 17:40:34.967821 waagent[1991]: 2025-12-12T17:40:34.967794Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Dec 12 17:40:34.972748 waagent[1991]: 2025-12-12T17:40:34.972723Z INFO Daemon Dec 12 17:40:34.974804 waagent[1991]: 2025-12-12T17:40:34.974775Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Dec 12 17:40:34.983209 waagent[1991]: 2025-12-12T17:40:34.983181Z INFO Daemon Daemon Downloading artifacts profile blob Dec 12 17:40:35.036526 waagent[1991]: 2025-12-12T17:40:35.036482Z INFO Daemon Downloaded certificate {'thumbprint': '7CFE28DC916DD1F0B916A92491DD16B84895EC87', 'hasPrivateKey': True} Dec 12 17:40:35.043478 waagent[1991]: 2025-12-12T17:40:35.043445Z INFO Daemon Fetch goal state completed Dec 12 17:40:35.051962 waagent[1991]: 2025-12-12T17:40:35.051934Z INFO Daemon Daemon Starting provisioning Dec 12 17:40:35.056258 waagent[1991]: 2025-12-12T17:40:35.056228Z INFO Daemon Daemon Handle ovf-env.xml. Dec 12 17:40:35.059699 waagent[1991]: 2025-12-12T17:40:35.059670Z INFO Daemon Daemon Set hostname [ci-4459.2.2-a-8645460de3] Dec 12 17:40:35.084105 waagent[1991]: 2025-12-12T17:40:35.084068Z INFO Daemon Daemon Publish hostname [ci-4459.2.2-a-8645460de3] Dec 12 17:40:35.088581 waagent[1991]: 2025-12-12T17:40:35.088549Z INFO Daemon Daemon Examine /proc/net/route for primary interface Dec 12 17:40:35.092940 waagent[1991]: 2025-12-12T17:40:35.092914Z INFO Daemon Daemon Primary interface is [eth0] Dec 12 17:40:35.101787 systemd-networkd[1474]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 17:40:35.101793 systemd-networkd[1474]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 17:40:35.101830 systemd-networkd[1474]: eth0: DHCP lease lost Dec 12 17:40:35.102631 waagent[1991]: 2025-12-12T17:40:35.102596Z INFO Daemon Daemon Create user account if not exists Dec 12 17:40:35.106480 waagent[1991]: 2025-12-12T17:40:35.106450Z INFO Daemon Daemon User core already exists, skip useradd Dec 12 17:40:35.110377 waagent[1991]: 2025-12-12T17:40:35.110351Z INFO Daemon Daemon Configure sudoer Dec 12 17:40:35.119875 waagent[1991]: 2025-12-12T17:40:35.119834Z INFO Daemon Daemon Configure sshd Dec 12 17:40:35.127059 systemd-networkd[1474]: eth0: DHCPv4 address 10.200.20.21/24, gateway 10.200.20.1 acquired from 168.63.129.16 Dec 12 17:40:35.130158 waagent[1991]: 2025-12-12T17:40:35.130122Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Dec 12 17:40:35.139082 waagent[1991]: 2025-12-12T17:40:35.139050Z INFO Daemon Daemon Deploy ssh public key. Dec 12 17:40:36.208532 waagent[1991]: 2025-12-12T17:40:36.208473Z INFO Daemon Daemon Provisioning complete Dec 12 17:40:36.219988 waagent[1991]: 2025-12-12T17:40:36.219954Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Dec 12 17:40:36.224487 waagent[1991]: 2025-12-12T17:40:36.224457Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Dec 12 17:40:36.231352 waagent[1991]: 2025-12-12T17:40:36.231325Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Dec 12 17:40:36.325964 waagent[2090]: 2025-12-12T17:40:36.325916Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Dec 12 17:40:36.327051 waagent[2090]: 2025-12-12T17:40:36.326278Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.2 Dec 12 17:40:36.327051 waagent[2090]: 2025-12-12T17:40:36.326334Z INFO ExtHandler ExtHandler Python: 3.11.13 Dec 12 17:40:36.327051 waagent[2090]: 2025-12-12T17:40:36.326371Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Dec 12 17:40:36.365474 waagent[2090]: 2025-12-12T17:40:36.365436Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Dec 12 17:40:36.365674 waagent[2090]: 2025-12-12T17:40:36.365646Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 17:40:36.365815 waagent[2090]: 2025-12-12T17:40:36.365790Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 17:40:36.371041 waagent[2090]: 2025-12-12T17:40:36.370983Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 12 17:40:36.375619 waagent[2090]: 2025-12-12T17:40:36.375587Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Dec 12 17:40:36.376043 waagent[2090]: 2025-12-12T17:40:36.375988Z INFO ExtHandler Dec 12 17:40:36.376173 waagent[2090]: 2025-12-12T17:40:36.376147Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 755d516f-323f-4723-8659-c505b2fd0a98 eTag: 16795568939557247876 source: Fabric] Dec 12 17:40:36.376479 waagent[2090]: 2025-12-12T17:40:36.376448Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Dec 12 17:40:36.376979 waagent[2090]: 2025-12-12T17:40:36.376946Z INFO ExtHandler Dec 12 17:40:36.377124 waagent[2090]: 2025-12-12T17:40:36.377097Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Dec 12 17:40:36.380449 waagent[2090]: 2025-12-12T17:40:36.380422Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Dec 12 17:40:36.430061 waagent[2090]: 2025-12-12T17:40:36.429882Z INFO ExtHandler Downloaded certificate {'thumbprint': '7CFE28DC916DD1F0B916A92491DD16B84895EC87', 'hasPrivateKey': True} Dec 12 17:40:36.430292 waagent[2090]: 2025-12-12T17:40:36.430257Z INFO ExtHandler Fetch goal state completed Dec 12 17:40:36.440083 waagent[2090]: 2025-12-12T17:40:36.440043Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Dec 12 17:40:36.443036 waagent[2090]: 2025-12-12T17:40:36.442991Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2090 Dec 12 17:40:36.443154 waagent[2090]: 2025-12-12T17:40:36.443126Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Dec 12 17:40:36.443388 waagent[2090]: 2025-12-12T17:40:36.443360Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Dec 12 17:40:36.444416 waagent[2090]: 2025-12-12T17:40:36.444383Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] Dec 12 17:40:36.444722 waagent[2090]: 2025-12-12T17:40:36.444693Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Dec 12 17:40:36.444817 waagent[2090]: 2025-12-12T17:40:36.444796Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Dec 12 17:40:36.445239 waagent[2090]: 2025-12-12T17:40:36.445210Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Dec 12 17:40:36.480293 waagent[2090]: 2025-12-12T17:40:36.480226Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Dec 12 17:40:36.480385 waagent[2090]: 2025-12-12T17:40:36.480357Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Dec 12 17:40:36.484441 waagent[2090]: 2025-12-12T17:40:36.484419Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Dec 12 17:40:36.499227 systemd[1]: Reload requested from client PID 2105 ('systemctl') (unit waagent.service)... Dec 12 17:40:36.499240 systemd[1]: Reloading... Dec 12 17:40:36.567036 zram_generator::config[2144]: No configuration found. Dec 12 17:40:36.709879 systemd[1]: Reloading finished in 210 ms. Dec 12 17:40:36.731077 waagent[2090]: 2025-12-12T17:40:36.730680Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Dec 12 17:40:36.731077 waagent[2090]: 2025-12-12T17:40:36.730810Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Dec 12 17:40:36.941944 waagent[2090]: 2025-12-12T17:40:36.941885Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Dec 12 17:40:36.942217 waagent[2090]: 2025-12-12T17:40:36.942187Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Dec 12 17:40:36.942780 waagent[2090]: 2025-12-12T17:40:36.942742Z INFO ExtHandler ExtHandler Starting env monitor service. Dec 12 17:40:36.943068 waagent[2090]: 2025-12-12T17:40:36.942998Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Dec 12 17:40:36.943761 waagent[2090]: 2025-12-12T17:40:36.943234Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 17:40:36.943761 waagent[2090]: 2025-12-12T17:40:36.943299Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 17:40:36.943761 waagent[2090]: 2025-12-12T17:40:36.943448Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Dec 12 17:40:36.943761 waagent[2090]: 2025-12-12T17:40:36.943567Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Dec 12 17:40:36.943761 waagent[2090]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Dec 12 17:40:36.943761 waagent[2090]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Dec 12 17:40:36.943761 waagent[2090]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Dec 12 17:40:36.943761 waagent[2090]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Dec 12 17:40:36.943761 waagent[2090]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 12 17:40:36.943761 waagent[2090]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 12 17:40:36.944062 waagent[2090]: 2025-12-12T17:40:36.943993Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Dec 12 17:40:36.944111 waagent[2090]: 2025-12-12T17:40:36.944073Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Dec 12 17:40:36.944297 waagent[2090]: 2025-12-12T17:40:36.944269Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 17:40:36.944490 waagent[2090]: 2025-12-12T17:40:36.944465Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 17:40:36.944656 waagent[2090]: 2025-12-12T17:40:36.944624Z INFO EnvHandler ExtHandler Configure routes Dec 12 17:40:36.944755 waagent[2090]: 2025-12-12T17:40:36.944734Z INFO EnvHandler ExtHandler Gateway:None Dec 12 17:40:36.944853 waagent[2090]: 2025-12-12T17:40:36.944834Z INFO EnvHandler ExtHandler Routes:None Dec 12 17:40:36.945146 waagent[2090]: 2025-12-12T17:40:36.945110Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Dec 12 17:40:36.945212 waagent[2090]: 2025-12-12T17:40:36.945191Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Dec 12 17:40:36.945317 waagent[2090]: 2025-12-12T17:40:36.945295Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Dec 12 17:40:36.950907 waagent[2090]: 2025-12-12T17:40:36.950874Z INFO ExtHandler ExtHandler Dec 12 17:40:36.950954 waagent[2090]: 2025-12-12T17:40:36.950935Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: a54a5841-3525-4e15-adc1-997ce1dd55b2 correlation f5d69c47-edc2-4ca5-a06c-e114800865fb created: 2025-12-12T17:39:37.019264Z] Dec 12 17:40:36.951218 waagent[2090]: 2025-12-12T17:40:36.951187Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Dec 12 17:40:36.951597 waagent[2090]: 2025-12-12T17:40:36.951572Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Dec 12 17:40:36.972768 waagent[2090]: 2025-12-12T17:40:36.972726Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Dec 12 17:40:36.972768 waagent[2090]: Try `iptables -h' or 'iptables --help' for more information.) Dec 12 17:40:36.973062 waagent[2090]: 2025-12-12T17:40:36.973034Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: C2E5DA76-6BA0-4ECC-A554-50B081FDB65D;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Dec 12 17:40:37.019105 waagent[2090]: 2025-12-12T17:40:37.018970Z INFO MonitorHandler ExtHandler Network interfaces: Dec 12 17:40:37.019105 waagent[2090]: Executing ['ip', '-a', '-o', 'link']: Dec 12 17:40:37.019105 waagent[2090]: 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 Dec 12 17:40:37.019105 waagent[2090]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:76:ff:13 brd ff:ff:ff:ff:ff:ff Dec 12 17:40:37.019105 waagent[2090]: 3: enP56472s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:76:ff:13 brd ff:ff:ff:ff:ff:ff\ altname enP56472p0s2 Dec 12 17:40:37.019105 waagent[2090]: Executing ['ip', '-4', '-a', '-o', 'address']: Dec 12 17:40:37.019105 waagent[2090]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Dec 12 17:40:37.019105 waagent[2090]: 2: eth0 inet 10.200.20.21/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Dec 12 17:40:37.019105 waagent[2090]: Executing ['ip', '-6', '-a', '-o', 'address']: Dec 12 17:40:37.019105 waagent[2090]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Dec 12 17:40:37.019105 waagent[2090]: 2: eth0 inet6 fe80::222:48ff:fe76:ff13/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Dec 12 17:40:37.040424 waagent[2090]: 2025-12-12T17:40:37.040392Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Dec 12 17:40:37.040424 waagent[2090]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 17:40:37.040424 waagent[2090]: pkts bytes target prot opt in out source destination Dec 12 17:40:37.040424 waagent[2090]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 12 17:40:37.040424 waagent[2090]: pkts bytes target prot opt in out source destination Dec 12 17:40:37.040424 waagent[2090]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 17:40:37.040424 waagent[2090]: pkts bytes target prot opt in out source destination Dec 12 17:40:37.040424 waagent[2090]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 12 17:40:37.040424 waagent[2090]: 2 112 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 12 17:40:37.040424 waagent[2090]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 12 17:40:37.043038 waagent[2090]: 2025-12-12T17:40:37.042772Z INFO EnvHandler ExtHandler Current Firewall rules: Dec 12 17:40:37.043038 waagent[2090]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 17:40:37.043038 waagent[2090]: pkts bytes target prot opt in out source destination Dec 12 17:40:37.043038 waagent[2090]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 12 17:40:37.043038 waagent[2090]: pkts bytes target prot opt in out source destination Dec 12 17:40:37.043038 waagent[2090]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 17:40:37.043038 waagent[2090]: pkts bytes target prot opt in out source destination Dec 12 17:40:37.043038 waagent[2090]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 12 17:40:37.043038 waagent[2090]: 5 468 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 12 17:40:37.043038 waagent[2090]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 12 17:40:37.043038 waagent[2090]: 2025-12-12T17:40:37.042947Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Dec 12 17:40:43.691559 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Dec 12 17:40:43.693153 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 12 17:40:43.803629 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Dec 12 17:40:43.805987 (kubelet)[2239]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Dec 12 17:40:43.947785 kubelet[2239]: E1212 17:40:43.947684 2239 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" Dec 12 17:40:43.950375 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Dec 12 17:40:43.950479 systemd[1]: kubelet.service: Failed with result 'exit-code'. Dec 12 17:40:43.950921 systemd[1]: kubelet.service: Consumed 103ms CPU time, 106.9M memory peak. Dec 12 17:40:53.286311 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Dec 12 17:40:53.287329 systemd[1]: Started sshd@0-10.200.20.21:22-10.200.16.10:53302.service - OpenSSH per-connection server daemon (10.200.16.10:53302). Dec 12 17:40:53.890769 sshd[2246]: Accepted publickey for core from 10.200.16.10 port 53302 ssh2: RSA SHA256:rv0ogpS37Fn9XgD1tbLDwSSen2nZukTXJG3iueJVyC4 Dec 12 17:40:53.891842 sshd-session[2246]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 17:40:53.894956 systemd-logind[1850]: New session 3 of user core. Dec 12 17:40:53.902283 systemd[1]: Started session-3.scope - Session 3 of User core. Dec 12 17:40:54.200795 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Dec 12 17:40:54.203157 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 12 17:40:54.321988 systemd[1]: Started sshd@1-10.200.20.21:22-10.200.16.10:53304.service - OpenSSH per-connection server daemon (10.200.16.10:53304). Dec 12 17:40:54.360650 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Dec 12 17:40:54.363142 (kubelet)[2263]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Dec 12 17:40:54.404487 kubelet[2263]: E1212 17:40:54.404438 2263 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" Dec 12 17:40:54.406430 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Dec 12 17:40:54.406526 systemd[1]: kubelet.service: Failed with result 'exit-code'. Dec 12 17:40:54.407003 systemd[1]: kubelet.service: Consumed 115ms CPU time, 107.8M memory peak. Dec 12 17:40:54.777075 sshd[2255]: Accepted publickey for core from 10.200.16.10 port 53304 ssh2: RSA SHA256:rv0ogpS37Fn9XgD1tbLDwSSen2nZukTXJG3iueJVyC4 Dec 12 17:40:54.778222 sshd-session[2255]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 17:40:54.781785 systemd-logind[1850]: New session 4 of user core. Dec 12 17:40:54.789382 systemd[1]: Started session-4.scope - Session 4 of User core. Dec 12 17:40:55.109787 sshd[2270]: Connection closed by 10.200.16.10 port 53304 Dec 12 17:40:55.109656 sshd-session[2255]: pam_unix(sshd:session): session closed for user core Dec 12 17:40:55.112679 systemd[1]: sshd@1-10.200.20.21:22-10.200.16.10:53304.service: Deactivated successfully. Dec 12 17:40:55.113874 systemd[1]: session-4.scope: Deactivated successfully. Dec 12 17:40:55.114733 systemd-logind[1850]: Session 4 logged out. Waiting for processes to exit. Dec 12 17:40:55.115788 systemd-logind[1850]: Removed session 4. Dec 12 17:40:55.199032 systemd[1]: Started sshd@2-10.200.20.21:22-10.200.16.10:53312.service - OpenSSH per-connection server daemon (10.200.16.10:53312). Dec 12 17:40:55.685563 sshd[2276]: Accepted publickey for core from 10.200.16.10 port 53312 ssh2: RSA SHA256:rv0ogpS37Fn9XgD1tbLDwSSen2nZukTXJG3iueJVyC4 Dec 12 17:40:55.686621 sshd-session[2276]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 17:40:55.689934 systemd-logind[1850]: New session 5 of user core. Dec 12 17:40:55.697285 systemd[1]: Started session-5.scope - Session 5 of User core. Dec 12 17:40:56.034414 sshd[2279]: Connection closed by 10.200.16.10 port 53312 Dec 12 17:40:56.035010 sshd-session[2276]: pam_unix(sshd:session): session closed for user core Dec 12 17:40:56.037827 systemd[1]: sshd@2-10.200.20.21:22-10.200.16.10:53312.service: Deactivated successfully. Dec 12 17:40:56.039052 systemd[1]: session-5.scope: Deactivated successfully. Dec 12 17:40:56.039624 systemd-logind[1850]: Session 5 logged out. Waiting for processes to exit. Dec 12 17:40:56.040541 systemd-logind[1850]: Removed session 5. Dec 12 17:40:56.077060 chronyd[1828]: Selected source PHC0 Dec 12 17:40:56.114148 systemd[1]: Started sshd@3-10.200.20.21:22-10.200.16.10:53318.service - OpenSSH per-connection server daemon (10.200.16.10:53318). Dec 12 17:40:56.572068 sshd[2285]: Accepted publickey for core from 10.200.16.10 port 53318 ssh2: RSA SHA256:rv0ogpS37Fn9XgD1tbLDwSSen2nZukTXJG3iueJVyC4 Dec 12 17:40:56.573076 sshd-session[2285]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 17:40:56.576830 systemd-logind[1850]: New session 6 of user core. Dec 12 17:40:56.583129 systemd[1]: Started session-6.scope - Session 6 of User core. Dec 12 17:40:56.920921 sshd[2288]: Connection closed by 10.200.16.10 port 53318 Dec 12 17:40:56.921573 sshd-session[2285]: pam_unix(sshd:session): session closed for user core Dec 12 17:40:56.924340 systemd[1]: sshd@3-10.200.20.21:22-10.200.16.10:53318.service: Deactivated successfully. Dec 12 17:40:56.925534 systemd[1]: session-6.scope: Deactivated successfully. Dec 12 17:40:56.926188 systemd-logind[1850]: Session 6 logged out. Waiting for processes to exit. Dec 12 17:40:56.926976 systemd-logind[1850]: Removed session 6. Dec 12 17:40:57.018263 systemd[1]: Started sshd@4-10.200.20.21:22-10.200.16.10:53330.service - OpenSSH per-connection server daemon (10.200.16.10:53330). Dec 12 17:40:57.508979 sshd[2294]: Accepted publickey for core from 10.200.16.10 port 53330 ssh2: RSA SHA256:rv0ogpS37Fn9XgD1tbLDwSSen2nZukTXJG3iueJVyC4 Dec 12 17:40:57.510439 sshd-session[2294]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 17:40:57.514195 systemd-logind[1850]: New session 7 of user core. Dec 12 17:40:57.520130 systemd[1]: Started session-7.scope - Session 7 of User core. Dec 12 17:40:57.918485 sudo[2298]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Dec 12 17:40:57.918703 sudo[2298]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Dec 12 17:40:58.278242 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Dec 12 17:40:58.278353 systemd[1]: kubelet.service: Consumed 115ms CPU time, 107.8M memory peak. Dec 12 17:40:58.280264 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 12 17:40:58.299558 systemd[1]: Reload requested from client PID 2333 ('systemctl') (unit session-7.scope)... Dec 12 17:40:58.299571 systemd[1]: Reloading... Dec 12 17:40:58.388079 zram_generator::config[2379]: No configuration found. Dec 12 17:40:58.535401 systemd[1]: Reloading finished in 235 ms. Dec 12 17:40:58.563128 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Dec 12 17:40:58.563193 systemd[1]: kubelet.service: Failed with result 'signal'. Dec 12 17:40:58.563406 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Dec 12 17:40:58.563454 systemd[1]: kubelet.service: Consumed 68ms CPU time, 95M memory peak. Dec 12 17:40:58.564556 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 12 17:40:58.808893 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Dec 12 17:40:58.817346 (kubelet)[2445]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Dec 12 17:40:58.843040 kubelet[2445]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Dec 12 17:40:58.843040 kubelet[2445]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Dec 12 17:40:58.843040 kubelet[2445]: 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. Dec 12 17:40:58.843040 kubelet[2445]: I1212 17:40:58.842532 2445 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Dec 12 17:40:59.175136 kubelet[2445]: I1212 17:40:59.175042 2445 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Dec 12 17:40:59.175499 kubelet[2445]: I1212 17:40:59.175486 2445 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Dec 12 17:40:59.175998 kubelet[2445]: I1212 17:40:59.175977 2445 server.go:956] "Client rotation is on, will bootstrap in background" Dec 12 17:40:59.189912 kubelet[2445]: I1212 17:40:59.189884 2445 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Dec 12 17:40:59.197410 kubelet[2445]: I1212 17:40:59.197390 2445 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Dec 12 17:40:59.200837 kubelet[2445]: I1212 17:40:59.200609 2445 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Dec 12 17:40:59.201743 kubelet[2445]: I1212 17:40:59.201716 2445 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Dec 12 17:40:59.201940 kubelet[2445]: I1212 17:40:59.201826 2445 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.20.21","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} Dec 12 17:40:59.202080 kubelet[2445]: I1212 17:40:59.202068 2445 topology_manager.go:138] "Creating topology manager with none policy" Dec 12 17:40:59.202131 kubelet[2445]: I1212 17:40:59.202124 2445 container_manager_linux.go:303] "Creating device plugin manager" Dec 12 17:40:59.202820 kubelet[2445]: I1212 17:40:59.202805 2445 state_mem.go:36] "Initialized new in-memory state store" Dec 12 17:40:59.205613 kubelet[2445]: I1212 17:40:59.205600 2445 kubelet.go:480] "Attempting to sync node with API server" Dec 12 17:40:59.205696 kubelet[2445]: I1212 17:40:59.205686 2445 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Dec 12 17:40:59.205749 kubelet[2445]: I1212 17:40:59.205743 2445 kubelet.go:386] "Adding apiserver pod source" Dec 12 17:40:59.205796 kubelet[2445]: I1212 17:40:59.205788 2445 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Dec 12 17:40:59.206087 kubelet[2445]: E1212 17:40:59.206070 2445 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:40:59.206178 kubelet[2445]: E1212 17:40:59.206168 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:40:59.206981 kubelet[2445]: I1212 17:40:59.206779 2445 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.7" apiVersion="v1" Dec 12 17:40:59.207166 kubelet[2445]: I1212 17:40:59.207146 2445 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Dec 12 17:40:59.207199 kubelet[2445]: W1212 17:40:59.207193 2445 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Dec 12 17:40:59.209034 kubelet[2445]: I1212 17:40:59.208895 2445 watchdog_linux.go:99] "Systemd watchdog is not enabled" Dec 12 17:40:59.209034 kubelet[2445]: I1212 17:40:59.208927 2445 server.go:1289] "Started kubelet" Dec 12 17:40:59.209205 kubelet[2445]: I1212 17:40:59.209183 2445 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Dec 12 17:40:59.209851 kubelet[2445]: I1212 17:40:59.209836 2445 server.go:317] "Adding debug handlers to kubelet server" Dec 12 17:40:59.211471 kubelet[2445]: I1212 17:40:59.211427 2445 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Dec 12 17:40:59.211705 kubelet[2445]: I1212 17:40:59.211687 2445 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Dec 12 17:40:59.215046 kubelet[2445]: I1212 17:40:59.215031 2445 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Dec 12 17:40:59.216483 kubelet[2445]: E1212 17:40:59.215623 2445 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.21.1880889c6456a7c8 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.200.20.21,UID:10.200.20.21,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.200.20.21,},FirstTimestamp:2025-12-12 17:40:59.208910792 +0000 UTC m=+0.388867721,LastTimestamp:2025-12-12 17:40:59.208910792 +0000 UTC m=+0.388867721,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.200.20.21,}" Dec 12 17:40:59.218544 kubelet[2445]: I1212 17:40:59.218386 2445 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Dec 12 17:40:59.219117 kubelet[2445]: I1212 17:40:59.219099 2445 volume_manager.go:297] "Starting Kubelet Volume Manager" Dec 12 17:40:59.219253 kubelet[2445]: E1212 17:40:59.219238 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:40:59.219377 kubelet[2445]: I1212 17:40:59.219365 2445 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Dec 12 17:40:59.219424 kubelet[2445]: I1212 17:40:59.219414 2445 reconciler.go:26] "Reconciler: start to sync state" Dec 12 17:40:59.220071 kubelet[2445]: I1212 17:40:59.220058 2445 factory.go:223] Registration of the systemd container factory successfully Dec 12 17:40:59.220206 kubelet[2445]: I1212 17:40:59.220190 2445 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 Dec 12 17:40:59.222528 kubelet[2445]: I1212 17:40:59.222515 2445 factory.go:223] Registration of the containerd container factory successfully Dec 12 17:40:59.226718 kubelet[2445]: E1212 17:40:59.226688 2445 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.200.20.21\" 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" Dec 12 17:40:59.226798 kubelet[2445]: E1212 17:40:59.226778 2445 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Dec 12 17:40:59.250247 kubelet[2445]: I1212 17:40:59.250226 2445 cpu_manager.go:221] "Starting CPU manager" policy="none" Dec 12 17:40:59.250247 kubelet[2445]: I1212 17:40:59.250243 2445 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Dec 12 17:40:59.250324 kubelet[2445]: I1212 17:40:59.250259 2445 state_mem.go:36] "Initialized new in-memory state store" Dec 12 17:40:59.250487 kubelet[2445]: E1212 17:40:59.250467 2445 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.200.20.21\" not found" node="10.200.20.21" Dec 12 17:40:59.319713 kubelet[2445]: E1212 17:40:59.319684 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:40:59.347662 kubelet[2445]: I1212 17:40:59.347642 2445 policy_none.go:49] "None policy: Start" Dec 12 17:40:59.347662 kubelet[2445]: I1212 17:40:59.347668 2445 memory_manager.go:186] "Starting memorymanager" policy="None" Dec 12 17:40:59.347761 kubelet[2445]: I1212 17:40:59.347679 2445 state_mem.go:35] "Initializing new in-memory state store" Dec 12 17:40:59.359935 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Dec 12 17:40:59.361938 kubelet[2445]: I1212 17:40:59.361903 2445 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Dec 12 17:40:59.363031 kubelet[2445]: I1212 17:40:59.362943 2445 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Dec 12 17:40:59.363031 kubelet[2445]: I1212 17:40:59.362967 2445 status_manager.go:230] "Starting to sync pod status with apiserver" Dec 12 17:40:59.363031 kubelet[2445]: I1212 17:40:59.362995 2445 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Dec 12 17:40:59.363031 kubelet[2445]: I1212 17:40:59.363001 2445 kubelet.go:2436] "Starting kubelet main sync loop" Dec 12 17:40:59.363133 kubelet[2445]: E1212 17:40:59.363041 2445 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Dec 12 17:40:59.369200 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Dec 12 17:40:59.371776 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Dec 12 17:40:59.378613 kubelet[2445]: E1212 17:40:59.378596 2445 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Dec 12 17:40:59.379079 kubelet[2445]: I1212 17:40:59.378890 2445 eviction_manager.go:189] "Eviction manager: starting control loop" Dec 12 17:40:59.379079 kubelet[2445]: I1212 17:40:59.378904 2445 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Dec 12 17:40:59.379357 kubelet[2445]: I1212 17:40:59.379324 2445 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Dec 12 17:40:59.381079 kubelet[2445]: E1212 17:40:59.381061 2445 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Dec 12 17:40:59.381135 kubelet[2445]: E1212 17:40:59.381092 2445 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.20.21\" not found" Dec 12 17:40:59.480700 kubelet[2445]: I1212 17:40:59.480377 2445 kubelet_node_status.go:75] "Attempting to register node" node="10.200.20.21" Dec 12 17:40:59.489527 kubelet[2445]: I1212 17:40:59.489502 2445 kubelet_node_status.go:78] "Successfully registered node" node="10.200.20.21" Dec 12 17:40:59.489624 kubelet[2445]: E1212 17:40:59.489613 2445 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.200.20.21\": node \"10.200.20.21\" not found" Dec 12 17:40:59.532112 kubelet[2445]: E1212 17:40:59.532086 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:40:59.632237 kubelet[2445]: E1212 17:40:59.632204 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:40:59.733249 kubelet[2445]: E1212 17:40:59.733164 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:40:59.828131 sudo[2298]: pam_unix(sudo:session): session closed for user root Dec 12 17:40:59.834226 kubelet[2445]: E1212 17:40:59.834198 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:40:59.904812 sshd[2297]: Connection closed by 10.200.16.10 port 53330 Dec 12 17:40:59.905258 sshd-session[2294]: pam_unix(sshd:session): session closed for user core Dec 12 17:40:59.908006 systemd-logind[1850]: Session 7 logged out. Waiting for processes to exit. Dec 12 17:40:59.908151 systemd[1]: sshd@4-10.200.20.21:22-10.200.16.10:53330.service: Deactivated successfully. Dec 12 17:40:59.909541 systemd[1]: session-7.scope: Deactivated successfully. Dec 12 17:40:59.909703 systemd[1]: session-7.scope: Consumed 266ms CPU time, 71.9M memory peak. Dec 12 17:40:59.911549 systemd-logind[1850]: Removed session 7. Dec 12 17:40:59.934703 kubelet[2445]: E1212 17:40:59.934676 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.035370 kubelet[2445]: E1212 17:41:00.035066 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.135699 kubelet[2445]: E1212 17:41:00.135668 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.178849 kubelet[2445]: I1212 17:41:00.178825 2445 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Dec 12 17:41:00.179074 kubelet[2445]: I1212 17:41:00.178977 2445 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Dec 12 17:41:00.179074 kubelet[2445]: I1212 17:41:00.178999 2445 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Dec 12 17:41:00.179074 kubelet[2445]: I1212 17:41:00.178978 2445 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" 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" Dec 12 17:41:00.207065 kubelet[2445]: E1212 17:41:00.207039 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:00.235772 kubelet[2445]: E1212 17:41:00.235744 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.336484 kubelet[2445]: E1212 17:41:00.336366 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.436840 kubelet[2445]: E1212 17:41:00.436768 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.537484 kubelet[2445]: E1212 17:41:00.537454 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.638645 kubelet[2445]: E1212 17:41:00.638472 2445 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.21\" not found" Dec 12 17:41:00.739691 kubelet[2445]: I1212 17:41:00.739668 2445 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Dec 12 17:41:00.739992 containerd[1872]: time="2025-12-12T17:41:00.739881080Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Dec 12 17:41:00.740581 kubelet[2445]: I1212 17:41:00.740400 2445 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Dec 12 17:41:01.207306 kubelet[2445]: E1212 17:41:01.207274 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:01.207604 kubelet[2445]: I1212 17:41:01.207322 2445 apiserver.go:52] "Watching apiserver" Dec 12 17:41:01.228732 systemd[1]: Created slice kubepods-besteffort-podd8a7f419_e18c_4c79_a4cf_f3bd774fa8db.slice - libcontainer container kubepods-besteffort-podd8a7f419_e18c_4c79_a4cf_f3bd774fa8db.slice. Dec 12 17:41:01.231754 kubelet[2445]: I1212 17:41:01.231701 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ddfkz\" (UniqueName: \"kubernetes.io/projected/af07740a-3dd7-4493-8330-50016b2d2247-kube-api-access-ddfkz\") pod \"kube-flannel-ds-xff66\" (UID: \"af07740a-3dd7-4493-8330-50016b2d2247\") " pod="kube-flannel/kube-flannel-ds-xff66" Dec 12 17:41:01.231754 kubelet[2445]: I1212 17:41:01.231731 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/d8a7f419-e18c-4c79-a4cf-f3bd774fa8db-lib-modules\") pod \"kube-proxy-8qgrh\" (UID: \"d8a7f419-e18c-4c79-a4cf-f3bd774fa8db\") " pod="kube-system/kube-proxy-8qgrh" Dec 12 17:41:01.231754 kubelet[2445]: I1212 17:41:01.231747 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/af07740a-3dd7-4493-8330-50016b2d2247-flannel-cfg\") pod \"kube-flannel-ds-xff66\" (UID: \"af07740a-3dd7-4493-8330-50016b2d2247\") " pod="kube-flannel/kube-flannel-ds-xff66" Dec 12 17:41:01.231754 kubelet[2445]: I1212 17:41:01.231759 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/d8a7f419-e18c-4c79-a4cf-f3bd774fa8db-kube-proxy\") pod \"kube-proxy-8qgrh\" (UID: \"d8a7f419-e18c-4c79-a4cf-f3bd774fa8db\") " pod="kube-system/kube-proxy-8qgrh" Dec 12 17:41:01.231859 kubelet[2445]: I1212 17:41:01.231771 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/d8a7f419-e18c-4c79-a4cf-f3bd774fa8db-xtables-lock\") pod \"kube-proxy-8qgrh\" (UID: \"d8a7f419-e18c-4c79-a4cf-f3bd774fa8db\") " pod="kube-system/kube-proxy-8qgrh" Dec 12 17:41:01.231859 kubelet[2445]: I1212 17:41:01.231782 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g4nbk\" (UniqueName: \"kubernetes.io/projected/d8a7f419-e18c-4c79-a4cf-f3bd774fa8db-kube-api-access-g4nbk\") pod \"kube-proxy-8qgrh\" (UID: \"d8a7f419-e18c-4c79-a4cf-f3bd774fa8db\") " pod="kube-system/kube-proxy-8qgrh" Dec 12 17:41:01.231859 kubelet[2445]: I1212 17:41:01.231792 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/af07740a-3dd7-4493-8330-50016b2d2247-run\") pod \"kube-flannel-ds-xff66\" (UID: \"af07740a-3dd7-4493-8330-50016b2d2247\") " pod="kube-flannel/kube-flannel-ds-xff66" Dec 12 17:41:01.231859 kubelet[2445]: I1212 17:41:01.231806 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/af07740a-3dd7-4493-8330-50016b2d2247-cni-plugin\") pod \"kube-flannel-ds-xff66\" (UID: \"af07740a-3dd7-4493-8330-50016b2d2247\") " pod="kube-flannel/kube-flannel-ds-xff66" Dec 12 17:41:01.231859 kubelet[2445]: I1212 17:41:01.231817 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/af07740a-3dd7-4493-8330-50016b2d2247-cni\") pod \"kube-flannel-ds-xff66\" (UID: \"af07740a-3dd7-4493-8330-50016b2d2247\") " pod="kube-flannel/kube-flannel-ds-xff66" Dec 12 17:41:01.231930 kubelet[2445]: I1212 17:41:01.231829 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/af07740a-3dd7-4493-8330-50016b2d2247-xtables-lock\") pod \"kube-flannel-ds-xff66\" (UID: \"af07740a-3dd7-4493-8330-50016b2d2247\") " pod="kube-flannel/kube-flannel-ds-xff66" Dec 12 17:41:01.245399 systemd[1]: Created slice kubepods-burstable-podaf07740a_3dd7_4493_8330_50016b2d2247.slice - libcontainer container kubepods-burstable-podaf07740a_3dd7_4493_8330_50016b2d2247.slice. Dec 12 17:41:01.319984 kubelet[2445]: I1212 17:41:01.319961 2445 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Dec 12 17:41:01.544059 containerd[1872]: time="2025-12-12T17:41:01.543971520Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8qgrh,Uid:d8a7f419-e18c-4c79-a4cf-f3bd774fa8db,Namespace:kube-system,Attempt:0,}" Dec 12 17:41:01.550962 containerd[1872]: time="2025-12-12T17:41:01.550931480Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-xff66,Uid:af07740a-3dd7-4493-8330-50016b2d2247,Namespace:kube-flannel,Attempt:0,}" Dec 12 17:41:02.208300 kubelet[2445]: E1212 17:41:02.208268 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:02.454398 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3380007161.mount: Deactivated successfully. Dec 12 17:41:02.493601 containerd[1872]: time="2025-12-12T17:41:02.493149936Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 12 17:41:02.507346 containerd[1872]: time="2025-12-12T17:41:02.507324680Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Dec 12 17:41:02.511953 containerd[1872]: time="2025-12-12T17:41:02.511927200Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 12 17:41:02.517867 containerd[1872]: time="2025-12-12T17:41:02.517542952Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 12 17:41:02.523626 containerd[1872]: time="2025-12-12T17:41:02.523600808Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Dec 12 17:41:02.531227 containerd[1872]: time="2025-12-12T17:41:02.531199784Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 12 17:41:02.531639 containerd[1872]: time="2025-12-12T17:41:02.531616664Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 968.048376ms" Dec 12 17:41:02.532550 containerd[1872]: time="2025-12-12T17:41:02.532526376Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 975.846208ms" Dec 12 17:41:02.598977 containerd[1872]: time="2025-12-12T17:41:02.598951720Z" level=info msg="connecting to shim 3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383" address="unix:///run/containerd/s/9d40ebc20ea0a737104490a012b1f73bfb1dda53f3a6ddcd7ba9b7247467297f" namespace=k8s.io protocol=ttrpc version=3 Dec 12 17:41:02.608477 containerd[1872]: time="2025-12-12T17:41:02.607203576Z" level=info msg="connecting to shim 32205c2b91545e2174acbab7132a9f7026457a1080533347c50d0a5980dd68c0" address="unix:///run/containerd/s/532911e42df3f06a3c8339d571861b7f6422b272ec249793d6d0a9e06b6b0c80" namespace=k8s.io protocol=ttrpc version=3 Dec 12 17:41:02.629139 systemd[1]: Started cri-containerd-32205c2b91545e2174acbab7132a9f7026457a1080533347c50d0a5980dd68c0.scope - libcontainer container 32205c2b91545e2174acbab7132a9f7026457a1080533347c50d0a5980dd68c0. Dec 12 17:41:02.629984 systemd[1]: Started cri-containerd-3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383.scope - libcontainer container 3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383. Dec 12 17:41:02.654464 containerd[1872]: time="2025-12-12T17:41:02.654439888Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8qgrh,Uid:d8a7f419-e18c-4c79-a4cf-f3bd774fa8db,Namespace:kube-system,Attempt:0,} returns sandbox id \"32205c2b91545e2174acbab7132a9f7026457a1080533347c50d0a5980dd68c0\"" Dec 12 17:41:02.657278 containerd[1872]: time="2025-12-12T17:41:02.657257928Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.7\"" Dec 12 17:41:02.665759 containerd[1872]: time="2025-12-12T17:41:02.665733584Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-xff66,Uid:af07740a-3dd7-4493-8330-50016b2d2247,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383\"" Dec 12 17:41:03.208400 kubelet[2445]: E1212 17:41:03.208359 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:03.780301 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2424432293.mount: Deactivated successfully. Dec 12 17:41:04.081114 containerd[1872]: time="2025-12-12T17:41:04.080648872Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:04.084981 containerd[1872]: time="2025-12-12T17:41:04.084955656Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.7: active requests=0, bytes read=28258673" Dec 12 17:41:04.088775 containerd[1872]: time="2025-12-12T17:41:04.088746952Z" level=info msg="ImageCreate event name:\"sha256:78ccb937011a53894db229033fd54e237d478ec85315f8b08e5dcaa0f737111b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:04.094008 containerd[1872]: time="2025-12-12T17:41:04.093979152Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:ec25702b19026e9c0d339bc1c3bd231435a59f28b5fccb21e1b1078a357380f5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:04.094523 containerd[1872]: time="2025-12-12T17:41:04.094232904Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.7\" with image id \"sha256:78ccb937011a53894db229033fd54e237d478ec85315f8b08e5dcaa0f737111b\", repo tag \"registry.k8s.io/kube-proxy:v1.33.7\", repo digest \"registry.k8s.io/kube-proxy@sha256:ec25702b19026e9c0d339bc1c3bd231435a59f28b5fccb21e1b1078a357380f5\", size \"28257692\" in 1.436831592s" Dec 12 17:41:04.094523 containerd[1872]: time="2025-12-12T17:41:04.094253936Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.7\" returns image reference \"sha256:78ccb937011a53894db229033fd54e237d478ec85315f8b08e5dcaa0f737111b\"" Dec 12 17:41:04.095678 containerd[1872]: time="2025-12-12T17:41:04.095574416Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Dec 12 17:41:04.104039 containerd[1872]: time="2025-12-12T17:41:04.102684068Z" level=info msg="CreateContainer within sandbox \"32205c2b91545e2174acbab7132a9f7026457a1080533347c50d0a5980dd68c0\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Dec 12 17:41:04.124892 containerd[1872]: time="2025-12-12T17:41:04.124867236Z" level=info msg="Container 5e9c74bc2f8c4663870cff082a0bb1da6c33f4b6a896d3093b16d368bff13c82: CDI devices from CRI Config.CDIDevices: []" Dec 12 17:41:04.129219 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount580460824.mount: Deactivated successfully. Dec 12 17:41:04.147699 containerd[1872]: time="2025-12-12T17:41:04.147672594Z" level=info msg="CreateContainer within sandbox \"32205c2b91545e2174acbab7132a9f7026457a1080533347c50d0a5980dd68c0\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"5e9c74bc2f8c4663870cff082a0bb1da6c33f4b6a896d3093b16d368bff13c82\"" Dec 12 17:41:04.149055 containerd[1872]: time="2025-12-12T17:41:04.148100089Z" level=info msg="StartContainer for \"5e9c74bc2f8c4663870cff082a0bb1da6c33f4b6a896d3093b16d368bff13c82\"" Dec 12 17:41:04.149055 containerd[1872]: time="2025-12-12T17:41:04.148938715Z" level=info msg="connecting to shim 5e9c74bc2f8c4663870cff082a0bb1da6c33f4b6a896d3093b16d368bff13c82" address="unix:///run/containerd/s/532911e42df3f06a3c8339d571861b7f6422b272ec249793d6d0a9e06b6b0c80" protocol=ttrpc version=3 Dec 12 17:41:04.164138 systemd[1]: Started cri-containerd-5e9c74bc2f8c4663870cff082a0bb1da6c33f4b6a896d3093b16d368bff13c82.scope - libcontainer container 5e9c74bc2f8c4663870cff082a0bb1da6c33f4b6a896d3093b16d368bff13c82. Dec 12 17:41:04.208710 kubelet[2445]: E1212 17:41:04.208685 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:04.218683 containerd[1872]: time="2025-12-12T17:41:04.218628958Z" level=info msg="StartContainer for \"5e9c74bc2f8c4663870cff082a0bb1da6c33f4b6a896d3093b16d368bff13c82\" returns successfully" Dec 12 17:41:04.387791 kubelet[2445]: I1212 17:41:04.387681 2445 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-8qgrh" podStartSLOduration=3.949410571 podStartE2EDuration="5.387668715s" podCreationTimestamp="2025-12-12 17:40:59 +0000 UTC" firstStartedPulling="2025-12-12 17:41:02.656638224 +0000 UTC m=+3.836595153" lastFinishedPulling="2025-12-12 17:41:04.094896368 +0000 UTC m=+5.274853297" observedRunningTime="2025-12-12 17:41:04.387636132 +0000 UTC m=+5.567593061" watchObservedRunningTime="2025-12-12 17:41:04.387668715 +0000 UTC m=+5.567625644" Dec 12 17:41:05.209823 kubelet[2445]: E1212 17:41:05.209787 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:05.432635 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount46793491.mount: Deactivated successfully. Dec 12 17:41:05.525249 containerd[1872]: time="2025-12-12T17:41:05.525157471Z" 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\"}" Dec 12 17:41:05.529177 containerd[1872]: time="2025-12-12T17:41:05.529152706Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Dec 12 17:41:05.532688 containerd[1872]: time="2025-12-12T17:41:05.532663174Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:05.539282 containerd[1872]: time="2025-12-12T17:41:05.539257779Z" 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.443541403s" Dec 12 17:41:05.539312 containerd[1872]: time="2025-12-12T17:41:05.539284420Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Dec 12 17:41:05.539916 containerd[1872]: time="2025-12-12T17:41:05.539381655Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:05.546876 containerd[1872]: time="2025-12-12T17:41:05.546856413Z" level=info msg="CreateContainer within sandbox \"3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Dec 12 17:41:05.576450 containerd[1872]: time="2025-12-12T17:41:05.576424844Z" level=info msg="Container 8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac: CDI devices from CRI Config.CDIDevices: []" Dec 12 17:41:05.602034 containerd[1872]: time="2025-12-12T17:41:05.601953911Z" level=info msg="CreateContainer within sandbox \"3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac\"" Dec 12 17:41:05.602898 containerd[1872]: time="2025-12-12T17:41:05.602699884Z" level=info msg="StartContainer for \"8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac\"" Dec 12 17:41:05.603411 containerd[1872]: time="2025-12-12T17:41:05.603372728Z" level=info msg="connecting to shim 8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac" address="unix:///run/containerd/s/9d40ebc20ea0a737104490a012b1f73bfb1dda53f3a6ddcd7ba9b7247467297f" protocol=ttrpc version=3 Dec 12 17:41:05.622117 systemd[1]: Started cri-containerd-8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac.scope - libcontainer container 8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac. Dec 12 17:41:05.640229 systemd[1]: cri-containerd-8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac.scope: Deactivated successfully. Dec 12 17:41:05.644265 containerd[1872]: time="2025-12-12T17:41:05.644241418Z" level=info msg="received container exit event container_id:\"8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac\" id:\"8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac\" pid:2783 exited_at:{seconds:1765561265 nanos:642620404}" Dec 12 17:41:05.644745 containerd[1872]: time="2025-12-12T17:41:05.644727200Z" level=info msg="StartContainer for \"8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac\" returns successfully" Dec 12 17:41:05.656441 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-8d613f90054938051db0fa483035b56bc4f91ee2c8ca57112dfa678410deebac-rootfs.mount: Deactivated successfully. Dec 12 17:41:06.210473 kubelet[2445]: E1212 17:41:06.210427 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:06.378339 containerd[1872]: time="2025-12-12T17:41:06.378305928Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Dec 12 17:41:07.210683 kubelet[2445]: E1212 17:41:07.210652 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:08.211553 kubelet[2445]: E1212 17:41:08.211514 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:08.400134 containerd[1872]: time="2025-12-12T17:41:08.400093615Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:08.404007 containerd[1872]: time="2025-12-12T17:41:08.403981838Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Dec 12 17:41:08.408603 containerd[1872]: time="2025-12-12T17:41:08.408580146Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:08.414900 containerd[1872]: time="2025-12-12T17:41:08.414873694Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:08.415584 containerd[1872]: time="2025-12-12T17:41:08.415483736Z" 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.037145119s" Dec 12 17:41:08.415584 containerd[1872]: time="2025-12-12T17:41:08.415508160Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Dec 12 17:41:08.424089 containerd[1872]: time="2025-12-12T17:41:08.424064141Z" level=info msg="CreateContainer within sandbox \"3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Dec 12 17:41:08.443030 containerd[1872]: time="2025-12-12T17:41:08.442379570Z" level=info msg="Container e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597: CDI devices from CRI Config.CDIDevices: []" Dec 12 17:41:08.461883 containerd[1872]: time="2025-12-12T17:41:08.461539127Z" level=info msg="CreateContainer within sandbox \"3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597\"" Dec 12 17:41:08.461883 containerd[1872]: time="2025-12-12T17:41:08.461843112Z" level=info msg="StartContainer for \"e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597\"" Dec 12 17:41:08.462494 containerd[1872]: time="2025-12-12T17:41:08.462470346Z" level=info msg="connecting to shim e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597" address="unix:///run/containerd/s/9d40ebc20ea0a737104490a012b1f73bfb1dda53f3a6ddcd7ba9b7247467297f" protocol=ttrpc version=3 Dec 12 17:41:08.482131 systemd[1]: Started cri-containerd-e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597.scope - libcontainer container e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597. Dec 12 17:41:08.499764 systemd[1]: cri-containerd-e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597.scope: Deactivated successfully. Dec 12 17:41:08.505152 containerd[1872]: time="2025-12-12T17:41:08.505085111Z" level=info msg="received container exit event container_id:\"e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597\" id:\"e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597\" pid:2860 exited_at:{seconds:1765561268 nanos:500249268}" Dec 12 17:41:08.505883 containerd[1872]: time="2025-12-12T17:41:08.505864661Z" level=info msg="StartContainer for \"e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597\" returns successfully" Dec 12 17:41:08.518210 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e501a044faf8c3371e239c419f11740758e6ccf1c82f09b340a52f8bfb037597-rootfs.mount: Deactivated successfully. Dec 12 17:41:08.556472 kubelet[2445]: I1212 17:41:08.555896 2445 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Dec 12 17:41:09.212075 kubelet[2445]: E1212 17:41:09.212032 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:09.392828 containerd[1872]: time="2025-12-12T17:41:09.392798702Z" level=info msg="CreateContainer within sandbox \"3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Dec 12 17:41:09.416036 containerd[1872]: time="2025-12-12T17:41:09.415856283Z" level=info msg="Container c3ad1d881a8f47ac65182ab6b4b43d2e615647346689c084a679ff9802869ecd: CDI devices from CRI Config.CDIDevices: []" Dec 12 17:41:09.435033 containerd[1872]: time="2025-12-12T17:41:09.434944181Z" level=info msg="CreateContainer within sandbox \"3d5a26a3bbb164cec69727faa71caddec46acb2669fc615ea82b0154d42ea383\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"c3ad1d881a8f47ac65182ab6b4b43d2e615647346689c084a679ff9802869ecd\"" Dec 12 17:41:09.435389 containerd[1872]: time="2025-12-12T17:41:09.435369194Z" level=info msg="StartContainer for \"c3ad1d881a8f47ac65182ab6b4b43d2e615647346689c084a679ff9802869ecd\"" Dec 12 17:41:09.435927 containerd[1872]: time="2025-12-12T17:41:09.435906857Z" level=info msg="connecting to shim c3ad1d881a8f47ac65182ab6b4b43d2e615647346689c084a679ff9802869ecd" address="unix:///run/containerd/s/9d40ebc20ea0a737104490a012b1f73bfb1dda53f3a6ddcd7ba9b7247467297f" protocol=ttrpc version=3 Dec 12 17:41:09.467119 systemd[1]: Started cri-containerd-c3ad1d881a8f47ac65182ab6b4b43d2e615647346689c084a679ff9802869ecd.scope - libcontainer container c3ad1d881a8f47ac65182ab6b4b43d2e615647346689c084a679ff9802869ecd. Dec 12 17:41:09.492889 containerd[1872]: time="2025-12-12T17:41:09.492831728Z" level=info msg="StartContainer for \"c3ad1d881a8f47ac65182ab6b4b43d2e615647346689c084a679ff9802869ecd\" returns successfully" Dec 12 17:41:10.212950 kubelet[2445]: E1212 17:41:10.212836 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:10.666604 systemd-networkd[1474]: flannel.1: Link UP Dec 12 17:41:10.666609 systemd-networkd[1474]: flannel.1: Gained carrier Dec 12 17:41:11.213972 kubelet[2445]: E1212 17:41:11.213871 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:11.931196 systemd-networkd[1474]: flannel.1: Gained IPv6LL Dec 12 17:41:12.214876 kubelet[2445]: E1212 17:41:12.214842 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:13.215764 kubelet[2445]: E1212 17:41:13.215720 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:13.766669 kubelet[2445]: I1212 17:41:13.766204 2445 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-xff66" podStartSLOduration=9.016487679 podStartE2EDuration="14.766185304s" podCreationTimestamp="2025-12-12 17:40:59 +0000 UTC" firstStartedPulling="2025-12-12 17:41:02.666374304 +0000 UTC m=+3.846331233" lastFinishedPulling="2025-12-12 17:41:08.416071929 +0000 UTC m=+9.596028858" observedRunningTime="2025-12-12 17:41:10.40214409 +0000 UTC m=+11.582101019" watchObservedRunningTime="2025-12-12 17:41:13.766185304 +0000 UTC m=+14.946142233" Dec 12 17:41:13.779333 systemd[1]: Created slice kubepods-besteffort-poddc79917c_389d_4651_8c93_016a4295dd3c.slice - libcontainer container kubepods-besteffort-poddc79917c_389d_4651_8c93_016a4295dd3c.slice. Dec 12 17:41:13.796752 kubelet[2445]: I1212 17:41:13.796713 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5vqzw\" (UniqueName: \"kubernetes.io/projected/dc79917c-389d-4651-8c93-016a4295dd3c-kube-api-access-5vqzw\") pod \"nginx-deployment-7fcdb87857-cfkbs\" (UID: \"dc79917c-389d-4651-8c93-016a4295dd3c\") " pod="default/nginx-deployment-7fcdb87857-cfkbs" Dec 12 17:41:14.082785 containerd[1872]: time="2025-12-12T17:41:14.082678251Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-cfkbs,Uid:dc79917c-389d-4651-8c93-016a4295dd3c,Namespace:default,Attempt:0,}" Dec 12 17:41:14.106101 systemd-networkd[1474]: cni0: Link UP Dec 12 17:41:14.106107 systemd-networkd[1474]: cni0: Gained carrier Dec 12 17:41:14.109866 systemd-networkd[1474]: cni0: Lost carrier Dec 12 17:41:14.127975 systemd-networkd[1474]: vetha4f84f6d: Link UP Dec 12 17:41:14.137075 kernel: cni0: port 1(vetha4f84f6d) entered blocking state Dec 12 17:41:14.137131 kernel: cni0: port 1(vetha4f84f6d) entered disabled state Dec 12 17:41:14.137143 kernel: vetha4f84f6d: entered allmulticast mode Dec 12 17:41:14.139666 kernel: vetha4f84f6d: entered promiscuous mode Dec 12 17:41:14.148145 kernel: cni0: port 1(vetha4f84f6d) entered blocking state Dec 12 17:41:14.148194 kernel: cni0: port 1(vetha4f84f6d) entered forwarding state Dec 12 17:41:14.148759 systemd-networkd[1474]: vetha4f84f6d: Gained carrier Dec 12 17:41:14.148896 systemd-networkd[1474]: cni0: Gained carrier Dec 12 17:41:14.150702 containerd[1872]: 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"} Dec 12 17:41:14.150702 containerd[1872]: delegateAdd: netconf sent to delegate plugin: Dec 12 17:41:14.202692 containerd[1872]: {"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-12-12T17:41:14.202624141Z" level=info msg="connecting to shim a941708b2e8fabd0173708a0103fb9aa4544c76e29d9ae25e45e69982891d99b" address="unix:///run/containerd/s/059f5fe16465218fd170c3f8fc0cbaab1ed83c1f3e0d5264f2aab18131175fd4" namespace=k8s.io protocol=ttrpc version=3 Dec 12 17:41:14.216864 kubelet[2445]: E1212 17:41:14.216727 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:14.231142 systemd[1]: Started cri-containerd-a941708b2e8fabd0173708a0103fb9aa4544c76e29d9ae25e45e69982891d99b.scope - libcontainer container a941708b2e8fabd0173708a0103fb9aa4544c76e29d9ae25e45e69982891d99b. Dec 12 17:41:14.257908 containerd[1872]: time="2025-12-12T17:41:14.257874661Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-cfkbs,Uid:dc79917c-389d-4651-8c93-016a4295dd3c,Namespace:default,Attempt:0,} returns sandbox id \"a941708b2e8fabd0173708a0103fb9aa4544c76e29d9ae25e45e69982891d99b\"" Dec 12 17:41:14.259098 containerd[1872]: time="2025-12-12T17:41:14.259065586Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Dec 12 17:41:15.217690 kubelet[2445]: E1212 17:41:15.217648 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:15.259183 systemd-networkd[1474]: cni0: Gained IPv6LL Dec 12 17:41:15.375035 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Dec 12 17:41:15.643231 systemd-networkd[1474]: vetha4f84f6d: Gained IPv6LL Dec 12 17:41:16.218636 kubelet[2445]: E1212 17:41:16.218509 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:16.374399 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4035300981.mount: Deactivated successfully. Dec 12 17:41:17.155836 containerd[1872]: time="2025-12-12T17:41:17.155792204Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:17.162240 containerd[1872]: time="2025-12-12T17:41:17.162210873Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017389" Dec 12 17:41:17.167651 containerd[1872]: time="2025-12-12T17:41:17.167613023Z" level=info msg="ImageCreate event name:\"sha256:7711a8752e1c04bf70ca3a76fb7dcaf2434e812e9334c22c59e7ff007592e25a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:17.172972 containerd[1872]: time="2025-12-12T17:41:17.172927602Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:3db8be616067ff6bd4534d63c0a1427862e285068488ddccf319982871e49aac\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:17.173765 containerd[1872]: time="2025-12-12T17:41:17.173449690Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7711a8752e1c04bf70ca3a76fb7dcaf2434e812e9334c22c59e7ff007592e25a\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:3db8be616067ff6bd4534d63c0a1427862e285068488ddccf319982871e49aac\", size \"70017267\" in 2.914346879s" Dec 12 17:41:17.173765 containerd[1872]: time="2025-12-12T17:41:17.173474747Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7711a8752e1c04bf70ca3a76fb7dcaf2434e812e9334c22c59e7ff007592e25a\"" Dec 12 17:41:17.182144 containerd[1872]: time="2025-12-12T17:41:17.182104500Z" level=info msg="CreateContainer within sandbox \"a941708b2e8fabd0173708a0103fb9aa4544c76e29d9ae25e45e69982891d99b\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Dec 12 17:41:17.209224 containerd[1872]: time="2025-12-12T17:41:17.209072248Z" level=info msg="Container ba5ff7dedd4bf71b0e2b2f938281b2d64637316a0be31a131bc304bc529a5764: CDI devices from CRI Config.CDIDevices: []" Dec 12 17:41:17.219369 kubelet[2445]: E1212 17:41:17.219350 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:17.228183 containerd[1872]: time="2025-12-12T17:41:17.228158210Z" level=info msg="CreateContainer within sandbox \"a941708b2e8fabd0173708a0103fb9aa4544c76e29d9ae25e45e69982891d99b\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"ba5ff7dedd4bf71b0e2b2f938281b2d64637316a0be31a131bc304bc529a5764\"" Dec 12 17:41:17.228518 containerd[1872]: time="2025-12-12T17:41:17.228426466Z" level=info msg="StartContainer for \"ba5ff7dedd4bf71b0e2b2f938281b2d64637316a0be31a131bc304bc529a5764\"" Dec 12 17:41:17.228945 containerd[1872]: time="2025-12-12T17:41:17.228918193Z" level=info msg="connecting to shim ba5ff7dedd4bf71b0e2b2f938281b2d64637316a0be31a131bc304bc529a5764" address="unix:///run/containerd/s/059f5fe16465218fd170c3f8fc0cbaab1ed83c1f3e0d5264f2aab18131175fd4" protocol=ttrpc version=3 Dec 12 17:41:17.246118 systemd[1]: Started cri-containerd-ba5ff7dedd4bf71b0e2b2f938281b2d64637316a0be31a131bc304bc529a5764.scope - libcontainer container ba5ff7dedd4bf71b0e2b2f938281b2d64637316a0be31a131bc304bc529a5764. Dec 12 17:41:17.273393 containerd[1872]: time="2025-12-12T17:41:17.273360518Z" level=info msg="StartContainer for \"ba5ff7dedd4bf71b0e2b2f938281b2d64637316a0be31a131bc304bc529a5764\" returns successfully" Dec 12 17:41:18.116037 update_engine[1855]: I20251212 17:41:18.115908 1855 update_attempter.cc:509] Updating boot flags... Dec 12 17:41:18.219920 kubelet[2445]: E1212 17:41:18.219888 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:19.206842 kubelet[2445]: E1212 17:41:19.206738 2445 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:19.220165 kubelet[2445]: E1212 17:41:19.220138 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:20.221078 kubelet[2445]: E1212 17:41:20.221038 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:21.221654 kubelet[2445]: E1212 17:41:21.221609 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:22.222853 kubelet[2445]: E1212 17:41:22.222735 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:22.859089 kubelet[2445]: I1212 17:41:22.859044 2445 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-cfkbs" podStartSLOduration=6.943449058 podStartE2EDuration="9.859012671s" podCreationTimestamp="2025-12-12 17:41:13 +0000 UTC" firstStartedPulling="2025-12-12 17:41:14.258540185 +0000 UTC m=+15.438497114" lastFinishedPulling="2025-12-12 17:41:17.174103798 +0000 UTC m=+18.354060727" observedRunningTime="2025-12-12 17:41:17.409073164 +0000 UTC m=+18.589030093" watchObservedRunningTime="2025-12-12 17:41:22.859012671 +0000 UTC m=+24.038969600" Dec 12 17:41:22.871180 systemd[1]: Created slice kubepods-besteffort-pode610276a_2d61_4754_ab39_fde2f1dda7fb.slice - libcontainer container kubepods-besteffort-pode610276a_2d61_4754_ab39_fde2f1dda7fb.slice. Dec 12 17:41:22.946882 kubelet[2445]: I1212 17:41:22.946832 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cm6nh\" (UniqueName: \"kubernetes.io/projected/e610276a-2d61-4754-ab39-fde2f1dda7fb-kube-api-access-cm6nh\") pod \"nfs-server-provisioner-0\" (UID: \"e610276a-2d61-4754-ab39-fde2f1dda7fb\") " pod="default/nfs-server-provisioner-0" Dec 12 17:41:22.946882 kubelet[2445]: I1212 17:41:22.946860 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/e610276a-2d61-4754-ab39-fde2f1dda7fb-data\") pod \"nfs-server-provisioner-0\" (UID: \"e610276a-2d61-4754-ab39-fde2f1dda7fb\") " pod="default/nfs-server-provisioner-0" Dec 12 17:41:23.175139 containerd[1872]: time="2025-12-12T17:41:23.174978673Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:e610276a-2d61-4754-ab39-fde2f1dda7fb,Namespace:default,Attempt:0,}" Dec 12 17:41:23.202397 systemd-networkd[1474]: veth36d8f656: Link UP Dec 12 17:41:23.209169 kernel: cni0: port 2(veth36d8f656) entered blocking state Dec 12 17:41:23.209229 kernel: cni0: port 2(veth36d8f656) entered disabled state Dec 12 17:41:23.213559 kernel: veth36d8f656: entered allmulticast mode Dec 12 17:41:23.216169 kernel: veth36d8f656: entered promiscuous mode Dec 12 17:41:23.225173 kernel: cni0: port 2(veth36d8f656) entered blocking state Dec 12 17:41:23.225228 kernel: cni0: port 2(veth36d8f656) entered forwarding state Dec 12 17:41:23.225229 systemd-networkd[1474]: veth36d8f656: Gained carrier Dec 12 17:41:23.225438 kubelet[2445]: E1212 17:41:23.225349 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:23.226983 containerd[1872]: 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"} Dec 12 17:41:23.226983 containerd[1872]: delegateAdd: netconf sent to delegate plugin: Dec 12 17:41:23.282747 containerd[1872]: {"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-12-12T17:41:23.282689712Z" level=info msg="connecting to shim cdc9e0e7a802e21e8c69f7d6ec36ff26de99f16c540a1a059eab8663a6a5b120" address="unix:///run/containerd/s/929acc77809512f4ffec2e25cc6f59513e57b0df454b51f2628b31f836bc640a" namespace=k8s.io protocol=ttrpc version=3 Dec 12 17:41:23.304133 systemd[1]: Started cri-containerd-cdc9e0e7a802e21e8c69f7d6ec36ff26de99f16c540a1a059eab8663a6a5b120.scope - libcontainer container cdc9e0e7a802e21e8c69f7d6ec36ff26de99f16c540a1a059eab8663a6a5b120. Dec 12 17:41:23.334423 containerd[1872]: time="2025-12-12T17:41:23.334392858Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:e610276a-2d61-4754-ab39-fde2f1dda7fb,Namespace:default,Attempt:0,} returns sandbox id \"cdc9e0e7a802e21e8c69f7d6ec36ff26de99f16c540a1a059eab8663a6a5b120\"" Dec 12 17:41:23.335873 containerd[1872]: time="2025-12-12T17:41:23.335845518Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Dec 12 17:41:24.226224 kubelet[2445]: E1212 17:41:24.226188 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:24.924136 systemd-networkd[1474]: veth36d8f656: Gained IPv6LL Dec 12 17:41:25.227028 kubelet[2445]: E1212 17:41:25.226923 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:25.322109 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1821052145.mount: Deactivated successfully. Dec 12 17:41:26.227907 kubelet[2445]: E1212 17:41:26.227870 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:27.063055 containerd[1872]: time="2025-12-12T17:41:27.062564084Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:27.066745 containerd[1872]: time="2025-12-12T17:41:27.066710562Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Dec 12 17:41:27.071049 containerd[1872]: time="2025-12-12T17:41:27.071001669Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:27.076817 containerd[1872]: time="2025-12-12T17:41:27.076780509Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:27.077345 containerd[1872]: time="2025-12-12T17:41:27.077200298Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.741323291s" Dec 12 17:41:27.077345 containerd[1872]: time="2025-12-12T17:41:27.077227443Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Dec 12 17:41:27.085294 containerd[1872]: time="2025-12-12T17:41:27.085268592Z" level=info msg="CreateContainer within sandbox \"cdc9e0e7a802e21e8c69f7d6ec36ff26de99f16c540a1a059eab8663a6a5b120\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Dec 12 17:41:27.108755 containerd[1872]: time="2025-12-12T17:41:27.108673819Z" level=info msg="Container 7beff61270f9f1b7bcffcd1cd205d012065d4d29485464fc94efb4fe6f32907f: CDI devices from CRI Config.CDIDevices: []" Dec 12 17:41:27.133063 containerd[1872]: time="2025-12-12T17:41:27.133038146Z" level=info msg="CreateContainer within sandbox \"cdc9e0e7a802e21e8c69f7d6ec36ff26de99f16c540a1a059eab8663a6a5b120\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"7beff61270f9f1b7bcffcd1cd205d012065d4d29485464fc94efb4fe6f32907f\"" Dec 12 17:41:27.133490 containerd[1872]: time="2025-12-12T17:41:27.133411974Z" level=info msg="StartContainer for \"7beff61270f9f1b7bcffcd1cd205d012065d4d29485464fc94efb4fe6f32907f\"" Dec 12 17:41:27.133992 containerd[1872]: time="2025-12-12T17:41:27.133967031Z" level=info msg="connecting to shim 7beff61270f9f1b7bcffcd1cd205d012065d4d29485464fc94efb4fe6f32907f" address="unix:///run/containerd/s/929acc77809512f4ffec2e25cc6f59513e57b0df454b51f2628b31f836bc640a" protocol=ttrpc version=3 Dec 12 17:41:27.151118 systemd[1]: Started cri-containerd-7beff61270f9f1b7bcffcd1cd205d012065d4d29485464fc94efb4fe6f32907f.scope - libcontainer container 7beff61270f9f1b7bcffcd1cd205d012065d4d29485464fc94efb4fe6f32907f. Dec 12 17:41:27.176351 containerd[1872]: time="2025-12-12T17:41:27.176314651Z" level=info msg="StartContainer for \"7beff61270f9f1b7bcffcd1cd205d012065d4d29485464fc94efb4fe6f32907f\" returns successfully" Dec 12 17:41:27.228312 kubelet[2445]: E1212 17:41:27.228286 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:28.229106 kubelet[2445]: E1212 17:41:28.229064 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:29.229934 kubelet[2445]: E1212 17:41:29.229901 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:30.230507 kubelet[2445]: E1212 17:41:30.230457 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:31.230788 kubelet[2445]: E1212 17:41:31.230746 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:32.231817 kubelet[2445]: E1212 17:41:32.231773 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:32.405709 kubelet[2445]: I1212 17:41:32.405659 2445 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.662986559 podStartE2EDuration="10.405643347s" podCreationTimestamp="2025-12-12 17:41:22 +0000 UTC" firstStartedPulling="2025-12-12 17:41:23.335431129 +0000 UTC m=+24.515388058" lastFinishedPulling="2025-12-12 17:41:27.078087917 +0000 UTC m=+28.258044846" observedRunningTime="2025-12-12 17:41:27.439101918 +0000 UTC m=+28.619058847" watchObservedRunningTime="2025-12-12 17:41:32.405643347 +0000 UTC m=+33.585600284" Dec 12 17:41:32.416700 systemd[1]: Created slice kubepods-besteffort-pod6d31aab1_f996_44e3_9d63_0426ccfd2511.slice - libcontainer container kubepods-besteffort-pod6d31aab1_f996_44e3_9d63_0426ccfd2511.slice. Dec 12 17:41:32.495664 kubelet[2445]: I1212 17:41:32.495489 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c917f7b6-7acb-4e17-a276-677b85727ac3\" (UniqueName: \"kubernetes.io/nfs/6d31aab1-f996-44e3-9d63-0426ccfd2511-pvc-c917f7b6-7acb-4e17-a276-677b85727ac3\") pod \"test-pod-1\" (UID: \"6d31aab1-f996-44e3-9d63-0426ccfd2511\") " pod="default/test-pod-1" Dec 12 17:41:32.495664 kubelet[2445]: I1212 17:41:32.495614 2445 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dkl5c\" (UniqueName: \"kubernetes.io/projected/6d31aab1-f996-44e3-9d63-0426ccfd2511-kube-api-access-dkl5c\") pod \"test-pod-1\" (UID: \"6d31aab1-f996-44e3-9d63-0426ccfd2511\") " pod="default/test-pod-1" Dec 12 17:41:32.700059 kernel: netfs: FS-Cache loaded Dec 12 17:41:32.763914 kernel: RPC: Registered named UNIX socket transport module. Dec 12 17:41:32.764005 kernel: RPC: Registered udp transport module. Dec 12 17:41:32.764037 kernel: RPC: Registered tcp transport module. Dec 12 17:41:32.766281 kernel: RPC: Registered tcp-with-tls transport module. Dec 12 17:41:32.772376 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 12 17:41:33.009177 kernel: NFS: Registering the id_resolver key type Dec 12 17:41:33.009262 kernel: Key type id_resolver registered Dec 12 17:41:33.009279 kernel: Key type id_legacy registered Dec 12 17:41:33.224425 nfsidmap[3487]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.2-a-8645460de3' Dec 12 17:41:33.232493 kubelet[2445]: E1212 17:41:33.232460 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:33.234243 nfsidmap[3488]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.2-a-8645460de3' Dec 12 17:41:33.242794 nfsrahead[3491]: setting /var/lib/kubelet/pods/6d31aab1-f996-44e3-9d63-0426ccfd2511/volumes/kubernetes.io~nfs/pvc-c917f7b6-7acb-4e17-a276-677b85727ac3 readahead to 128 Dec 12 17:41:33.318868 containerd[1872]: time="2025-12-12T17:41:33.318835882Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:6d31aab1-f996-44e3-9d63-0426ccfd2511,Namespace:default,Attempt:0,}" Dec 12 17:41:33.339226 systemd-networkd[1474]: vethb1d03b24: Link UP Dec 12 17:41:33.347444 kernel: cni0: port 3(vethb1d03b24) entered blocking state Dec 12 17:41:33.347495 kernel: cni0: port 3(vethb1d03b24) entered disabled state Dec 12 17:41:33.350174 kernel: vethb1d03b24: entered allmulticast mode Dec 12 17:41:33.352689 kernel: vethb1d03b24: entered promiscuous mode Dec 12 17:41:33.362516 kernel: cni0: port 3(vethb1d03b24) entered blocking state Dec 12 17:41:33.362563 kernel: cni0: port 3(vethb1d03b24) entered forwarding state Dec 12 17:41:33.362608 systemd-networkd[1474]: vethb1d03b24: Gained carrier Dec 12 17:41:33.364855 containerd[1872]: 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"} Dec 12 17:41:33.364855 containerd[1872]: delegateAdd: netconf sent to delegate plugin: Dec 12 17:41:33.407167 containerd[1872]: {"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-12-12T17:41:33.407140921Z" level=info msg="connecting to shim b79a47f7ccbfa7bd83dd7ef14139201ceb2a39182fb5aea51b8a204332967858" address="unix:///run/containerd/s/6d2e52410ba85ad81d960c37c08c6fb41c0bca7b47e52ed5ca4b3b683b0a20d8" namespace=k8s.io protocol=ttrpc version=3 Dec 12 17:41:33.422139 systemd[1]: Started cri-containerd-b79a47f7ccbfa7bd83dd7ef14139201ceb2a39182fb5aea51b8a204332967858.scope - libcontainer container b79a47f7ccbfa7bd83dd7ef14139201ceb2a39182fb5aea51b8a204332967858. Dec 12 17:41:33.447626 containerd[1872]: time="2025-12-12T17:41:33.447601229Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:6d31aab1-f996-44e3-9d63-0426ccfd2511,Namespace:default,Attempt:0,} returns sandbox id \"b79a47f7ccbfa7bd83dd7ef14139201ceb2a39182fb5aea51b8a204332967858\"" Dec 12 17:41:33.448644 containerd[1872]: time="2025-12-12T17:41:33.448606211Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Dec 12 17:41:33.850406 containerd[1872]: time="2025-12-12T17:41:33.850346355Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 12 17:41:33.854040 containerd[1872]: time="2025-12-12T17:41:33.853931759Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Dec 12 17:41:33.855702 containerd[1872]: time="2025-12-12T17:41:33.855595970Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7711a8752e1c04bf70ca3a76fb7dcaf2434e812e9334c22c59e7ff007592e25a\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:3db8be616067ff6bd4534d63c0a1427862e285068488ddccf319982871e49aac\", size \"70017267\" in 406.874227ms" Dec 12 17:41:33.855702 containerd[1872]: time="2025-12-12T17:41:33.855622219Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7711a8752e1c04bf70ca3a76fb7dcaf2434e812e9334c22c59e7ff007592e25a\"" Dec 12 17:41:33.862981 containerd[1872]: time="2025-12-12T17:41:33.862909616Z" level=info msg="CreateContainer within sandbox \"b79a47f7ccbfa7bd83dd7ef14139201ceb2a39182fb5aea51b8a204332967858\" for container &ContainerMetadata{Name:test,Attempt:0,}" Dec 12 17:41:33.884278 containerd[1872]: time="2025-12-12T17:41:33.883790826Z" level=info msg="Container 887933b9d530a6d1918ff61db6e3f0e0341a7ff0e2b38b617bbb50cf778f0870: CDI devices from CRI Config.CDIDevices: []" Dec 12 17:41:33.907614 containerd[1872]: time="2025-12-12T17:41:33.907586204Z" level=info msg="CreateContainer within sandbox \"b79a47f7ccbfa7bd83dd7ef14139201ceb2a39182fb5aea51b8a204332967858\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"887933b9d530a6d1918ff61db6e3f0e0341a7ff0e2b38b617bbb50cf778f0870\"" Dec 12 17:41:33.909001 containerd[1872]: time="2025-12-12T17:41:33.908117316Z" level=info msg="StartContainer for \"887933b9d530a6d1918ff61db6e3f0e0341a7ff0e2b38b617bbb50cf778f0870\"" Dec 12 17:41:33.909001 containerd[1872]: time="2025-12-12T17:41:33.908789512Z" level=info msg="connecting to shim 887933b9d530a6d1918ff61db6e3f0e0341a7ff0e2b38b617bbb50cf778f0870" address="unix:///run/containerd/s/6d2e52410ba85ad81d960c37c08c6fb41c0bca7b47e52ed5ca4b3b683b0a20d8" protocol=ttrpc version=3 Dec 12 17:41:33.927124 systemd[1]: Started cri-containerd-887933b9d530a6d1918ff61db6e3f0e0341a7ff0e2b38b617bbb50cf778f0870.scope - libcontainer container 887933b9d530a6d1918ff61db6e3f0e0341a7ff0e2b38b617bbb50cf778f0870. Dec 12 17:41:33.952446 containerd[1872]: time="2025-12-12T17:41:33.952419620Z" level=info msg="StartContainer for \"887933b9d530a6d1918ff61db6e3f0e0341a7ff0e2b38b617bbb50cf778f0870\" returns successfully" Dec 12 17:41:34.232903 kubelet[2445]: E1212 17:41:34.232850 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:34.523160 systemd-networkd[1474]: vethb1d03b24: Gained IPv6LL Dec 12 17:41:35.233110 kubelet[2445]: E1212 17:41:35.233067 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:36.233662 kubelet[2445]: E1212 17:41:36.233612 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:37.234623 kubelet[2445]: E1212 17:41:37.234579 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:38.235111 kubelet[2445]: E1212 17:41:38.235071 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:39.206247 kubelet[2445]: E1212 17:41:39.206196 2445 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 12 17:41:39.235996 kubelet[2445]: E1212 17:41:39.235967 2445 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"