Nov 5 15:07:46.396872 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Nov 5 15:07:46.398938 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Wed Nov 5 13:42:06 -00 2025 Nov 5 15:07:46.398946 kernel: KASLR enabled Nov 5 15:07:46.398951 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Nov 5 15:07:46.398958 kernel: printk: legacy bootconsole [pl11] enabled Nov 5 15:07:46.398962 kernel: efi: EFI v2.7 by EDK II Nov 5 15:07:46.398968 kernel: efi: ACPI 2.0=0x3f979018 SMBIOS=0x3f8a0000 SMBIOS 3.0=0x3f880000 MEMATTR=0x3e3ed698 RNG=0x3f979998 MEMRESERVE=0x3db7d598 Nov 5 15:07:46.398972 kernel: random: crng init done Nov 5 15:07:46.398977 kernel: secureboot: Secure boot disabled Nov 5 15:07:46.398981 kernel: ACPI: Early table checksum verification disabled Nov 5 15:07:46.398985 kernel: ACPI: RSDP 0x000000003F979018 000024 (v02 VRTUAL) Nov 5 15:07:46.398989 kernel: ACPI: XSDT 0x000000003F979F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.398994 kernel: ACPI: FACP 0x000000003F979C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.398999 kernel: ACPI: DSDT 0x000000003F95A018 01E046 (v02 MSFTVM DSDT01 00000001 INTL 20230628) Nov 5 15:07:46.399005 kernel: ACPI: DBG2 0x000000003F979B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.399009 kernel: ACPI: GTDT 0x000000003F979D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.399014 kernel: ACPI: OEM0 0x000000003F979098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.399020 kernel: ACPI: SPCR 0x000000003F979A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.399024 kernel: ACPI: APIC 0x000000003F979818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.399029 kernel: ACPI: SRAT 0x000000003F979198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.399033 kernel: ACPI: PPTT 0x000000003F979418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Nov 5 15:07:46.399038 kernel: ACPI: BGRT 0x000000003F979E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:07:46.399042 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Nov 5 15:07:46.399047 kernel: ACPI: Use ACPI SPCR as default console: No Nov 5 15:07:46.399051 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 5 15:07:46.399056 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Nov 5 15:07:46.399060 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Nov 5 15:07:46.399065 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Nov 5 15:07:46.399070 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Nov 5 15:07:46.399074 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Nov 5 15:07:46.399079 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Nov 5 15:07:46.399083 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Nov 5 15:07:46.399088 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Nov 5 15:07:46.399092 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Nov 5 15:07:46.399097 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Nov 5 15:07:46.399101 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Nov 5 15:07:46.399106 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Nov 5 15:07:46.399110 kernel: NODE_DATA(0) allocated [mem 0x1bf7ffa00-0x1bf806fff] Nov 5 15:07:46.399115 kernel: Zone ranges: Nov 5 15:07:46.399120 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Nov 5 15:07:46.399126 kernel: DMA32 empty Nov 5 15:07:46.399131 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Nov 5 15:07:46.399136 kernel: Device empty Nov 5 15:07:46.399141 kernel: Movable zone start for each node Nov 5 15:07:46.399146 kernel: Early memory node ranges Nov 5 15:07:46.399151 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Nov 5 15:07:46.399156 kernel: node 0: [mem 0x0000000000824000-0x000000003f38ffff] Nov 5 15:07:46.399160 kernel: node 0: [mem 0x000000003f390000-0x000000003f93ffff] Nov 5 15:07:46.399165 kernel: node 0: [mem 0x000000003f940000-0x000000003f9effff] Nov 5 15:07:46.399170 kernel: node 0: [mem 0x000000003f9f0000-0x000000003fdeffff] Nov 5 15:07:46.399174 kernel: node 0: [mem 0x000000003fdf0000-0x000000003fffffff] Nov 5 15:07:46.399179 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Nov 5 15:07:46.399185 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Nov 5 15:07:46.399189 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Nov 5 15:07:46.399194 kernel: cma: Reserved 16 MiB at 0x000000003ca00000 on node -1 Nov 5 15:07:46.399199 kernel: psci: probing for conduit method from ACPI. Nov 5 15:07:46.399203 kernel: psci: PSCIv1.3 detected in firmware. Nov 5 15:07:46.399208 kernel: psci: Using standard PSCI v0.2 function IDs Nov 5 15:07:46.399213 kernel: psci: MIGRATE_INFO_TYPE not supported. Nov 5 15:07:46.399217 kernel: psci: SMC Calling Convention v1.4 Nov 5 15:07:46.399222 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Nov 5 15:07:46.399227 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Nov 5 15:07:46.399231 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Nov 5 15:07:46.399236 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Nov 5 15:07:46.399242 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 5 15:07:46.399247 kernel: Detected PIPT I-cache on CPU0 Nov 5 15:07:46.399251 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Nov 5 15:07:46.399256 kernel: CPU features: detected: GIC system register CPU interface Nov 5 15:07:46.399261 kernel: CPU features: detected: Spectre-v4 Nov 5 15:07:46.399265 kernel: CPU features: detected: Spectre-BHB Nov 5 15:07:46.399270 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 5 15:07:46.399275 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 5 15:07:46.399280 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Nov 5 15:07:46.399284 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 5 15:07:46.399290 kernel: alternatives: applying boot alternatives Nov 5 15:07:46.399295 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=15758474ef4cace68fb389c1b75e821ab8f30d9b752a28429e0459793723ea7b Nov 5 15:07:46.399301 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 5 15:07:46.399305 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 5 15:07:46.399310 kernel: Fallback order for Node 0: 0 Nov 5 15:07:46.399315 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Nov 5 15:07:46.399319 kernel: Policy zone: Normal Nov 5 15:07:46.399324 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 5 15:07:46.399329 kernel: software IO TLB: area num 2. Nov 5 15:07:46.399334 kernel: software IO TLB: mapped [mem 0x0000000037300000-0x000000003b300000] (64MB) Nov 5 15:07:46.399338 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 5 15:07:46.399344 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 5 15:07:46.399350 kernel: rcu: RCU event tracing is enabled. Nov 5 15:07:46.399355 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 5 15:07:46.399359 kernel: Trampoline variant of Tasks RCU enabled. Nov 5 15:07:46.399364 kernel: Tracing variant of Tasks RCU enabled. Nov 5 15:07:46.399369 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 5 15:07:46.399374 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 5 15:07:46.399378 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 5 15:07:46.399383 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 5 15:07:46.399388 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 5 15:07:46.399393 kernel: GICv3: 960 SPIs implemented Nov 5 15:07:46.399398 kernel: GICv3: 0 Extended SPIs implemented Nov 5 15:07:46.399403 kernel: Root IRQ handler: gic_handle_irq Nov 5 15:07:46.399407 kernel: GICv3: GICv3 features: 16 PPIs, RSS Nov 5 15:07:46.399412 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Nov 5 15:07:46.399417 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Nov 5 15:07:46.399422 kernel: ITS: No ITS available, not enabling LPIs Nov 5 15:07:46.399426 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 5 15:07:46.399431 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Nov 5 15:07:46.399436 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 5 15:07:46.399441 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Nov 5 15:07:46.399446 kernel: Console: colour dummy device 80x25 Nov 5 15:07:46.399452 kernel: printk: legacy console [tty1] enabled Nov 5 15:07:46.399457 kernel: ACPI: Core revision 20240827 Nov 5 15:07:46.399462 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Nov 5 15:07:46.399467 kernel: pid_max: default: 32768 minimum: 301 Nov 5 15:07:46.399472 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 5 15:07:46.399477 kernel: landlock: Up and running. Nov 5 15:07:46.399482 kernel: SELinux: Initializing. Nov 5 15:07:46.399488 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:07:46.399493 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:07:46.399498 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0xa0000e, misc 0x31e1 Nov 5 15:07:46.399503 kernel: Hyper-V: Host Build 10.0.26102.1109-1-0 Nov 5 15:07:46.399512 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 5 15:07:46.399518 kernel: rcu: Hierarchical SRCU implementation. Nov 5 15:07:46.399523 kernel: rcu: Max phase no-delay instances is 400. Nov 5 15:07:46.399528 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 5 15:07:46.399534 kernel: Remapping and enabling EFI services. Nov 5 15:07:46.399540 kernel: smp: Bringing up secondary CPUs ... Nov 5 15:07:46.399545 kernel: Detected PIPT I-cache on CPU1 Nov 5 15:07:46.399550 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Nov 5 15:07:46.399556 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Nov 5 15:07:46.399562 kernel: smp: Brought up 1 node, 2 CPUs Nov 5 15:07:46.399567 kernel: SMP: Total of 2 processors activated. Nov 5 15:07:46.399572 kernel: CPU: All CPU(s) started at EL1 Nov 5 15:07:46.399577 kernel: CPU features: detected: 32-bit EL0 Support Nov 5 15:07:46.399582 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Nov 5 15:07:46.399588 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 5 15:07:46.399593 kernel: CPU features: detected: Common not Private translations Nov 5 15:07:46.399599 kernel: CPU features: detected: CRC32 instructions Nov 5 15:07:46.399604 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Nov 5 15:07:46.399609 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 5 15:07:46.399615 kernel: CPU features: detected: LSE atomic instructions Nov 5 15:07:46.399620 kernel: CPU features: detected: Privileged Access Never Nov 5 15:07:46.399625 kernel: CPU features: detected: Speculation barrier (SB) Nov 5 15:07:46.399630 kernel: CPU features: detected: TLB range maintenance instructions Nov 5 15:07:46.399636 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 5 15:07:46.399642 kernel: CPU features: detected: Scalable Vector Extension Nov 5 15:07:46.399647 kernel: alternatives: applying system-wide alternatives Nov 5 15:07:46.399652 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Nov 5 15:07:46.399658 kernel: SVE: maximum available vector length 16 bytes per vector Nov 5 15:07:46.399663 kernel: SVE: default vector length 16 bytes per vector Nov 5 15:07:46.399668 kernel: Memory: 3979452K/4194160K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 193520K reserved, 16384K cma-reserved) Nov 5 15:07:46.399674 kernel: devtmpfs: initialized Nov 5 15:07:46.399680 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 5 15:07:46.399685 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 5 15:07:46.399690 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 5 15:07:46.399695 kernel: 0 pages in range for non-PLT usage Nov 5 15:07:46.399701 kernel: 515056 pages in range for PLT usage Nov 5 15:07:46.399706 kernel: pinctrl core: initialized pinctrl subsystem Nov 5 15:07:46.399712 kernel: SMBIOS 3.1.0 present. Nov 5 15:07:46.399717 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 06/10/2025 Nov 5 15:07:46.399723 kernel: DMI: Memory slots populated: 2/2 Nov 5 15:07:46.399728 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 5 15:07:46.399733 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 5 15:07:46.399738 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 5 15:07:46.399744 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 5 15:07:46.399749 kernel: audit: initializing netlink subsys (disabled) Nov 5 15:07:46.399755 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Nov 5 15:07:46.399760 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 5 15:07:46.399765 kernel: cpuidle: using governor menu Nov 5 15:07:46.399770 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 5 15:07:46.399775 kernel: ASID allocator initialised with 32768 entries Nov 5 15:07:46.399780 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 5 15:07:46.399786 kernel: Serial: AMBA PL011 UART driver Nov 5 15:07:46.399792 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 5 15:07:46.399797 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 5 15:07:46.399802 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 5 15:07:46.399807 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 5 15:07:46.399812 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 5 15:07:46.399818 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 5 15:07:46.399823 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 5 15:07:46.399829 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 5 15:07:46.399834 kernel: ACPI: Added _OSI(Module Device) Nov 5 15:07:46.399839 kernel: ACPI: Added _OSI(Processor Device) Nov 5 15:07:46.399844 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 5 15:07:46.399850 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 5 15:07:46.399855 kernel: ACPI: Interpreter enabled Nov 5 15:07:46.399860 kernel: ACPI: Using GIC for interrupt routing Nov 5 15:07:46.399866 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Nov 5 15:07:46.399872 kernel: printk: legacy console [ttyAMA0] enabled Nov 5 15:07:46.399885 kernel: printk: legacy bootconsole [pl11] disabled Nov 5 15:07:46.399890 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Nov 5 15:07:46.399895 kernel: ACPI: CPU0 has been hot-added Nov 5 15:07:46.399901 kernel: ACPI: CPU1 has been hot-added Nov 5 15:07:46.399906 kernel: iommu: Default domain type: Translated Nov 5 15:07:46.399913 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 5 15:07:46.399918 kernel: efivars: Registered efivars operations Nov 5 15:07:46.399923 kernel: vgaarb: loaded Nov 5 15:07:46.399928 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 5 15:07:46.399933 kernel: VFS: Disk quotas dquot_6.6.0 Nov 5 15:07:46.399938 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 5 15:07:46.399944 kernel: pnp: PnP ACPI init Nov 5 15:07:46.399950 kernel: pnp: PnP ACPI: found 0 devices Nov 5 15:07:46.399955 kernel: NET: Registered PF_INET protocol family Nov 5 15:07:46.399960 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 5 15:07:46.399965 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 5 15:07:46.399971 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 5 15:07:46.399976 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 5 15:07:46.399981 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 5 15:07:46.399987 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 5 15:07:46.399993 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:07:46.399998 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:07:46.400003 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 5 15:07:46.400008 kernel: PCI: CLS 0 bytes, default 64 Nov 5 15:07:46.400014 kernel: kvm [1]: HYP mode not available Nov 5 15:07:46.400019 kernel: Initialise system trusted keyrings Nov 5 15:07:46.400024 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 5 15:07:46.400030 kernel: Key type asymmetric registered Nov 5 15:07:46.400035 kernel: Asymmetric key parser 'x509' registered Nov 5 15:07:46.400040 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Nov 5 15:07:46.400045 kernel: io scheduler mq-deadline registered Nov 5 15:07:46.400051 kernel: io scheduler kyber registered Nov 5 15:07:46.400056 kernel: io scheduler bfq registered Nov 5 15:07:46.400061 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 5 15:07:46.400067 kernel: thunder_xcv, ver 1.0 Nov 5 15:07:46.400073 kernel: thunder_bgx, ver 1.0 Nov 5 15:07:46.400078 kernel: nicpf, ver 1.0 Nov 5 15:07:46.400083 kernel: nicvf, ver 1.0 Nov 5 15:07:46.400249 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 5 15:07:46.400320 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-05T15:07:42 UTC (1762355262) Nov 5 15:07:46.400328 kernel: efifb: probing for efifb Nov 5 15:07:46.400334 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 5 15:07:46.400339 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 5 15:07:46.400344 kernel: efifb: scrolling: redraw Nov 5 15:07:46.400349 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 5 15:07:46.400354 kernel: Console: switching to colour frame buffer device 128x48 Nov 5 15:07:46.400359 kernel: fb0: EFI VGA frame buffer device Nov 5 15:07:46.400366 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Nov 5 15:07:46.400371 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 5 15:07:46.400376 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Nov 5 15:07:46.400381 kernel: watchdog: NMI not fully supported Nov 5 15:07:46.400387 kernel: watchdog: Hard watchdog permanently disabled Nov 5 15:07:46.400392 kernel: NET: Registered PF_INET6 protocol family Nov 5 15:07:46.400397 kernel: Segment Routing with IPv6 Nov 5 15:07:46.400403 kernel: In-situ OAM (IOAM) with IPv6 Nov 5 15:07:46.400408 kernel: NET: Registered PF_PACKET protocol family Nov 5 15:07:46.400413 kernel: Key type dns_resolver registered Nov 5 15:07:46.400418 kernel: registered taskstats version 1 Nov 5 15:07:46.400424 kernel: Loading compiled-in X.509 certificates Nov 5 15:07:46.400429 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 4b3babb46eb583bd8b0310732885d24e60ea58c5' Nov 5 15:07:46.400434 kernel: Demotion targets for Node 0: null Nov 5 15:07:46.400440 kernel: Key type .fscrypt registered Nov 5 15:07:46.400445 kernel: Key type fscrypt-provisioning registered Nov 5 15:07:46.400451 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 5 15:07:46.400456 kernel: ima: Allocated hash algorithm: sha1 Nov 5 15:07:46.400461 kernel: ima: No architecture policies found Nov 5 15:07:46.400466 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 5 15:07:46.400471 kernel: clk: Disabling unused clocks Nov 5 15:07:46.400476 kernel: PM: genpd: Disabling unused power domains Nov 5 15:07:46.400482 kernel: Freeing unused kernel memory: 12992K Nov 5 15:07:46.400487 kernel: Run /init as init process Nov 5 15:07:46.400493 kernel: with arguments: Nov 5 15:07:46.400498 kernel: /init Nov 5 15:07:46.400503 kernel: with environment: Nov 5 15:07:46.400508 kernel: HOME=/ Nov 5 15:07:46.400513 kernel: TERM=linux Nov 5 15:07:46.400519 kernel: hv_vmbus: Vmbus version:5.3 Nov 5 15:07:46.400525 kernel: hv_vmbus: registering driver hid_hyperv Nov 5 15:07:46.400530 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Nov 5 15:07:46.400619 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 5 15:07:46.400626 kernel: SCSI subsystem initialized Nov 5 15:07:46.400631 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 5 15:07:46.400638 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 Nov 5 15:07:46.400644 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 5 15:07:46.400649 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 5 15:07:46.400654 kernel: PTP clock support registered Nov 5 15:07:46.400660 kernel: hv_utils: Registering HyperV Utility Driver Nov 5 15:07:46.400665 kernel: hv_vmbus: registering driver hv_utils Nov 5 15:07:46.400670 kernel: hv_utils: Heartbeat IC version 3.0 Nov 5 15:07:46.400676 kernel: hv_utils: Shutdown IC version 3.2 Nov 5 15:07:46.400682 kernel: hv_utils: TimeSync IC version 4.0 Nov 5 15:07:46.400687 kernel: hv_vmbus: registering driver hv_storvsc Nov 5 15:07:46.400781 kernel: scsi host0: storvsc_host_t Nov 5 15:07:46.400859 kernel: scsi host1: storvsc_host_t Nov 5 15:07:46.400961 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 5 15:07:46.401049 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Nov 5 15:07:46.401123 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 5 15:07:46.401196 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 5 15:07:46.401269 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 5 15:07:46.401342 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 5 15:07:46.401415 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 5 15:07:46.401497 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#189 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:07:46.401567 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#132 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:07:46.401573 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 5 15:07:46.401646 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 5 15:07:46.401720 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 5 15:07:46.401728 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 5 15:07:46.401800 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 5 15:07:46.401807 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 5 15:07:46.401812 kernel: device-mapper: uevent: version 1.0.3 Nov 5 15:07:46.401818 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 5 15:07:46.401823 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Nov 5 15:07:46.401828 kernel: raid6: neonx8 gen() 18556 MB/s Nov 5 15:07:46.401835 kernel: raid6: neonx4 gen() 18562 MB/s Nov 5 15:07:46.401840 kernel: raid6: neonx2 gen() 17080 MB/s Nov 5 15:07:46.401845 kernel: raid6: neonx1 gen() 15008 MB/s Nov 5 15:07:46.401850 kernel: raid6: int64x8 gen() 10296 MB/s Nov 5 15:07:46.401855 kernel: raid6: int64x4 gen() 10614 MB/s Nov 5 15:07:46.401861 kernel: raid6: int64x2 gen() 9004 MB/s Nov 5 15:07:46.401866 kernel: raid6: int64x1 gen() 7053 MB/s Nov 5 15:07:46.401871 kernel: raid6: using algorithm neonx4 gen() 18562 MB/s Nov 5 15:07:46.402934 kernel: raid6: .... xor() 15154 MB/s, rmw enabled Nov 5 15:07:46.402942 kernel: raid6: using neon recovery algorithm Nov 5 15:07:46.402948 kernel: xor: measuring software checksum speed Nov 5 15:07:46.402954 kernel: 8regs : 28334 MB/sec Nov 5 15:07:46.402959 kernel: 32regs : 28797 MB/sec Nov 5 15:07:46.402965 kernel: arm64_neon : 36963 MB/sec Nov 5 15:07:46.402970 kernel: xor: using function: arm64_neon (36963 MB/sec) Nov 5 15:07:46.402979 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 5 15:07:46.402985 kernel: BTRFS: device fsid d8f84a83-fd8b-4c0e-831a-0d7c5ff234be devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (369) Nov 5 15:07:46.402990 kernel: BTRFS info (device dm-0): first mount of filesystem d8f84a83-fd8b-4c0e-831a-0d7c5ff234be Nov 5 15:07:46.402996 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:07:46.403001 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 5 15:07:46.403007 kernel: BTRFS info (device dm-0): enabling free space tree Nov 5 15:07:46.403012 kernel: loop: module loaded Nov 5 15:07:46.403019 kernel: loop0: detected capacity change from 0 to 91464 Nov 5 15:07:46.403024 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 5 15:07:46.403030 systemd[1]: Successfully made /usr/ read-only. Nov 5 15:07:46.403038 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:07:46.403044 systemd[1]: Detected virtualization microsoft. Nov 5 15:07:46.403050 systemd[1]: Detected architecture arm64. Nov 5 15:07:46.403057 systemd[1]: Running in initrd. Nov 5 15:07:46.403062 systemd[1]: No hostname configured, using default hostname. Nov 5 15:07:46.403068 systemd[1]: Hostname set to . Nov 5 15:07:46.403074 systemd[1]: Initializing machine ID from random generator. Nov 5 15:07:46.403080 systemd[1]: Queued start job for default target initrd.target. Nov 5 15:07:46.403085 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:07:46.403092 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:07:46.403098 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:07:46.403105 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 5 15:07:46.403111 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:07:46.403117 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 5 15:07:46.403123 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 5 15:07:46.403130 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:07:46.403136 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:07:46.403142 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:07:46.403147 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:07:46.403153 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:07:46.403159 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:07:46.403164 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:07:46.403171 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:07:46.403177 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:07:46.403183 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 5 15:07:46.403189 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 5 15:07:46.403194 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:07:46.403200 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:07:46.403211 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:07:46.403218 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:07:46.403225 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 5 15:07:46.403231 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 5 15:07:46.403237 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:07:46.403243 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 5 15:07:46.403250 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). Nov 5 15:07:46.403255 systemd[1]: Starting systemd-fsck-usr.service... Nov 5 15:07:46.403261 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:07:46.403267 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:07:46.403299 systemd-journald[505]: Collecting audit messages is disabled. Nov 5 15:07:46.403315 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:07:46.403323 systemd-journald[505]: Journal started Nov 5 15:07:46.403338 systemd-journald[505]: Runtime Journal (/run/log/journal/f5e171179d0747d58cbb744727b36f05) is 8M, max 78.3M, 70.3M free. Nov 5 15:07:46.422250 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:07:46.422932 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 5 15:07:46.428979 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:07:46.434228 systemd[1]: Finished systemd-fsck-usr.service. Nov 5 15:07:46.442743 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 15:07:46.465025 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:07:46.486068 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 5 15:07:46.549994 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:07:46.567072 kernel: Bridge firewalling registered Nov 5 15:07:46.552042 systemd-tmpfiles[517]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 5 15:07:46.554390 systemd-modules-load[508]: Inserted module 'br_netfilter' Nov 5 15:07:46.556869 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:07:46.566855 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:07:46.573772 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:07:46.585554 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:07:46.612099 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:07:46.622099 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:07:46.630965 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:07:46.638580 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 15:07:46.664584 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:07:46.698998 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:07:46.713057 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 5 15:07:46.785577 dracut-cmdline[547]: 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=15758474ef4cace68fb389c1b75e821ab8f30d9b752a28429e0459793723ea7b Nov 5 15:07:46.815871 systemd-resolved[535]: Positive Trust Anchors: Nov 5 15:07:46.815898 systemd-resolved[535]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:07:46.815901 systemd-resolved[535]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:07:46.815921 systemd-resolved[535]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 15:07:46.862032 systemd-resolved[535]: Defaulting to hostname 'linux'. Nov 5 15:07:46.862794 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:07:46.870514 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:07:46.996904 kernel: Loading iSCSI transport class v2.0-870. Nov 5 15:07:47.053905 kernel: iscsi: registered transport (tcp) Nov 5 15:07:47.087406 kernel: iscsi: registered transport (qla4xxx) Nov 5 15:07:47.087424 kernel: QLogic iSCSI HBA Driver Nov 5 15:07:47.166231 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:07:47.191171 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:07:47.197644 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:07:47.246790 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 5 15:07:47.251893 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 5 15:07:47.282604 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 5 15:07:47.306059 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:07:47.316309 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:07:47.423502 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:07:47.454912 systemd-udevd[768]: Using default interface naming scheme 'v257'. Nov 5 15:07:47.460105 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:07:47.472762 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 5 15:07:47.490962 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:07:47.507948 dracut-pre-trigger[888]: rd.md=0: removing MD RAID activation Nov 5 15:07:47.527614 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:07:47.537058 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:07:47.545779 systemd-networkd[889]: lo: Link UP Nov 5 15:07:47.545781 systemd-networkd[889]: lo: Gained carrier Nov 5 15:07:47.546555 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:07:47.555391 systemd[1]: Reached target network.target - Network. Nov 5 15:07:47.597929 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:07:47.609500 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 5 15:07:47.676893 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#134 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 5 15:07:47.704207 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:07:47.708196 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:07:47.717151 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:07:47.728165 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:07:47.737321 kernel: hv_vmbus: registering driver hv_netvsc Nov 5 15:07:47.760784 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:07:47.814904 kernel: hv_netvsc 000d3a6d-f9ce-000d-3a6d-f9ce000d3a6d eth0: VF slot 1 added Nov 5 15:07:47.838264 systemd-networkd[889]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:07:47.838274 systemd-networkd[889]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:07:47.860467 kernel: hv_vmbus: registering driver hv_pci Nov 5 15:07:47.860487 kernel: hv_pci 0f19be16-29d1-471a-8390-94220be2b6de: PCI VMBus probing: Using version 0x10004 Nov 5 15:07:47.847424 systemd-networkd[889]: eth0: Link UP Nov 5 15:07:47.867699 kernel: hv_pci 0f19be16-29d1-471a-8390-94220be2b6de: PCI host bridge to bus 29d1:00 Nov 5 15:07:47.867842 kernel: pci_bus 29d1:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Nov 5 15:07:47.847546 systemd-networkd[889]: eth0: Gained carrier Nov 5 15:07:47.887004 kernel: pci_bus 29d1:00: No busn resource found for root bus, will use [bus 00-ff] Nov 5 15:07:47.887197 kernel: pci 29d1:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Nov 5 15:07:47.887248 kernel: pci 29d1:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Nov 5 15:07:47.847558 systemd-networkd[889]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:07:47.899090 kernel: pci 29d1:00:02.0: enabling Extended Tags Nov 5 15:07:47.913037 kernel: pci 29d1:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 29d1:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Nov 5 15:07:47.913938 systemd-networkd[889]: eth0: DHCPv4 address 10.200.20.18/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 5 15:07:47.928114 kernel: pci_bus 29d1:00: busn_res: [bus 00-ff] end is updated to 00 Nov 5 15:07:47.928329 kernel: pci 29d1:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Nov 5 15:07:48.117687 kernel: mlx5_core 29d1:00:02.0: enabling device (0000 -> 0002) Nov 5 15:07:48.125636 kernel: mlx5_core 29d1:00:02.0: PTM is not supported by PCIe Nov 5 15:07:48.125851 kernel: mlx5_core 29d1:00:02.0: firmware version: 16.30.5006 Nov 5 15:07:48.266021 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 5 15:07:48.272288 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 5 15:07:48.318740 kernel: hv_netvsc 000d3a6d-f9ce-000d-3a6d-f9ce000d3a6d eth0: VF registering: eth1 Nov 5 15:07:48.319013 kernel: mlx5_core 29d1:00:02.0 eth1: joined to eth0 Nov 5 15:07:48.320607 kernel: mlx5_core 29d1:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Nov 5 15:07:48.334280 systemd-networkd[889]: eth1: Interface name change detected, renamed to enP10705s1. Nov 5 15:07:48.341205 kernel: mlx5_core 29d1:00:02.0 enP10705s1: renamed from eth1 Nov 5 15:07:48.385531 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 5 15:07:48.413237 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 5 15:07:48.435582 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 5 15:07:48.482907 kernel: mlx5_core 29d1:00:02.0 enP10705s1: Link up Nov 5 15:07:48.516338 systemd-networkd[889]: enP10705s1: Link UP Nov 5 15:07:48.520769 kernel: hv_netvsc 000d3a6d-f9ce-000d-3a6d-f9ce000d3a6d eth0: Data path switched to VF: enP10705s1 Nov 5 15:07:48.521323 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 5 15:07:48.526390 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:07:48.531657 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:07:48.541067 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:07:48.551627 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 5 15:07:48.581061 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:07:48.853157 systemd-networkd[889]: enP10705s1: Gained carrier Nov 5 15:07:48.933783 systemd-networkd[889]: eth0: Gained IPv6LL Nov 5 15:07:49.528274 disk-uuid[1007]: Warning: The kernel is still using the old partition table. Nov 5 15:07:49.528274 disk-uuid[1007]: The new table will be used at the next reboot or after you Nov 5 15:07:49.528274 disk-uuid[1007]: run partprobe(8) or kpartx(8) Nov 5 15:07:49.528274 disk-uuid[1007]: The operation has completed successfully. Nov 5 15:07:49.539002 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 5 15:07:49.539096 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 5 15:07:49.550547 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 5 15:07:49.610434 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1161) Nov 5 15:07:49.610487 kernel: BTRFS info (device sda6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:07:49.614580 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:07:49.686341 kernel: BTRFS info (device sda6): turning on async discard Nov 5 15:07:49.686362 kernel: BTRFS info (device sda6): enabling free space tree Nov 5 15:07:49.695895 kernel: BTRFS info (device sda6): last unmount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:07:49.696150 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 5 15:07:49.701602 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 5 15:07:50.990860 ignition[1180]: Ignition 2.22.0 Nov 5 15:07:50.990887 ignition[1180]: Stage: fetch-offline Nov 5 15:07:50.992314 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:07:50.990998 ignition[1180]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:07:51.000534 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 5 15:07:50.991007 ignition[1180]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:07:50.991078 ignition[1180]: parsed url from cmdline: "" Nov 5 15:07:50.991080 ignition[1180]: no config URL provided Nov 5 15:07:50.991085 ignition[1180]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 15:07:50.991092 ignition[1180]: no config at "/usr/lib/ignition/user.ign" Nov 5 15:07:50.991095 ignition[1180]: failed to fetch config: resource requires networking Nov 5 15:07:50.991213 ignition[1180]: Ignition finished successfully Nov 5 15:07:51.037468 ignition[1188]: Ignition 2.22.0 Nov 5 15:07:51.037474 ignition[1188]: Stage: fetch Nov 5 15:07:51.037720 ignition[1188]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:07:51.037728 ignition[1188]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:07:51.037814 ignition[1188]: parsed url from cmdline: "" Nov 5 15:07:51.037817 ignition[1188]: no config URL provided Nov 5 15:07:51.037820 ignition[1188]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 15:07:51.037827 ignition[1188]: no config at "/usr/lib/ignition/user.ign" Nov 5 15:07:51.037843 ignition[1188]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 5 15:07:51.133242 ignition[1188]: GET result: OK Nov 5 15:07:51.133295 ignition[1188]: config has been read from IMDS userdata Nov 5 15:07:51.133317 ignition[1188]: parsing config with SHA512: 9f581d6c43516dead62ca951932dc2194a5713a0f204335bc85fe9c8e107acbff71018d7883e98ebe9dbb6c8bd1c5aec605b96a5913e60ae55c3616e6848e2b4 Nov 5 15:07:51.135605 unknown[1188]: fetched base config from "system" Nov 5 15:07:51.135809 ignition[1188]: fetch: fetch complete Nov 5 15:07:51.135611 unknown[1188]: fetched base config from "system" Nov 5 15:07:51.135812 ignition[1188]: fetch: fetch passed Nov 5 15:07:51.135615 unknown[1188]: fetched user config from "azure" Nov 5 15:07:51.135855 ignition[1188]: Ignition finished successfully Nov 5 15:07:51.142066 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 5 15:07:51.147718 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 5 15:07:51.180366 ignition[1194]: Ignition 2.22.0 Nov 5 15:07:51.180387 ignition[1194]: Stage: kargs Nov 5 15:07:51.180570 ignition[1194]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:07:51.186433 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 5 15:07:51.180577 ignition[1194]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:07:51.195376 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 5 15:07:51.180991 ignition[1194]: kargs: kargs passed Nov 5 15:07:51.181034 ignition[1194]: Ignition finished successfully Nov 5 15:07:51.224795 ignition[1200]: Ignition 2.22.0 Nov 5 15:07:51.224813 ignition[1200]: Stage: disks Nov 5 15:07:51.228267 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 5 15:07:51.225010 ignition[1200]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:07:51.233102 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 5 15:07:51.225017 ignition[1200]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:07:51.239399 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 5 15:07:51.225490 ignition[1200]: disks: disks passed Nov 5 15:07:51.247859 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:07:51.225534 ignition[1200]: Ignition finished successfully Nov 5 15:07:51.255442 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:07:51.263882 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:07:51.272841 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 5 15:07:51.895965 systemd-fsck[1208]: ROOT: clean, 15/6361680 files, 408771/6359552 blocks Nov 5 15:07:51.904232 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 5 15:07:51.912035 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 5 15:07:53.966959 kernel: EXT4-fs (sda9): mounted filesystem 67ab558f-e1dc-496b-b18a-e9709809a3c4 r/w with ordered data mode. Quota mode: none. Nov 5 15:07:53.971719 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 5 15:07:53.975448 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 5 15:07:54.027977 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:07:54.046564 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 5 15:07:54.050969 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 5 15:07:54.071929 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1222) Nov 5 15:07:54.065568 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 5 15:07:54.065611 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:07:54.088207 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 5 15:07:54.110982 kernel: BTRFS info (device sda6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:07:54.111005 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:07:54.108225 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 5 15:07:54.123807 kernel: BTRFS info (device sda6): turning on async discard Nov 5 15:07:54.123838 kernel: BTRFS info (device sda6): enabling free space tree Nov 5 15:07:54.125104 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:07:54.778953 coreos-metadata[1224]: Nov 05 15:07:54.778 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 5 15:07:54.785991 coreos-metadata[1224]: Nov 05 15:07:54.785 INFO Fetch successful Nov 5 15:07:54.789796 coreos-metadata[1224]: Nov 05 15:07:54.789 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 5 15:07:54.797850 coreos-metadata[1224]: Nov 05 15:07:54.797 INFO Fetch successful Nov 5 15:07:54.813311 coreos-metadata[1224]: Nov 05 15:07:54.813 INFO wrote hostname ci-4487.0.1-a-6d305da4f4 to /sysroot/etc/hostname Nov 5 15:07:54.820426 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 5 15:07:55.062066 initrd-setup-root[1252]: cut: /sysroot/etc/passwd: No such file or directory Nov 5 15:07:55.147790 initrd-setup-root[1259]: cut: /sysroot/etc/group: No such file or directory Nov 5 15:07:55.199780 initrd-setup-root[1266]: cut: /sysroot/etc/shadow: No such file or directory Nov 5 15:07:55.204975 initrd-setup-root[1273]: cut: /sysroot/etc/gshadow: No such file or directory Nov 5 15:07:56.551062 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 5 15:07:56.556777 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 5 15:07:56.573622 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 5 15:07:56.597330 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 5 15:07:56.608899 kernel: BTRFS info (device sda6): last unmount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:07:56.622998 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 5 15:07:56.641338 ignition[1343]: INFO : Ignition 2.22.0 Nov 5 15:07:56.641338 ignition[1343]: INFO : Stage: mount Nov 5 15:07:56.648195 ignition[1343]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:07:56.648195 ignition[1343]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:07:56.648195 ignition[1343]: INFO : mount: mount passed Nov 5 15:07:56.648195 ignition[1343]: INFO : Ignition finished successfully Nov 5 15:07:56.646197 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 5 15:07:56.653952 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 5 15:07:56.678984 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:07:56.701894 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1352) Nov 5 15:07:56.712478 kernel: BTRFS info (device sda6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:07:56.712524 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:07:56.721682 kernel: BTRFS info (device sda6): turning on async discard Nov 5 15:07:56.721720 kernel: BTRFS info (device sda6): enabling free space tree Nov 5 15:07:56.723177 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:07:56.752783 ignition[1370]: INFO : Ignition 2.22.0 Nov 5 15:07:56.752783 ignition[1370]: INFO : Stage: files Nov 5 15:07:56.752783 ignition[1370]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:07:56.752783 ignition[1370]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:07:56.752783 ignition[1370]: DEBUG : files: compiled without relabeling support, skipping Nov 5 15:07:56.772569 ignition[1370]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 5 15:07:56.778136 ignition[1370]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 5 15:07:56.888088 ignition[1370]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 5 15:07:56.893668 ignition[1370]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 5 15:07:56.893668 ignition[1370]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 5 15:07:56.888473 unknown[1370]: wrote ssh authorized keys file for user: core Nov 5 15:07:56.939772 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 5 15:07:56.947028 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 5 15:07:56.956626 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:07:56.963941 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:07:56.963941 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:07:56.981003 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:07:56.981003 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:07:56.981003 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.34.1-arm64.raw: attempt #1 Nov 5 15:08:00.828547 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 5 15:08:01.000849 ignition[1370]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:08:01.009694 ignition[1370]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:08:01.009694 ignition[1370]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:08:01.009694 ignition[1370]: INFO : files: files passed Nov 5 15:08:01.009694 ignition[1370]: INFO : Ignition finished successfully Nov 5 15:08:01.014720 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 5 15:08:01.021687 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 5 15:08:01.043566 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 5 15:08:01.056346 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 5 15:08:01.056427 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 5 15:08:01.079939 initrd-setup-root-after-ignition[1400]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:08:01.079939 initrd-setup-root-after-ignition[1400]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:08:01.093930 initrd-setup-root-after-ignition[1404]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:08:01.087540 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:08:01.099484 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 5 15:08:01.110160 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 5 15:08:01.152602 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 5 15:08:01.152737 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 5 15:08:01.161169 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 5 15:08:01.169683 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 5 15:08:01.177429 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 5 15:08:01.178265 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 5 15:08:01.214053 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:08:01.220388 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 5 15:08:01.248383 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:08:01.248541 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:08:01.257882 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:08:01.266490 systemd[1]: Stopped target timers.target - Timer Units. Nov 5 15:08:01.274278 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 5 15:08:01.274441 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:08:01.285557 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 5 15:08:01.293985 systemd[1]: Stopped target basic.target - Basic System. Nov 5 15:08:01.301416 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 5 15:08:01.309173 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:08:01.317885 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 5 15:08:01.326980 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:08:01.335923 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 5 15:08:01.344242 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:08:01.353068 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 5 15:08:01.361655 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 5 15:08:01.369359 systemd[1]: Stopped target swap.target - Swaps. Nov 5 15:08:01.376158 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 5 15:08:01.376320 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:08:01.387264 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:08:01.395599 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:08:01.404709 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 5 15:08:01.409061 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:08:01.414245 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 5 15:08:01.414383 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 5 15:08:01.427199 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 5 15:08:01.427337 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:08:01.436175 systemd[1]: ignition-files.service: Deactivated successfully. Nov 5 15:08:01.436274 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 5 15:08:01.444289 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 5 15:08:01.444403 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 5 15:08:01.496980 ignition[1424]: INFO : Ignition 2.22.0 Nov 5 15:08:01.496980 ignition[1424]: INFO : Stage: umount Nov 5 15:08:01.496980 ignition[1424]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:08:01.496980 ignition[1424]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:08:01.496980 ignition[1424]: INFO : umount: umount passed Nov 5 15:08:01.496980 ignition[1424]: INFO : Ignition finished successfully Nov 5 15:08:01.455982 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 5 15:08:01.463653 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 5 15:08:01.463864 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:08:01.480387 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 5 15:08:01.491937 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 5 15:08:01.492083 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:08:01.497775 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 5 15:08:01.497862 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:08:01.505017 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 5 15:08:01.505096 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:08:01.521810 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 5 15:08:01.521910 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 5 15:08:01.529597 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 5 15:08:01.529688 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 5 15:08:01.536447 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 5 15:08:01.536494 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 5 15:08:01.547887 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 5 15:08:01.547938 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 5 15:08:01.556093 systemd[1]: Stopped target network.target - Network. Nov 5 15:08:01.567751 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 5 15:08:01.567840 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:08:01.576760 systemd[1]: Stopped target paths.target - Path Units. Nov 5 15:08:01.589791 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 5 15:08:01.592895 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:08:01.599245 systemd[1]: Stopped target slices.target - Slice Units. Nov 5 15:08:01.608126 systemd[1]: Stopped target sockets.target - Socket Units. Nov 5 15:08:01.615001 systemd[1]: iscsid.socket: Deactivated successfully. Nov 5 15:08:01.615071 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:08:01.620652 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 5 15:08:01.620690 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:08:01.628215 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 5 15:08:01.628274 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 5 15:08:01.636937 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 5 15:08:01.636970 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 5 15:08:01.644641 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 5 15:08:01.652284 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 5 15:08:01.662165 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 5 15:08:01.662732 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 5 15:08:01.662804 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 5 15:08:01.678930 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 5 15:08:01.858164 kernel: hv_netvsc 000d3a6d-f9ce-000d-3a6d-f9ce000d3a6d eth0: Data path switched from VF: enP10705s1 Nov 5 15:08:01.679060 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 5 15:08:01.693098 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 5 15:08:01.695110 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 5 15:08:01.705172 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 5 15:08:01.717642 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 5 15:08:01.717692 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:08:01.730447 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 5 15:08:01.739205 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 5 15:08:01.739285 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:08:01.744484 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 5 15:08:01.744522 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:08:01.751977 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 5 15:08:01.752011 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 5 15:08:01.761408 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:08:01.784457 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 5 15:08:01.786952 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:08:01.796060 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 5 15:08:01.796104 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 5 15:08:01.804008 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 5 15:08:01.804039 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:08:01.812323 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 5 15:08:01.812375 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:08:01.824810 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 5 15:08:01.824852 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 5 15:08:01.836336 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 15:08:01.836382 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:08:01.862823 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 5 15:08:01.872132 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 5 15:08:01.872211 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:08:01.882379 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 5 15:08:01.882472 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:08:01.892338 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:08:01.892389 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:08:01.898188 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 5 15:08:01.898276 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 5 15:08:01.907779 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 5 15:08:01.907902 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 5 15:08:01.917578 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 5 15:08:01.917669 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 5 15:08:01.926078 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 5 15:08:01.934410 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 5 15:08:01.934498 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 5 15:08:01.944556 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 5 15:08:02.192129 systemd[1]: Switching root. Nov 5 15:08:02.286974 systemd-journald[505]: Journal stopped Nov 5 15:08:12.663005 systemd-journald[505]: Received SIGTERM from PID 1 (systemd). Nov 5 15:08:12.663025 kernel: SELinux: policy capability network_peer_controls=1 Nov 5 15:08:12.663034 kernel: SELinux: policy capability open_perms=1 Nov 5 15:08:12.663042 kernel: SELinux: policy capability extended_socket_class=1 Nov 5 15:08:12.663048 kernel: SELinux: policy capability always_check_network=0 Nov 5 15:08:12.663054 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 5 15:08:12.663060 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 5 15:08:12.663066 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 5 15:08:12.663071 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 5 15:08:12.663078 kernel: SELinux: policy capability userspace_initial_context=0 Nov 5 15:08:12.663084 kernel: audit: type=1403 audit(1762355283.256:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 5 15:08:12.663091 systemd[1]: Successfully loaded SELinux policy in 184.255ms. Nov 5 15:08:12.663098 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 4.580ms. Nov 5 15:08:12.663105 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:08:12.663113 systemd[1]: Detected virtualization microsoft. Nov 5 15:08:12.663119 systemd[1]: Detected architecture arm64. Nov 5 15:08:12.663126 systemd[1]: Detected first boot. Nov 5 15:08:12.663133 systemd[1]: Hostname set to . Nov 5 15:08:12.663139 systemd[1]: Initializing machine ID from random generator. Nov 5 15:08:12.663147 zram_generator::config[1467]: No configuration found. Nov 5 15:08:12.663156 kernel: NET: Registered PF_VSOCK protocol family Nov 5 15:08:12.663162 systemd[1]: Populated /etc with preset unit settings. Nov 5 15:08:12.663168 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 5 15:08:12.663175 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 5 15:08:12.663181 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 5 15:08:12.663189 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 5 15:08:12.663196 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 5 15:08:12.663203 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 5 15:08:12.663209 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 5 15:08:12.663216 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 5 15:08:12.663222 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 5 15:08:12.663229 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 5 15:08:12.663236 systemd[1]: Created slice user.slice - User and Session Slice. Nov 5 15:08:12.663242 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:08:12.663249 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:08:12.663255 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 5 15:08:12.663262 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 5 15:08:12.663269 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 5 15:08:12.663276 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:08:12.663282 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 5 15:08:12.663290 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:08:12.663298 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:08:12.663305 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 5 15:08:12.663312 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 5 15:08:12.663319 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 5 15:08:12.663325 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 5 15:08:12.663332 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:08:12.663339 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:08:12.663345 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:08:12.663352 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:08:12.663359 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 5 15:08:12.663366 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 5 15:08:12.663372 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 5 15:08:12.663379 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:08:12.663387 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:08:12.663393 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:08:12.663400 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 5 15:08:12.663407 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 5 15:08:12.663413 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 5 15:08:12.663420 systemd[1]: Mounting media.mount - External Media Directory... Nov 5 15:08:12.663428 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 5 15:08:12.663434 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 5 15:08:12.663441 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 5 15:08:12.663448 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 5 15:08:12.663455 systemd[1]: Reached target machines.target - Containers. Nov 5 15:08:12.663462 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 5 15:08:12.663469 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:08:12.663476 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:08:12.663483 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 5 15:08:12.663490 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:08:12.663497 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:08:12.663503 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:08:12.663510 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 5 15:08:12.663516 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:08:12.663524 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 5 15:08:12.663531 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 5 15:08:12.663538 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 5 15:08:12.663544 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 5 15:08:12.663551 systemd[1]: Stopped systemd-fsck-usr.service. Nov 5 15:08:12.663558 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:08:12.663565 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:08:12.663572 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:08:12.663578 kernel: fuse: init (API version 7.41) Nov 5 15:08:12.663586 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:08:12.663592 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 5 15:08:12.663599 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 5 15:08:12.663606 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:08:12.663613 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 5 15:08:12.663620 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 5 15:08:12.663626 systemd[1]: Mounted media.mount - External Media Directory. Nov 5 15:08:12.663633 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 5 15:08:12.663639 kernel: ACPI: bus type drm_connector registered Nov 5 15:08:12.663657 systemd-journald[1550]: Collecting audit messages is disabled. Nov 5 15:08:12.663672 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 5 15:08:12.663680 systemd-journald[1550]: Journal started Nov 5 15:08:12.663695 systemd-journald[1550]: Runtime Journal (/run/log/journal/3e2724eede3e42aaaa841719f8ed0624) is 8M, max 78.3M, 70.3M free. Nov 5 15:08:11.754039 systemd[1]: Queued start job for default target multi-user.target. Nov 5 15:08:11.758333 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 5 15:08:11.758799 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 5 15:08:11.759112 systemd[1]: systemd-journald.service: Consumed 2.133s CPU time. Nov 5 15:08:12.678541 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:08:12.679451 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 5 15:08:12.684926 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 5 15:08:12.689848 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:08:12.695087 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 5 15:08:12.695228 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 5 15:08:12.700259 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:08:12.700396 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:08:12.705234 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:08:12.705351 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:08:12.709921 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:08:12.710046 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:08:12.715314 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 5 15:08:12.715428 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 5 15:08:12.720048 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:08:12.720180 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:08:12.724826 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:08:12.729771 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:08:12.736365 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 5 15:08:12.742094 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 5 15:08:12.748371 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:08:12.761152 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:08:12.766430 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 5 15:08:12.772546 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 5 15:08:12.790006 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 5 15:08:12.795062 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 5 15:08:12.795098 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:08:12.799901 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 5 15:08:12.805173 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:08:12.821527 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 5 15:08:12.835579 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 5 15:08:12.840250 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:08:12.841140 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 5 15:08:12.845584 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:08:12.847040 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:08:12.853148 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 5 15:08:12.861223 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 5 15:08:12.868844 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 5 15:08:12.875271 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 5 15:08:12.883538 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 5 15:08:12.891170 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 5 15:08:12.897799 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 5 15:08:12.912636 systemd-journald[1550]: Time spent on flushing to /var/log/journal/3e2724eede3e42aaaa841719f8ed0624 is 10.909ms for 898 entries. Nov 5 15:08:12.912636 systemd-journald[1550]: System Journal (/var/log/journal/3e2724eede3e42aaaa841719f8ed0624) is 8M, max 2.2G, 2.2G free. Nov 5 15:08:12.958843 kernel: loop1: detected capacity change from 0 to 200800 Nov 5 15:08:12.958937 systemd-journald[1550]: Received client request to flush runtime journal. Nov 5 15:08:12.960732 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 5 15:08:12.977908 kernel: loop2: detected capacity change from 0 to 119344 Nov 5 15:08:13.008834 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 5 15:08:13.010643 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 5 15:08:13.036321 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:08:13.629906 kernel: loop3: detected capacity change from 0 to 100624 Nov 5 15:08:13.735403 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 5 15:08:13.743066 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:08:13.750112 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:08:13.791117 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 5 15:08:13.855580 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 5 15:08:13.860598 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 5 15:08:13.954451 systemd-resolved[1623]: Positive Trust Anchors: Nov 5 15:08:13.954465 systemd-resolved[1623]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:08:13.954467 systemd-resolved[1623]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:08:13.954486 systemd-resolved[1623]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 15:08:13.996651 systemd-tmpfiles[1624]: ACLs are not supported, ignoring. Nov 5 15:08:13.996667 systemd-tmpfiles[1624]: ACLs are not supported, ignoring. Nov 5 15:08:14.000962 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:08:14.007970 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:08:14.034215 systemd-udevd[1635]: Using default interface naming scheme 'v257'. Nov 5 15:08:14.068933 systemd-resolved[1623]: Using system hostname 'ci-4487.0.1-a-6d305da4f4'. Nov 5 15:08:14.070293 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:08:14.075103 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:08:14.174908 kernel: loop4: detected capacity change from 0 to 27760 Nov 5 15:08:14.749328 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:08:14.758034 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:08:14.805908 kernel: loop5: detected capacity change from 0 to 200800 Nov 5 15:08:14.822938 kernel: loop6: detected capacity change from 0 to 119344 Nov 5 15:08:14.835916 kernel: loop7: detected capacity change from 0 to 100624 Nov 5 15:08:14.851929 kernel: loop1: detected capacity change from 0 to 27760 Nov 5 15:08:14.859427 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 5 15:08:14.864945 (sd-merge)[1662]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw', 'oem-azure.raw'. Nov 5 15:08:14.868184 (sd-merge)[1662]: Merged extensions into '/usr'. Nov 5 15:08:14.873202 systemd[1]: Reload requested from client PID 1606 ('systemd-sysext') (unit systemd-sysext.service)... Nov 5 15:08:14.873219 systemd[1]: Reloading... Nov 5 15:08:14.899998 kernel: mousedev: PS/2 mouse device common for all mice Nov 5 15:08:14.900086 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#132 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 5 15:08:14.953916 zram_generator::config[1715]: No configuration found. Nov 5 15:08:14.986764 kernel: hv_vmbus: registering driver hyperv_fb Nov 5 15:08:14.986867 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 5 15:08:14.993387 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 5 15:08:14.999344 kernel: Console: switching to colour dummy device 80x25 Nov 5 15:08:15.010155 kernel: hv_vmbus: registering driver hv_balloon Nov 5 15:08:15.010259 kernel: Console: switching to colour frame buffer device 128x48 Nov 5 15:08:15.028608 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 5 15:08:15.028712 kernel: hv_balloon: Memory hot add disabled on ARM64 Nov 5 15:08:15.075706 systemd-networkd[1641]: lo: Link UP Nov 5 15:08:15.075717 systemd-networkd[1641]: lo: Gained carrier Nov 5 15:08:15.082175 systemd-networkd[1641]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:08:15.082183 systemd-networkd[1641]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:08:15.137899 kernel: mlx5_core 29d1:00:02.0 enP10705s1: Link up Nov 5 15:08:15.166917 kernel: hv_netvsc 000d3a6d-f9ce-000d-3a6d-f9ce000d3a6d eth0: Data path switched to VF: enP10705s1 Nov 5 15:08:15.168660 systemd-networkd[1641]: enP10705s1: Link UP Nov 5 15:08:15.169381 systemd-networkd[1641]: eth0: Link UP Nov 5 15:08:15.169387 systemd-networkd[1641]: eth0: Gained carrier Nov 5 15:08:15.169404 systemd-networkd[1641]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:08:15.175022 systemd-networkd[1641]: enP10705s1: Gained carrier Nov 5 15:08:15.181917 systemd-networkd[1641]: eth0: DHCPv4 address 10.200.20.18/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 5 15:08:15.204902 kernel: MACsec IEEE 802.1AE Nov 5 15:08:15.222867 systemd[1]: Reloading finished in 349 ms. Nov 5 15:08:15.241382 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:08:15.246820 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 5 15:08:15.265555 systemd[1]: Reached target network.target - Network. Nov 5 15:08:15.277844 systemd[1]: Starting ensure-sysext.service... Nov 5 15:08:15.284091 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 5 15:08:15.290181 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 5 15:08:15.301706 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:08:15.309043 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:08:15.324600 systemd[1]: Reload requested from client PID 1787 ('systemctl') (unit ensure-sysext.service)... Nov 5 15:08:15.324610 systemd[1]: Reloading... Nov 5 15:08:15.392796 systemd-tmpfiles[1791]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 5 15:08:15.392820 systemd-tmpfiles[1791]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 5 15:08:15.393439 systemd-tmpfiles[1791]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 5 15:08:15.393591 systemd-tmpfiles[1791]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 5 15:08:15.394064 systemd-tmpfiles[1791]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 5 15:08:15.394208 systemd-tmpfiles[1791]: ACLs are not supported, ignoring. Nov 5 15:08:15.394249 systemd-tmpfiles[1791]: ACLs are not supported, ignoring. Nov 5 15:08:15.423900 zram_generator::config[1876]: No configuration found. Nov 5 15:08:15.478184 systemd-tmpfiles[1791]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:08:15.480059 systemd-tmpfiles[1791]: Skipping /boot Nov 5 15:08:15.485675 systemd-tmpfiles[1791]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:08:15.485797 systemd-tmpfiles[1791]: Skipping /boot Nov 5 15:08:15.579767 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 5 15:08:15.584591 systemd[1]: Reloading finished in 259 ms. Nov 5 15:08:15.596442 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 5 15:08:15.608379 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:08:15.636523 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 15:08:15.650966 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 5 15:08:15.655964 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:08:15.657835 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 5 15:08:15.663250 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:08:15.669999 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:08:15.677160 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:08:15.681390 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:08:15.682947 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 5 15:08:15.688313 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:08:15.692201 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 5 15:08:15.700854 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 5 15:08:15.708432 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:08:15.708611 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:08:15.713723 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:08:15.713905 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:08:15.720166 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:08:15.720310 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:08:15.733096 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:08:15.736054 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:08:15.748871 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:08:15.757183 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:08:15.763321 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:08:15.767969 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:08:15.768090 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:08:15.768197 systemd[1]: Reached target time-set.target - System Time Set. Nov 5 15:08:15.773343 systemd[1]: Finished ensure-sysext.service. Nov 5 15:08:15.777187 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:08:15.777344 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:08:15.786185 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:08:15.786344 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:08:15.791188 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 5 15:08:15.796462 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:08:15.796606 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:08:15.802205 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:08:15.802331 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:08:15.807145 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:08:15.807335 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:08:15.818383 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:08:15.818568 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:08:15.819783 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:08:15.832085 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 5 15:08:16.022324 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 5 15:08:16.325073 systemd-networkd[1641]: eth0: Gained IPv6LL Nov 5 15:08:16.326970 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 5 15:08:16.333328 systemd[1]: Reached target network-online.target - Network is Online. Nov 5 15:08:16.381600 augenrules[1986]: No rules Nov 5 15:08:16.382945 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 15:08:16.383158 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 15:08:17.042309 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:08:18.570588 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 5 15:08:18.576034 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 5 15:08:26.262731 ldconfig[1939]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 5 15:08:26.273249 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 5 15:08:26.279767 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 5 15:08:26.311226 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 5 15:08:26.316094 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:08:26.320731 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 5 15:08:26.325843 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 5 15:08:26.331127 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 5 15:08:26.335666 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 5 15:08:26.340751 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 5 15:08:26.345790 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 5 15:08:26.345821 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:08:26.349858 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:08:26.369527 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 5 15:08:26.375220 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 5 15:08:26.380474 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 5 15:08:26.385597 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 5 15:08:26.390820 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 5 15:08:26.396962 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 5 15:08:26.401356 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 5 15:08:26.406736 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 5 15:08:26.411182 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:08:26.415198 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:08:26.418895 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:08:26.418920 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:08:26.435594 systemd[1]: Starting chronyd.service - NTP client/server... Nov 5 15:08:26.448007 systemd[1]: Starting containerd.service - containerd container runtime... Nov 5 15:08:26.454016 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 5 15:08:26.463051 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 5 15:08:26.469554 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 5 15:08:26.477030 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 5 15:08:26.482309 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 5 15:08:26.486446 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 5 15:08:26.488040 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 5 15:08:26.492752 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 5 15:08:26.494668 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:08:26.500527 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 5 15:08:26.508161 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 5 15:08:26.508464 jq[2009]: false Nov 5 15:08:26.512918 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 5 15:08:26.518552 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 5 15:08:26.525354 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 5 15:08:26.530032 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 5 15:08:26.530470 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 5 15:08:26.531236 systemd[1]: Starting update-engine.service - Update Engine... Nov 5 15:08:26.537776 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 5 15:08:26.545508 KVP[2011]: KVP starting; pid is:2011 Nov 5 15:08:26.550497 extend-filesystems[2010]: Found /dev/sda6 Nov 5 15:08:26.567288 kernel: hv_utils: KVP IC version 4.0 Nov 5 15:08:26.558411 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 5 15:08:26.555721 chronyd[2001]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 5 15:08:26.565053 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 5 15:08:26.556913 KVP[2011]: KVP LIC Version: 3.1 Nov 5 15:08:26.569471 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 5 15:08:26.573473 jq[2025]: true Nov 5 15:08:26.572867 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 5 15:08:26.573721 extend-filesystems[2010]: Found /dev/sda9 Nov 5 15:08:26.581259 extend-filesystems[2010]: Checking size of /dev/sda9 Nov 5 15:08:26.573778 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 5 15:08:26.592212 chronyd[2001]: Timezone right/UTC failed leap second check, ignoring Nov 5 15:08:26.592399 chronyd[2001]: Loaded seccomp filter (level 2) Nov 5 15:08:26.598054 systemd[1]: Started chronyd.service - NTP client/server. Nov 5 15:08:26.614187 jq[2042]: true Nov 5 15:08:26.629477 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 5 15:08:26.639327 update_engine[2021]: I20251105 15:08:26.636601 2021 main.cc:92] Flatcar Update Engine starting Nov 5 15:08:26.644698 extend-filesystems[2010]: Resized partition /dev/sda9 Nov 5 15:08:26.656748 (ntainerd)[2056]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 5 15:08:26.657354 systemd[1]: motdgen.service: Deactivated successfully. Nov 5 15:08:26.657551 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 5 15:08:26.669020 systemd-logind[2020]: New seat seat0. Nov 5 15:08:26.671975 systemd-logind[2020]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 5 15:08:26.672190 systemd[1]: Started systemd-logind.service - User Login Management. Nov 5 15:08:26.682930 extend-filesystems[2075]: resize2fs 1.47.3 (8-Jul-2025) Nov 5 15:08:26.703008 kernel: EXT4-fs (sda9): resizing filesystem from 6359552 to 6376955 blocks Nov 5 15:08:26.722622 kernel: EXT4-fs (sda9): resized filesystem to 6376955 Nov 5 15:08:26.736013 extend-filesystems[2075]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Nov 5 15:08:26.736013 extend-filesystems[2075]: old_desc_blocks = 4, new_desc_blocks = 4 Nov 5 15:08:26.736013 extend-filesystems[2075]: The filesystem on /dev/sda9 is now 6376955 (4k) blocks long. Nov 5 15:08:26.781494 extend-filesystems[2010]: Resized filesystem in /dev/sda9 Nov 5 15:08:26.738043 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 5 15:08:26.739736 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 5 15:08:26.792942 bash[2090]: Updated "/home/core/.ssh/authorized_keys" Nov 5 15:08:26.794625 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 5 15:08:26.800925 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 5 15:08:26.943468 sshd_keygen[2038]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 5 15:08:26.963539 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 5 15:08:26.971051 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 5 15:08:26.975792 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 5 15:08:26.999271 systemd[1]: issuegen.service: Deactivated successfully. Nov 5 15:08:26.999458 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 5 15:08:27.009042 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 5 15:08:27.020097 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 5 15:08:27.057388 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 5 15:08:27.068190 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 5 15:08:27.069222 dbus-daemon[2004]: [system] SELinux support is enabled Nov 5 15:08:27.078103 update_engine[2021]: I20251105 15:08:27.076390 2021 update_check_scheduler.cc:74] Next update check in 5m8s Nov 5 15:08:27.079067 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 5 15:08:27.087332 systemd[1]: Reached target getty.target - Login Prompts. Nov 5 15:08:27.093410 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 5 15:08:27.099814 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 5 15:08:27.100377 dbus-daemon[2004]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 5 15:08:27.099843 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 5 15:08:27.104888 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 5 15:08:27.104901 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 5 15:08:27.110189 systemd[1]: Started update-engine.service - Update Engine. Nov 5 15:08:27.115730 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 5 15:08:27.186296 coreos-metadata[2003]: Nov 05 15:08:27.185 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 5 15:08:27.188944 coreos-metadata[2003]: Nov 05 15:08:27.188 INFO Fetch successful Nov 5 15:08:27.189276 coreos-metadata[2003]: Nov 05 15:08:27.189 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 5 15:08:27.192870 coreos-metadata[2003]: Nov 05 15:08:27.192 INFO Fetch successful Nov 5 15:08:27.193280 coreos-metadata[2003]: Nov 05 15:08:27.193 INFO Fetching http://168.63.129.16/machine/cdb64ac3-863a-4065-9ddc-938c10d7f331/4beeb434%2D56a7%2D4cdb%2Db7aa%2Df25b39c970ea.%5Fci%2D4487.0.1%2Da%2D6d305da4f4?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 5 15:08:27.194573 coreos-metadata[2003]: Nov 05 15:08:27.194 INFO Fetch successful Nov 5 15:08:27.194573 coreos-metadata[2003]: Nov 05 15:08:27.194 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 5 15:08:27.201361 coreos-metadata[2003]: Nov 05 15:08:27.201 INFO Fetch successful Nov 5 15:08:27.333060 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:08:27.370063 locksmithd[2178]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 5 15:08:27.592037 (kubelet)[2190]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 5 15:08:27.608165 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 5 15:08:27.613476 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 5 15:08:27.731206 containerd[2056]: time="2025-11-05T15:08:27Z" 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 Nov 5 15:08:27.732890 containerd[2056]: time="2025-11-05T15:08:27.732083840Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 5 15:08:27.739352 containerd[2056]: time="2025-11-05T15:08:27.739315256Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.232µs" Nov 5 15:08:27.739436 containerd[2056]: time="2025-11-05T15:08:27.739424528Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 5 15:08:27.739505 containerd[2056]: time="2025-11-05T15:08:27.739493384Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 5 15:08:27.739697 containerd[2056]: time="2025-11-05T15:08:27.739676808Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 5 15:08:27.739754 containerd[2056]: time="2025-11-05T15:08:27.739742616Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 5 15:08:27.739806 containerd[2056]: time="2025-11-05T15:08:27.739795392Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 15:08:27.739926 containerd[2056]: time="2025-11-05T15:08:27.739911160Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 15:08:27.739991 containerd[2056]: time="2025-11-05T15:08:27.739978432Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 5 15:08:27.740242 containerd[2056]: time="2025-11-05T15:08:27.740218584Z" 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 Nov 5 15:08:27.740297 containerd[2056]: time="2025-11-05T15:08:27.740285960Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:08:27.740347 containerd[2056]: time="2025-11-05T15:08:27.740335248Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:08:27.740385 containerd[2056]: time="2025-11-05T15:08:27.740373960Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 5 15:08:27.740514 containerd[2056]: time="2025-11-05T15:08:27.740497704Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 5 15:08:27.740774 containerd[2056]: time="2025-11-05T15:08:27.740751544Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 5 15:08:27.740853 containerd[2056]: time="2025-11-05T15:08:27.740839928Z" 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 Nov 5 15:08:27.740916 containerd[2056]: time="2025-11-05T15:08:27.740903352Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 5 15:08:27.741009 containerd[2056]: time="2025-11-05T15:08:27.740996248Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 5 15:08:27.741270 containerd[2056]: time="2025-11-05T15:08:27.741238968Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 5 15:08:27.741358 containerd[2056]: time="2025-11-05T15:08:27.741341248Z" level=info msg="metadata content store policy set" policy=shared Nov 5 15:08:27.755838 containerd[2056]: time="2025-11-05T15:08:27.755779936Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 5 15:08:27.755940 containerd[2056]: time="2025-11-05T15:08:27.755911128Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 5 15:08:27.755940 containerd[2056]: time="2025-11-05T15:08:27.755928080Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 5 15:08:27.755940 containerd[2056]: time="2025-11-05T15:08:27.755936984Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 5 15:08:27.756009 containerd[2056]: time="2025-11-05T15:08:27.755946296Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 5 15:08:27.756009 containerd[2056]: time="2025-11-05T15:08:27.755953312Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 5 15:08:27.756009 containerd[2056]: time="2025-11-05T15:08:27.755965392Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 5 15:08:27.756009 containerd[2056]: time="2025-11-05T15:08:27.755987160Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 5 15:08:27.756009 containerd[2056]: time="2025-11-05T15:08:27.755995704Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 5 15:08:27.756009 containerd[2056]: time="2025-11-05T15:08:27.756002656Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 5 15:08:27.756009 containerd[2056]: time="2025-11-05T15:08:27.756008976Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 5 15:08:27.756099 containerd[2056]: time="2025-11-05T15:08:27.756019712Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 5 15:08:27.756234 containerd[2056]: time="2025-11-05T15:08:27.756210264Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 5 15:08:27.756234 containerd[2056]: time="2025-11-05T15:08:27.756233768Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 5 15:08:27.756272 containerd[2056]: time="2025-11-05T15:08:27.756245776Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 5 15:08:27.756272 containerd[2056]: time="2025-11-05T15:08:27.756253688Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 5 15:08:27.756272 containerd[2056]: time="2025-11-05T15:08:27.756261184Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 5 15:08:27.756272 containerd[2056]: time="2025-11-05T15:08:27.756267688Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 5 15:08:27.756326 containerd[2056]: time="2025-11-05T15:08:27.756274592Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 5 15:08:27.756326 containerd[2056]: time="2025-11-05T15:08:27.756280616Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 5 15:08:27.756326 containerd[2056]: time="2025-11-05T15:08:27.756289464Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 5 15:08:27.756326 containerd[2056]: time="2025-11-05T15:08:27.756296048Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 5 15:08:27.756326 containerd[2056]: time="2025-11-05T15:08:27.756306920Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 5 15:08:27.757622 containerd[2056]: time="2025-11-05T15:08:27.757184064Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 5 15:08:27.757622 containerd[2056]: time="2025-11-05T15:08:27.757221232Z" level=info msg="Start snapshots syncer" Nov 5 15:08:27.757622 containerd[2056]: time="2025-11-05T15:08:27.757242512Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 5 15:08:27.757736 containerd[2056]: time="2025-11-05T15:08:27.757482592Z" 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\"}" Nov 5 15:08:27.757736 containerd[2056]: time="2025-11-05T15:08:27.757526000Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 5 15:08:27.758049 containerd[2056]: time="2025-11-05T15:08:27.758025400Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 5 15:08:27.758265 containerd[2056]: time="2025-11-05T15:08:27.758246464Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 5 15:08:27.758335 containerd[2056]: time="2025-11-05T15:08:27.758324024Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 5 15:08:27.758379 containerd[2056]: time="2025-11-05T15:08:27.758368904Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 5 15:08:27.758414 containerd[2056]: time="2025-11-05T15:08:27.758404800Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 5 15:08:27.758454 containerd[2056]: time="2025-11-05T15:08:27.758445232Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 5 15:08:27.758492 containerd[2056]: time="2025-11-05T15:08:27.758482424Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 5 15:08:27.758527 containerd[2056]: time="2025-11-05T15:08:27.758518608Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 5 15:08:27.758596 containerd[2056]: time="2025-11-05T15:08:27.758584424Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 5 15:08:27.758638 containerd[2056]: time="2025-11-05T15:08:27.758629400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 5 15:08:27.758673 containerd[2056]: time="2025-11-05T15:08:27.758664824Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 5 15:08:27.758739 containerd[2056]: time="2025-11-05T15:08:27.758727808Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 5 15:08:27.758786 containerd[2056]: time="2025-11-05T15:08:27.758774752Z" 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 Nov 5 15:08:27.758815 containerd[2056]: time="2025-11-05T15:08:27.758807592Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 15:08:27.758855 containerd[2056]: time="2025-11-05T15:08:27.758844672Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 15:08:27.758926 containerd[2056]: time="2025-11-05T15:08:27.758911264Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 5 15:08:27.758970 containerd[2056]: time="2025-11-05T15:08:27.758961672Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 5 15:08:27.759017 containerd[2056]: time="2025-11-05T15:08:27.759008176Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 5 15:08:27.759067 containerd[2056]: time="2025-11-05T15:08:27.759057968Z" level=info msg="runtime interface created" Nov 5 15:08:27.759104 containerd[2056]: time="2025-11-05T15:08:27.759094168Z" level=info msg="created NRI interface" Nov 5 15:08:27.759146 containerd[2056]: time="2025-11-05T15:08:27.759134680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 5 15:08:27.759190 containerd[2056]: time="2025-11-05T15:08:27.759180520Z" level=info msg="Connect containerd service" Nov 5 15:08:27.759253 containerd[2056]: time="2025-11-05T15:08:27.759242544Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 5 15:08:27.761268 containerd[2056]: time="2025-11-05T15:08:27.761027408Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 5 15:08:27.905749 kubelet[2190]: E1105 15:08:27.905635 2190 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 5 15:08:27.908430 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 5 15:08:27.908539 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 5 15:08:27.908931 systemd[1]: kubelet.service: Consumed 507ms CPU time, 248.5M memory peak. Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.122987344Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123043888Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123068040Z" level=info msg="Start subscribing containerd event" Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123103408Z" level=info msg="Start recovering state" Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123169272Z" level=info msg="Start event monitor" Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123178224Z" level=info msg="Start cni network conf syncer for default" Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123188296Z" level=info msg="Start streaming server" Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123194016Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123198488Z" level=info msg="runtime interface starting up..." Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123202208Z" level=info msg="starting plugins..." Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123213384Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 5 15:08:28.123899 containerd[2056]: time="2025-11-05T15:08:28.123312576Z" level=info msg="containerd successfully booted in 0.392438s" Nov 5 15:08:28.123464 systemd[1]: Started containerd.service - containerd container runtime. Nov 5 15:08:28.128380 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 5 15:08:28.137972 systemd[1]: Startup finished in 3.373s (kernel) + 18.094s (initrd) + 25.064s (userspace) = 46.532s. Nov 5 15:08:28.798261 login[2177]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 5 15:08:28.832956 login[2174]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:08:28.838913 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 5 15:08:28.839773 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 5 15:08:28.845431 systemd-logind[2020]: New session 2 of user core. Nov 5 15:08:28.868178 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 5 15:08:28.870199 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 5 15:08:28.898791 (systemd)[2226]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:08:28.901178 systemd-logind[2020]: New session c1 of user core. Nov 5 15:08:29.200868 systemd[2226]: Queued start job for default target default.target. Nov 5 15:08:29.207602 systemd[2226]: Created slice app.slice - User Application Slice. Nov 5 15:08:29.207625 systemd[2226]: Reached target paths.target - Paths. Nov 5 15:08:29.207656 systemd[2226]: Reached target timers.target - Timers. Nov 5 15:08:29.209228 systemd[2226]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 5 15:08:29.218535 systemd[2226]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 5 15:08:29.218837 systemd[2226]: Reached target sockets.target - Sockets. Nov 5 15:08:29.219148 systemd[2226]: Reached target basic.target - Basic System. Nov 5 15:08:29.219298 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 5 15:08:29.220148 systemd[2226]: Reached target default.target - Main User Target. Nov 5 15:08:29.220172 systemd[2226]: Startup finished in 314ms. Nov 5 15:08:29.226327 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 5 15:08:29.293578 waagent[2172]: 2025-11-05T15:08:29.289306Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 5 15:08:29.293918 waagent[2172]: 2025-11-05T15:08:29.293728Z INFO Daemon Daemon OS: flatcar 4487.0.1 Nov 5 15:08:29.297126 waagent[2172]: 2025-11-05T15:08:29.297082Z INFO Daemon Daemon Python: 3.11.13 Nov 5 15:08:29.302865 waagent[2172]: 2025-11-05T15:08:29.300759Z INFO Daemon Daemon Run daemon Nov 5 15:08:29.304039 waagent[2172]: 2025-11-05T15:08:29.304000Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.1' Nov 5 15:08:29.310746 waagent[2172]: 2025-11-05T15:08:29.310707Z INFO Daemon Daemon Using waagent for provisioning Nov 5 15:08:29.314778 waagent[2172]: 2025-11-05T15:08:29.314744Z INFO Daemon Daemon Activate resource disk Nov 5 15:08:29.319538 waagent[2172]: 2025-11-05T15:08:29.319178Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 5 15:08:29.327752 waagent[2172]: 2025-11-05T15:08:29.327705Z INFO Daemon Daemon Found device: None Nov 5 15:08:29.331726 waagent[2172]: 2025-11-05T15:08:29.331680Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Nov 5 15:08:29.338052 waagent[2172]: 2025-11-05T15:08:29.338013Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Nov 5 15:08:29.347295 waagent[2172]: 2025-11-05T15:08:29.347237Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 5 15:08:29.351601 waagent[2172]: 2025-11-05T15:08:29.351562Z INFO Daemon Daemon Running default provisioning handler Nov 5 15:08:29.360897 waagent[2172]: 2025-11-05T15:08:29.360816Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 5 15:08:29.371403 waagent[2172]: 2025-11-05T15:08:29.371346Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 5 15:08:29.378893 waagent[2172]: 2025-11-05T15:08:29.378794Z INFO Daemon Daemon cloud-init is enabled: False Nov 5 15:08:29.382922 waagent[2172]: 2025-11-05T15:08:29.382862Z INFO Daemon Daemon Copying ovf-env.xml Nov 5 15:08:29.527362 waagent[2172]: 2025-11-05T15:08:29.527224Z INFO Daemon Daemon Successfully mounted dvd Nov 5 15:08:29.558521 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 5 15:08:29.560817 waagent[2172]: 2025-11-05T15:08:29.560746Z INFO Daemon Daemon Detect protocol endpoint Nov 5 15:08:29.564523 waagent[2172]: 2025-11-05T15:08:29.564473Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 5 15:08:29.569149 waagent[2172]: 2025-11-05T15:08:29.569107Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 5 15:08:29.573804 waagent[2172]: 2025-11-05T15:08:29.573768Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 5 15:08:29.577965 waagent[2172]: 2025-11-05T15:08:29.577925Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 5 15:08:29.581799 waagent[2172]: 2025-11-05T15:08:29.581766Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 5 15:08:29.669191 waagent[2172]: 2025-11-05T15:08:29.669137Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 5 15:08:29.674003 waagent[2172]: 2025-11-05T15:08:29.673981Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 5 15:08:29.677686 waagent[2172]: 2025-11-05T15:08:29.677660Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 5 15:08:29.800013 login[2177]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:08:29.803907 systemd-logind[2020]: New session 1 of user core. Nov 5 15:08:29.808000 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 5 15:08:29.825081 waagent[2172]: 2025-11-05T15:08:29.824996Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 5 15:08:29.829820 waagent[2172]: 2025-11-05T15:08:29.829772Z INFO Daemon Daemon Forcing an update of the goal state. Nov 5 15:08:29.836914 waagent[2172]: 2025-11-05T15:08:29.836850Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 5 15:08:29.856847 waagent[2172]: 2025-11-05T15:08:29.856801Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 5 15:08:29.861215 waagent[2172]: 2025-11-05T15:08:29.861176Z INFO Daemon Nov 5 15:08:29.863273 waagent[2172]: 2025-11-05T15:08:29.863244Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: eee78512-e825-4c91-a067-722b0636f2e6 eTag: 16247179457534419485 source: Fabric] Nov 5 15:08:29.871405 waagent[2172]: 2025-11-05T15:08:29.871370Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 5 15:08:29.876127 waagent[2172]: 2025-11-05T15:08:29.876095Z INFO Daemon Nov 5 15:08:29.878150 waagent[2172]: 2025-11-05T15:08:29.878122Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 5 15:08:29.886082 waagent[2172]: 2025-11-05T15:08:29.886050Z INFO Daemon Daemon Downloading artifacts profile blob Nov 5 15:08:29.945746 waagent[2172]: 2025-11-05T15:08:29.945663Z INFO Daemon Downloaded certificate {'thumbprint': 'FF101FFD482FC54CF384FF4C3E3FC079805C3F2B', 'hasPrivateKey': True} Nov 5 15:08:29.953067 waagent[2172]: 2025-11-05T15:08:29.953020Z INFO Daemon Fetch goal state completed Nov 5 15:08:29.962559 waagent[2172]: 2025-11-05T15:08:29.962516Z INFO Daemon Daemon Starting provisioning Nov 5 15:08:29.966375 waagent[2172]: 2025-11-05T15:08:29.966344Z INFO Daemon Daemon Handle ovf-env.xml. Nov 5 15:08:29.969527 waagent[2172]: 2025-11-05T15:08:29.969501Z INFO Daemon Daemon Set hostname [ci-4487.0.1-a-6d305da4f4] Nov 5 15:08:30.006210 waagent[2172]: 2025-11-05T15:08:30.006146Z INFO Daemon Daemon Publish hostname [ci-4487.0.1-a-6d305da4f4] Nov 5 15:08:30.011426 waagent[2172]: 2025-11-05T15:08:30.011377Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 5 15:08:30.016023 waagent[2172]: 2025-11-05T15:08:30.015984Z INFO Daemon Daemon Primary interface is [eth0] Nov 5 15:08:30.025761 systemd-networkd[1641]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:08:30.025769 systemd-networkd[1641]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:08:30.025843 systemd-networkd[1641]: eth0: DHCP lease lost Nov 5 15:08:30.038907 waagent[2172]: 2025-11-05T15:08:30.038378Z INFO Daemon Daemon Create user account if not exists Nov 5 15:08:30.042682 waagent[2172]: 2025-11-05T15:08:30.042628Z INFO Daemon Daemon User core already exists, skip useradd Nov 5 15:08:30.046966 waagent[2172]: 2025-11-05T15:08:30.046914Z INFO Daemon Daemon Configure sudoer Nov 5 15:08:30.053936 systemd-networkd[1641]: eth0: DHCPv4 address 10.200.20.18/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 5 15:08:30.054891 waagent[2172]: 2025-11-05T15:08:30.054434Z INFO Daemon Daemon Configure sshd Nov 5 15:08:30.062192 waagent[2172]: 2025-11-05T15:08:30.062126Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 5 15:08:30.071918 waagent[2172]: 2025-11-05T15:08:30.071859Z INFO Daemon Daemon Deploy ssh public key. Nov 5 15:08:31.213329 waagent[2172]: 2025-11-05T15:08:31.213281Z INFO Daemon Daemon Provisioning complete Nov 5 15:08:31.224770 waagent[2172]: 2025-11-05T15:08:31.224729Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 5 15:08:31.229851 waagent[2172]: 2025-11-05T15:08:31.229809Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 5 15:08:31.236788 waagent[2172]: 2025-11-05T15:08:31.236752Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 5 15:08:31.338374 waagent[2275]: 2025-11-05T15:08:31.338299Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 5 15:08:31.340125 waagent[2275]: 2025-11-05T15:08:31.338828Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.1 Nov 5 15:08:31.340125 waagent[2275]: 2025-11-05T15:08:31.338916Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 5 15:08:31.340125 waagent[2275]: 2025-11-05T15:08:31.338962Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Nov 5 15:08:31.427016 waagent[2275]: 2025-11-05T15:08:31.426948Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 5 15:08:31.427324 waagent[2275]: 2025-11-05T15:08:31.427293Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 5 15:08:31.427471 waagent[2275]: 2025-11-05T15:08:31.427445Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 5 15:08:31.433027 waagent[2275]: 2025-11-05T15:08:31.432971Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 5 15:08:31.438255 waagent[2275]: 2025-11-05T15:08:31.438220Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 5 15:08:31.438764 waagent[2275]: 2025-11-05T15:08:31.438732Z INFO ExtHandler Nov 5 15:08:31.438909 waagent[2275]: 2025-11-05T15:08:31.438864Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 60a3e32e-9ee7-4d82-a41a-dcc72e66b836 eTag: 16247179457534419485 source: Fabric] Nov 5 15:08:31.439216 waagent[2275]: 2025-11-05T15:08:31.439188Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 5 15:08:31.439714 waagent[2275]: 2025-11-05T15:08:31.439685Z INFO ExtHandler Nov 5 15:08:31.439849 waagent[2275]: 2025-11-05T15:08:31.439826Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 5 15:08:31.442673 waagent[2275]: 2025-11-05T15:08:31.442645Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 5 15:08:31.502068 waagent[2275]: 2025-11-05T15:08:31.501950Z INFO ExtHandler Downloaded certificate {'thumbprint': 'FF101FFD482FC54CF384FF4C3E3FC079805C3F2B', 'hasPrivateKey': True} Nov 5 15:08:31.502648 waagent[2275]: 2025-11-05T15:08:31.502611Z INFO ExtHandler Fetch goal state completed Nov 5 15:08:31.512963 waagent[2275]: 2025-11-05T15:08:31.512920Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 5 15:08:31.516916 waagent[2275]: 2025-11-05T15:08:31.516554Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2275 Nov 5 15:08:31.516916 waagent[2275]: 2025-11-05T15:08:31.516687Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 5 15:08:31.517163 waagent[2275]: 2025-11-05T15:08:31.517128Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 5 15:08:31.518382 waagent[2275]: 2025-11-05T15:08:31.518341Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.1', '', 'Flatcar Container Linux by Kinvolk'] Nov 5 15:08:31.518786 waagent[2275]: 2025-11-05T15:08:31.518751Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 5 15:08:31.519021 waagent[2275]: 2025-11-05T15:08:31.518991Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 5 15:08:31.519525 waagent[2275]: 2025-11-05T15:08:31.519491Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 5 15:08:31.570449 waagent[2275]: 2025-11-05T15:08:31.570410Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 5 15:08:31.570800 waagent[2275]: 2025-11-05T15:08:31.570764Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 5 15:08:31.575488 waagent[2275]: 2025-11-05T15:08:31.575462Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 5 15:08:31.591202 systemd[1]: Reload requested from client PID 2290 ('systemctl') (unit waagent.service)... Nov 5 15:08:31.591460 systemd[1]: Reloading... Nov 5 15:08:31.670968 zram_generator::config[2339]: No configuration found. Nov 5 15:08:31.818910 systemd[1]: Reloading finished in 227 ms. Nov 5 15:08:31.833935 waagent[2275]: 2025-11-05T15:08:31.833606Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 5 15:08:31.833935 waagent[2275]: 2025-11-05T15:08:31.833760Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 5 15:08:32.731804 waagent[2275]: 2025-11-05T15:08:32.731717Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 5 15:08:32.732132 waagent[2275]: 2025-11-05T15:08:32.732066Z 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] Nov 5 15:08:32.732746 waagent[2275]: 2025-11-05T15:08:32.732701Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 5 15:08:32.733018 waagent[2275]: 2025-11-05T15:08:32.732980Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 5 15:08:32.733805 waagent[2275]: 2025-11-05T15:08:32.733213Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 5 15:08:32.733805 waagent[2275]: 2025-11-05T15:08:32.733285Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 5 15:08:32.733805 waagent[2275]: 2025-11-05T15:08:32.733444Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 5 15:08:32.733805 waagent[2275]: 2025-11-05T15:08:32.733581Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 5 15:08:32.733805 waagent[2275]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 5 15:08:32.733805 waagent[2275]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Nov 5 15:08:32.733805 waagent[2275]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 5 15:08:32.733805 waagent[2275]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 5 15:08:32.733805 waagent[2275]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 5 15:08:32.733805 waagent[2275]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 5 15:08:32.734118 waagent[2275]: 2025-11-05T15:08:32.734079Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 5 15:08:32.734251 waagent[2275]: 2025-11-05T15:08:32.734217Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 5 15:08:32.734538 waagent[2275]: 2025-11-05T15:08:32.734510Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 5 15:08:32.734708 waagent[2275]: 2025-11-05T15:08:32.734638Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 5 15:08:32.734767 waagent[2275]: 2025-11-05T15:08:32.734743Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 5 15:08:32.734837 waagent[2275]: 2025-11-05T15:08:32.734815Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 5 15:08:32.735213 waagent[2275]: 2025-11-05T15:08:32.735180Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 5 15:08:32.735518 waagent[2275]: 2025-11-05T15:08:32.735479Z INFO EnvHandler ExtHandler Configure routes Nov 5 15:08:32.735554 waagent[2275]: 2025-11-05T15:08:32.735545Z INFO EnvHandler ExtHandler Gateway:None Nov 5 15:08:32.735581 waagent[2275]: 2025-11-05T15:08:32.735572Z INFO EnvHandler ExtHandler Routes:None Nov 5 15:08:32.740211 waagent[2275]: 2025-11-05T15:08:32.740168Z INFO ExtHandler ExtHandler Nov 5 15:08:32.740495 waagent[2275]: 2025-11-05T15:08:32.740467Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 2765e909-a1ee-4597-85e2-66813be0a0be correlation 3db44185-03a6-4907-b909-fb99cb7cfe7b created: 2025-11-05T15:07:07.168526Z] Nov 5 15:08:32.741194 waagent[2275]: 2025-11-05T15:08:32.741148Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 5 15:08:32.742192 waagent[2275]: 2025-11-05T15:08:32.742152Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Nov 5 15:08:32.786737 waagent[2275]: 2025-11-05T15:08:32.786668Z 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 Nov 5 15:08:32.786737 waagent[2275]: Try `iptables -h' or 'iptables --help' for more information.) Nov 5 15:08:32.787126 waagent[2275]: 2025-11-05T15:08:32.787090Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: A22853F8-04C0-40F9-917F-082EFDBF73E4;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 5 15:08:32.869515 waagent[2275]: 2025-11-05T15:08:32.869440Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 5 15:08:32.869515 waagent[2275]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:08:32.869515 waagent[2275]: pkts bytes target prot opt in out source destination Nov 5 15:08:32.869515 waagent[2275]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:08:32.869515 waagent[2275]: pkts bytes target prot opt in out source destination Nov 5 15:08:32.869515 waagent[2275]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:08:32.869515 waagent[2275]: pkts bytes target prot opt in out source destination Nov 5 15:08:32.869515 waagent[2275]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 5 15:08:32.869515 waagent[2275]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 5 15:08:32.869515 waagent[2275]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 5 15:08:32.871961 waagent[2275]: 2025-11-05T15:08:32.871911Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 5 15:08:32.871961 waagent[2275]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:08:32.871961 waagent[2275]: pkts bytes target prot opt in out source destination Nov 5 15:08:32.871961 waagent[2275]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:08:32.871961 waagent[2275]: pkts bytes target prot opt in out source destination Nov 5 15:08:32.871961 waagent[2275]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:08:32.871961 waagent[2275]: pkts bytes target prot opt in out source destination Nov 5 15:08:32.871961 waagent[2275]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 5 15:08:32.871961 waagent[2275]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 5 15:08:32.871961 waagent[2275]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 5 15:08:32.872159 waagent[2275]: 2025-11-05T15:08:32.872132Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 5 15:08:32.898855 waagent[2275]: 2025-11-05T15:08:32.898795Z INFO MonitorHandler ExtHandler Network interfaces: Nov 5 15:08:32.898855 waagent[2275]: Executing ['ip', '-a', '-o', 'link']: Nov 5 15:08:32.898855 waagent[2275]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 5 15:08:32.898855 waagent[2275]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6d:f9:ce brd ff:ff:ff:ff:ff:ff\ altname enx000d3a6df9ce Nov 5 15:08:32.898855 waagent[2275]: 3: enP10705s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6d:f9:ce brd ff:ff:ff:ff:ff:ff\ altname enP10705p0s2 Nov 5 15:08:32.898855 waagent[2275]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 5 15:08:32.898855 waagent[2275]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 5 15:08:32.898855 waagent[2275]: 2: eth0 inet 10.200.20.18/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 5 15:08:32.898855 waagent[2275]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 5 15:08:32.898855 waagent[2275]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 5 15:08:32.898855 waagent[2275]: 2: eth0 inet6 fe80::20d:3aff:fe6d:f9ce/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 5 15:08:37.919106 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Nov 5 15:08:37.920345 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:08:38.070171 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:08:38.073551 (kubelet)[2425]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 5 15:08:38.155551 kubelet[2425]: E1105 15:08:38.155488 2425 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 5 15:08:38.158277 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 5 15:08:38.158396 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 5 15:08:38.158926 systemd[1]: kubelet.service: Consumed 114ms CPU time, 107.1M memory peak. Nov 5 15:08:48.168557 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Nov 5 15:08:48.169893 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:08:48.267831 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:08:48.276244 (kubelet)[2440]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 5 15:08:48.412442 kubelet[2440]: E1105 15:08:48.412374 2440 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 5 15:08:48.414391 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 5 15:08:48.414501 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 5 15:08:48.415029 systemd[1]: kubelet.service: Consumed 107ms CPU time, 106M memory peak. Nov 5 15:08:50.395776 chronyd[2001]: Selected source PHC0 Nov 5 15:08:53.506503 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 5 15:08:53.507535 systemd[1]: Started sshd@0-10.200.20.18:22-10.200.16.10:51502.service - OpenSSH per-connection server daemon (10.200.16.10:51502). Nov 5 15:08:54.195277 sshd[2448]: Accepted publickey for core from 10.200.16.10 port 51502 ssh2: RSA SHA256:mGUAnMJC54q9ii6P+9FPV0TJpSBkn3Z8kncSeRZ8Yxo Nov 5 15:08:54.196077 sshd-session[2448]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:08:54.199890 systemd-logind[2020]: New session 3 of user core. Nov 5 15:08:54.207021 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 5 15:08:54.609071 systemd[1]: Started sshd@1-10.200.20.18:22-10.200.16.10:51516.service - OpenSSH per-connection server daemon (10.200.16.10:51516). Nov 5 15:08:55.071977 sshd[2454]: Accepted publickey for core from 10.200.16.10 port 51516 ssh2: RSA SHA256:mGUAnMJC54q9ii6P+9FPV0TJpSBkn3Z8kncSeRZ8Yxo Nov 5 15:08:55.073045 sshd-session[2454]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:08:55.076994 systemd-logind[2020]: New session 4 of user core. Nov 5 15:08:55.087006 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 5 15:08:55.400457 sshd[2457]: Connection closed by 10.200.16.10 port 51516 Nov 5 15:08:55.401008 sshd-session[2454]: pam_unix(sshd:session): session closed for user core Nov 5 15:08:55.405082 systemd-logind[2020]: Session 4 logged out. Waiting for processes to exit. Nov 5 15:08:55.405338 systemd[1]: sshd@1-10.200.20.18:22-10.200.16.10:51516.service: Deactivated successfully. Nov 5 15:08:55.406610 systemd[1]: session-4.scope: Deactivated successfully. Nov 5 15:08:55.408246 systemd-logind[2020]: Removed session 4. Nov 5 15:08:55.481517 systemd[1]: Started sshd@2-10.200.20.18:22-10.200.16.10:51528.service - OpenSSH per-connection server daemon (10.200.16.10:51528). Nov 5 15:08:55.935126 sshd[2463]: Accepted publickey for core from 10.200.16.10 port 51528 ssh2: RSA SHA256:mGUAnMJC54q9ii6P+9FPV0TJpSBkn3Z8kncSeRZ8Yxo Nov 5 15:08:55.936184 sshd-session[2463]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:08:55.939868 systemd-logind[2020]: New session 5 of user core. Nov 5 15:08:55.948016 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 5 15:08:56.271306 sshd[2466]: Connection closed by 10.200.16.10 port 51528 Nov 5 15:08:56.271139 sshd-session[2463]: pam_unix(sshd:session): session closed for user core Nov 5 15:08:56.274566 systemd[1]: sshd@2-10.200.20.18:22-10.200.16.10:51528.service: Deactivated successfully. Nov 5 15:08:56.275860 systemd[1]: session-5.scope: Deactivated successfully. Nov 5 15:08:56.276461 systemd-logind[2020]: Session 5 logged out. Waiting for processes to exit. Nov 5 15:08:56.277435 systemd-logind[2020]: Removed session 5. Nov 5 15:08:56.351523 systemd[1]: Started sshd@3-10.200.20.18:22-10.200.16.10:51532.service - OpenSSH per-connection server daemon (10.200.16.10:51532). Nov 5 15:08:56.779308 sshd[2472]: Accepted publickey for core from 10.200.16.10 port 51532 ssh2: RSA SHA256:mGUAnMJC54q9ii6P+9FPV0TJpSBkn3Z8kncSeRZ8Yxo Nov 5 15:08:56.780336 sshd-session[2472]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:08:56.784149 systemd-logind[2020]: New session 6 of user core. Nov 5 15:08:56.792035 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 5 15:08:57.090988 sshd[2475]: Connection closed by 10.200.16.10 port 51532 Nov 5 15:08:57.091613 sshd-session[2472]: pam_unix(sshd:session): session closed for user core Nov 5 15:08:57.095380 systemd[1]: sshd@3-10.200.20.18:22-10.200.16.10:51532.service: Deactivated successfully. Nov 5 15:08:57.096958 systemd[1]: session-6.scope: Deactivated successfully. Nov 5 15:08:57.098474 systemd-logind[2020]: Session 6 logged out. Waiting for processes to exit. Nov 5 15:08:57.099480 systemd-logind[2020]: Removed session 6. Nov 5 15:08:57.173115 systemd[1]: Started sshd@4-10.200.20.18:22-10.200.16.10:51538.service - OpenSSH per-connection server daemon (10.200.16.10:51538). Nov 5 15:08:57.632917 sshd[2481]: Accepted publickey for core from 10.200.16.10 port 51538 ssh2: RSA SHA256:mGUAnMJC54q9ii6P+9FPV0TJpSBkn3Z8kncSeRZ8Yxo Nov 5 15:08:57.634021 sshd-session[2481]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:08:57.637691 systemd-logind[2020]: New session 7 of user core. Nov 5 15:08:57.646036 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 5 15:08:58.065540 sudo[2485]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 5 15:08:58.066144 sudo[2485]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:08:58.418401 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 3. Nov 5 15:08:58.420131 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:08:58.445278 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 5 15:08:58.445497 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 5 15:08:58.446966 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:08:58.449045 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:08:58.471672 systemd[1]: Reload requested from client PID 2521 ('systemctl') (unit session-7.scope)... Nov 5 15:08:58.471685 systemd[1]: Reloading... Nov 5 15:08:58.571914 zram_generator::config[2577]: No configuration found. Nov 5 15:08:58.714296 systemd[1]: Reloading finished in 242 ms. Nov 5 15:08:58.766353 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:08:58.767778 systemd[1]: kubelet.service: Deactivated successfully. Nov 5 15:08:58.767975 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:08:58.768029 systemd[1]: kubelet.service: Consumed 78ms CPU time, 95.1M memory peak. Nov 5 15:08:58.771097 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:08:59.504793 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:08:59.509133 (kubelet)[2637]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 5 15:08:59.536258 kubelet[2637]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 5 15:08:59.536258 kubelet[2637]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 5 15:08:59.536588 kubelet[2637]: I1105 15:08:59.536311 2637 server.go:213] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 5 15:08:59.945099 kubelet[2637]: I1105 15:08:59.945041 2637 server.go:529] "Kubelet version" kubeletVersion="v1.34.1" Nov 5 15:08:59.945099 kubelet[2637]: I1105 15:08:59.945068 2637 server.go:531] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 5 15:08:59.945099 kubelet[2637]: I1105 15:08:59.945094 2637 watchdog_linux.go:95] "Systemd watchdog is not enabled" Nov 5 15:08:59.945099 kubelet[2637]: I1105 15:08:59.945099 2637 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 5 15:08:59.945299 kubelet[2637]: I1105 15:08:59.945279 2637 server.go:956] "Client rotation is on, will bootstrap in background" Nov 5 15:08:59.948040 kubelet[2637]: I1105 15:08:59.948010 2637 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 5 15:08:59.952905 kubelet[2637]: I1105 15:08:59.952054 2637 server.go:1423] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Nov 5 15:08:59.954489 kubelet[2637]: I1105 15:08:59.954466 2637 server.go:781] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Nov 5 15:08:59.954770 kubelet[2637]: I1105 15:08:59.954746 2637 container_manager_linux.go:270] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 5 15:08:59.954960 kubelet[2637]: I1105 15:08:59.954825 2637 container_manager_linux.go:275] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.20.18","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 5 15:08:59.955103 kubelet[2637]: I1105 15:08:59.955088 2637 topology_manager.go:138] "Creating topology manager with none policy" Nov 5 15:08:59.955147 kubelet[2637]: I1105 15:08:59.955141 2637 container_manager_linux.go:306] "Creating device plugin manager" Nov 5 15:08:59.955287 kubelet[2637]: I1105 15:08:59.955275 2637 container_manager_linux.go:315] "Creating Dynamic Resource Allocation (DRA) manager" Nov 5 15:08:59.961014 kubelet[2637]: I1105 15:08:59.960989 2637 state_mem.go:36] "Initialized new in-memory state store" Nov 5 15:08:59.962237 kubelet[2637]: I1105 15:08:59.962220 2637 kubelet.go:475] "Attempting to sync node with API server" Nov 5 15:08:59.962281 kubelet[2637]: I1105 15:08:59.962246 2637 kubelet.go:376] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 5 15:08:59.962883 kubelet[2637]: I1105 15:08:59.962784 2637 kubelet.go:387] "Adding apiserver pod source" Nov 5 15:08:59.962883 kubelet[2637]: I1105 15:08:59.962808 2637 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 5 15:08:59.962883 kubelet[2637]: E1105 15:08:59.962817 2637 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:08:59.962883 kubelet[2637]: E1105 15:08:59.962850 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:08:59.964900 kubelet[2637]: I1105 15:08:59.963427 2637 kuberuntime_manager.go:291] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Nov 5 15:08:59.964900 kubelet[2637]: I1105 15:08:59.963800 2637 kubelet.go:940] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Nov 5 15:08:59.964900 kubelet[2637]: I1105 15:08:59.963820 2637 kubelet.go:964] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Nov 5 15:08:59.964900 kubelet[2637]: W1105 15:08:59.963848 2637 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 5 15:08:59.965508 kubelet[2637]: I1105 15:08:59.965495 2637 server.go:1262] "Started kubelet" Nov 5 15:08:59.966332 kubelet[2637]: I1105 15:08:59.966303 2637 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Nov 5 15:08:59.966953 kubelet[2637]: I1105 15:08:59.966938 2637 server.go:310] "Adding debug handlers to kubelet server" Nov 5 15:08:59.968693 kubelet[2637]: I1105 15:08:59.967996 2637 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 5 15:08:59.968810 kubelet[2637]: I1105 15:08:59.968793 2637 server_v1.go:49] "podresources" method="list" useActivePods=true Nov 5 15:08:59.969134 kubelet[2637]: I1105 15:08:59.969114 2637 server.go:249] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 5 15:08:59.970558 kubelet[2637]: I1105 15:08:59.970529 2637 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 5 15:08:59.978894 kubelet[2637]: I1105 15:08:59.977678 2637 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 5 15:08:59.979557 kubelet[2637]: I1105 15:08:59.979542 2637 volume_manager.go:313] "Starting Kubelet Volume Manager" Nov 5 15:08:59.979871 kubelet[2637]: E1105 15:08:59.979853 2637 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.200.20.18\" not found" Nov 5 15:08:59.980445 kubelet[2637]: I1105 15:08:59.980427 2637 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Nov 5 15:08:59.980705 kubelet[2637]: I1105 15:08:59.980692 2637 reconciler.go:29] "Reconciler: start to sync state" Nov 5 15:08:59.980986 kubelet[2637]: I1105 15:08:59.980965 2637 factory.go:223] Registration of the systemd container factory successfully Nov 5 15:08:59.981131 kubelet[2637]: I1105 15:08:59.981113 2637 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 5 15:08:59.984730 kubelet[2637]: E1105 15:08:59.984673 2637 kubelet.go:1615] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 5 15:08:59.984943 kubelet[2637]: I1105 15:08:59.984891 2637 factory.go:223] Registration of the containerd container factory successfully Nov 5 15:09:00.010268 kubelet[2637]: E1105 15:09:00.010228 2637 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.200.20.18\" not found" node="10.200.20.18" Nov 5 15:09:00.011228 kubelet[2637]: I1105 15:09:00.011211 2637 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 5 15:09:00.011428 kubelet[2637]: I1105 15:09:00.011417 2637 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 5 15:09:00.011496 kubelet[2637]: I1105 15:09:00.011488 2637 state_mem.go:36] "Initialized new in-memory state store" Nov 5 15:09:00.016990 kubelet[2637]: I1105 15:09:00.016963 2637 policy_none.go:49] "None policy: Start" Nov 5 15:09:00.016990 kubelet[2637]: I1105 15:09:00.016990 2637 memory_manager.go:187] "Starting memorymanager" policy="None" Nov 5 15:09:00.017082 kubelet[2637]: I1105 15:09:00.017001 2637 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Nov 5 15:09:00.018418 sudo[2485]: pam_unix(sudo:session): session closed for user root Nov 5 15:09:00.022544 kubelet[2637]: I1105 15:09:00.022521 2637 policy_none.go:47] "Start" Nov 5 15:09:00.026534 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 5 15:09:00.034297 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 5 15:09:00.037164 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 5 15:09:00.043640 kubelet[2637]: E1105 15:09:00.043612 2637 manager.go:513] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Nov 5 15:09:00.043818 kubelet[2637]: I1105 15:09:00.043800 2637 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 5 15:09:00.043856 kubelet[2637]: I1105 15:09:00.043815 2637 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 5 15:09:00.044447 kubelet[2637]: I1105 15:09:00.044397 2637 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 5 15:09:00.045498 kubelet[2637]: E1105 15:09:00.045480 2637 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 5 15:09:00.045560 kubelet[2637]: E1105 15:09:00.045518 2637 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.20.18\" not found" Nov 5 15:09:00.081800 kubelet[2637]: I1105 15:09:00.081689 2637 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Nov 5 15:09:00.082997 kubelet[2637]: I1105 15:09:00.082981 2637 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Nov 5 15:09:00.083152 kubelet[2637]: I1105 15:09:00.083083 2637 status_manager.go:244] "Starting to sync pod status with apiserver" Nov 5 15:09:00.083152 kubelet[2637]: I1105 15:09:00.083122 2637 kubelet.go:2427] "Starting kubelet main sync loop" Nov 5 15:09:00.083249 kubelet[2637]: E1105 15:09:00.083238 2637 kubelet.go:2451] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Nov 5 15:09:00.084665 sshd[2484]: Connection closed by 10.200.16.10 port 51538 Nov 5 15:09:00.085517 sshd-session[2481]: pam_unix(sshd:session): session closed for user core Nov 5 15:09:00.090749 systemd[1]: sshd@4-10.200.20.18:22-10.200.16.10:51538.service: Deactivated successfully. Nov 5 15:09:00.092561 systemd[1]: session-7.scope: Deactivated successfully. Nov 5 15:09:00.092992 systemd[1]: session-7.scope: Consumed 270ms CPU time, 73.2M memory peak. Nov 5 15:09:00.094086 systemd-logind[2020]: Session 7 logged out. Waiting for processes to exit. Nov 5 15:09:00.095298 systemd-logind[2020]: Removed session 7. Nov 5 15:09:00.145252 kubelet[2637]: I1105 15:09:00.145067 2637 kubelet_node_status.go:75] "Attempting to register node" node="10.200.20.18" Nov 5 15:09:00.151199 kubelet[2637]: I1105 15:09:00.151045 2637 kubelet_node_status.go:78] "Successfully registered node" node="10.200.20.18" Nov 5 15:09:00.151199 kubelet[2637]: E1105 15:09:00.151073 2637 kubelet_node_status.go:486] "Error updating node status, will retry" err="error getting node \"10.200.20.18\": node \"10.200.20.18\" not found" Nov 5 15:09:00.169099 kubelet[2637]: I1105 15:09:00.169078 2637 kuberuntime_manager.go:1828] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 5 15:09:00.169403 containerd[2056]: time="2025-11-05T15:09:00.169371356Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 5 15:09:00.170143 kubelet[2637]: I1105 15:09:00.169946 2637 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 5 15:09:00.947742 kubelet[2637]: I1105 15:09:00.947216 2637 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 5 15:09:00.947742 kubelet[2637]: I1105 15:09:00.947410 2637 reflector.go:568] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 5 15:09:00.947742 kubelet[2637]: I1105 15:09:00.947440 2637 reflector.go:568] "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" Nov 5 15:09:00.947742 kubelet[2637]: I1105 15:09:00.947471 2637 reflector.go:568] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 5 15:09:00.963172 kubelet[2637]: I1105 15:09:00.963134 2637 apiserver.go:52] "Watching apiserver" Nov 5 15:09:00.963369 kubelet[2637]: E1105 15:09:00.963140 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:00.976246 systemd[1]: Created slice kubepods-burstable-pod9754ad9d_8231_41fb_ba15_d5a032551e3a.slice - libcontainer container kubepods-burstable-pod9754ad9d_8231_41fb_ba15_d5a032551e3a.slice. Nov 5 15:09:00.981907 kubelet[2637]: I1105 15:09:00.981170 2637 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Nov 5 15:09:00.986750 kubelet[2637]: I1105 15:09:00.986719 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/50ec5b05-ea78-46f4-a98d-c9368b140560-kube-proxy\") pod \"kube-proxy-tvb8l\" (UID: \"50ec5b05-ea78-46f4-a98d-c9368b140560\") " pod="kube-system/kube-proxy-tvb8l" Nov 5 15:09:00.986750 kubelet[2637]: I1105 15:09:00.986748 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/9754ad9d-8231-41fb-ba15-d5a032551e3a-cni\") pod \"kube-flannel-ds-7q7z8\" (UID: \"9754ad9d-8231-41fb-ba15-d5a032551e3a\") " pod="kube-flannel/kube-flannel-ds-7q7z8" Nov 5 15:09:00.986899 kubelet[2637]: I1105 15:09:00.986761 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/9754ad9d-8231-41fb-ba15-d5a032551e3a-flannel-cfg\") pod \"kube-flannel-ds-7q7z8\" (UID: \"9754ad9d-8231-41fb-ba15-d5a032551e3a\") " pod="kube-flannel/kube-flannel-ds-7q7z8" Nov 5 15:09:00.986899 kubelet[2637]: I1105 15:09:00.986771 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wbgws\" (UniqueName: \"kubernetes.io/projected/9754ad9d-8231-41fb-ba15-d5a032551e3a-kube-api-access-wbgws\") pod \"kube-flannel-ds-7q7z8\" (UID: \"9754ad9d-8231-41fb-ba15-d5a032551e3a\") " pod="kube-flannel/kube-flannel-ds-7q7z8" Nov 5 15:09:00.986899 kubelet[2637]: I1105 15:09:00.986781 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/50ec5b05-ea78-46f4-a98d-c9368b140560-xtables-lock\") pod \"kube-proxy-tvb8l\" (UID: \"50ec5b05-ea78-46f4-a98d-c9368b140560\") " pod="kube-system/kube-proxy-tvb8l" Nov 5 15:09:00.986899 kubelet[2637]: I1105 15:09:00.986789 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/50ec5b05-ea78-46f4-a98d-c9368b140560-lib-modules\") pod \"kube-proxy-tvb8l\" (UID: \"50ec5b05-ea78-46f4-a98d-c9368b140560\") " pod="kube-system/kube-proxy-tvb8l" Nov 5 15:09:00.986899 kubelet[2637]: I1105 15:09:00.986797 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-z2mz5\" (UniqueName: \"kubernetes.io/projected/50ec5b05-ea78-46f4-a98d-c9368b140560-kube-api-access-z2mz5\") pod \"kube-proxy-tvb8l\" (UID: \"50ec5b05-ea78-46f4-a98d-c9368b140560\") " pod="kube-system/kube-proxy-tvb8l" Nov 5 15:09:00.986981 kubelet[2637]: I1105 15:09:00.986808 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/9754ad9d-8231-41fb-ba15-d5a032551e3a-run\") pod \"kube-flannel-ds-7q7z8\" (UID: \"9754ad9d-8231-41fb-ba15-d5a032551e3a\") " pod="kube-flannel/kube-flannel-ds-7q7z8" Nov 5 15:09:00.986981 kubelet[2637]: I1105 15:09:00.986817 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/9754ad9d-8231-41fb-ba15-d5a032551e3a-cni-plugin\") pod \"kube-flannel-ds-7q7z8\" (UID: \"9754ad9d-8231-41fb-ba15-d5a032551e3a\") " pod="kube-flannel/kube-flannel-ds-7q7z8" Nov 5 15:09:00.986981 kubelet[2637]: I1105 15:09:00.986824 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/9754ad9d-8231-41fb-ba15-d5a032551e3a-xtables-lock\") pod \"kube-flannel-ds-7q7z8\" (UID: \"9754ad9d-8231-41fb-ba15-d5a032551e3a\") " pod="kube-flannel/kube-flannel-ds-7q7z8" Nov 5 15:09:00.996501 systemd[1]: Created slice kubepods-besteffort-pod50ec5b05_ea78_46f4_a98d_c9368b140560.slice - libcontainer container kubepods-besteffort-pod50ec5b05_ea78_46f4_a98d_c9368b140560.slice. Nov 5 15:09:01.299972 containerd[2056]: time="2025-11-05T15:09:01.299571032Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7q7z8,Uid:9754ad9d-8231-41fb-ba15-d5a032551e3a,Namespace:kube-flannel,Attempt:0,}" Nov 5 15:09:01.311800 containerd[2056]: time="2025-11-05T15:09:01.311761849Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tvb8l,Uid:50ec5b05-ea78-46f4-a98d-c9368b140560,Namespace:kube-system,Attempt:0,}" Nov 5 15:09:01.963522 kubelet[2637]: E1105 15:09:01.963471 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:02.098588 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1024547855.mount: Deactivated successfully. Nov 5 15:09:02.130475 containerd[2056]: time="2025-11-05T15:09:02.130005111Z" 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\"}" Nov 5 15:09:02.135432 containerd[2056]: time="2025-11-05T15:09:02.135401807Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Nov 5 15:09:02.138321 containerd[2056]: time="2025-11-05T15:09:02.138286244Z" 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\"}" Nov 5 15:09:02.141755 containerd[2056]: time="2025-11-05T15:09:02.141316486Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 15:09:02.143779 containerd[2056]: time="2025-11-05T15:09:02.143751006Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Nov 5 15:09:02.147324 containerd[2056]: time="2025-11-05T15:09:02.147292831Z" 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\"}" Nov 5 15:09:02.147674 containerd[2056]: time="2025-11-05T15:09:02.147651490Z" 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 831.944812ms" Nov 5 15:09:02.148411 containerd[2056]: time="2025-11-05T15:09:02.148377655Z" 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 815.842031ms" Nov 5 15:09:02.223673 containerd[2056]: time="2025-11-05T15:09:02.223506216Z" level=info msg="connecting to shim 6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531" address="unix:///run/containerd/s/b9104678b4fd112dbe79bdd25fd112e90441abf063beedd7fce3ecddacb086da" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:09:02.231086 containerd[2056]: time="2025-11-05T15:09:02.231051663Z" level=info msg="connecting to shim c2452e12f69906cdf24154bb2b37843a0cc0905b8baffba976294c70f330a433" address="unix:///run/containerd/s/0c60df21afdac4e9a989040a2f8ab9645f48c89de9684acd4802c0fcf870d3ac" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:09:02.247110 systemd[1]: Started cri-containerd-6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531.scope - libcontainer container 6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531. Nov 5 15:09:02.251016 systemd[1]: Started cri-containerd-c2452e12f69906cdf24154bb2b37843a0cc0905b8baffba976294c70f330a433.scope - libcontainer container c2452e12f69906cdf24154bb2b37843a0cc0905b8baffba976294c70f330a433. Nov 5 15:09:02.283079 containerd[2056]: time="2025-11-05T15:09:02.282992137Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tvb8l,Uid:50ec5b05-ea78-46f4-a98d-c9368b140560,Namespace:kube-system,Attempt:0,} returns sandbox id \"c2452e12f69906cdf24154bb2b37843a0cc0905b8baffba976294c70f330a433\"" Nov 5 15:09:02.285054 containerd[2056]: time="2025-11-05T15:09:02.285032094Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.1\"" Nov 5 15:09:02.285641 containerd[2056]: time="2025-11-05T15:09:02.285506612Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7q7z8,Uid:9754ad9d-8231-41fb-ba15-d5a032551e3a,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531\"" Nov 5 15:09:02.964090 kubelet[2637]: E1105 15:09:02.964035 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:03.158902 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Nov 5 15:09:03.202672 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2832100544.mount: Deactivated successfully. Nov 5 15:09:03.406712 containerd[2056]: time="2025-11-05T15:09:03.406576994Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.34.1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:03.409441 containerd[2056]: time="2025-11-05T15:09:03.409395701Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.34.1: active requests=0, bytes read=22789028" Nov 5 15:09:03.412451 containerd[2056]: time="2025-11-05T15:09:03.412403287Z" level=info msg="ImageCreate event name:\"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:03.416072 containerd[2056]: time="2025-11-05T15:09:03.416032994Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:913cc83ca0b5588a81d86ce8eedeb3ed1e9c1326e81852a1ea4f622b74ff749a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:03.416535 containerd[2056]: time="2025-11-05T15:09:03.416483751Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.34.1\" with image id \"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\", repo tag \"registry.k8s.io/kube-proxy:v1.34.1\", repo digest \"registry.k8s.io/kube-proxy@sha256:913cc83ca0b5588a81d86ce8eedeb3ed1e9c1326e81852a1ea4f622b74ff749a\", size \"22788047\" in 1.131243451s" Nov 5 15:09:03.416535 containerd[2056]: time="2025-11-05T15:09:03.416510064Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.1\" returns image reference \"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\"" Nov 5 15:09:03.418105 containerd[2056]: time="2025-11-05T15:09:03.417868712Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Nov 5 15:09:03.426550 containerd[2056]: time="2025-11-05T15:09:03.426504704Z" level=info msg="CreateContainer within sandbox \"c2452e12f69906cdf24154bb2b37843a0cc0905b8baffba976294c70f330a433\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 5 15:09:03.447050 containerd[2056]: time="2025-11-05T15:09:03.447007359Z" level=info msg="Container 8f7f343591af4eef3782803c020c28152dd40bca1413133c35fc76ded675c701: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:09:03.447779 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount469450175.mount: Deactivated successfully. Nov 5 15:09:03.471835 containerd[2056]: time="2025-11-05T15:09:03.471797469Z" level=info msg="CreateContainer within sandbox \"c2452e12f69906cdf24154bb2b37843a0cc0905b8baffba976294c70f330a433\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"8f7f343591af4eef3782803c020c28152dd40bca1413133c35fc76ded675c701\"" Nov 5 15:09:03.472809 containerd[2056]: time="2025-11-05T15:09:03.472785290Z" level=info msg="StartContainer for \"8f7f343591af4eef3782803c020c28152dd40bca1413133c35fc76ded675c701\"" Nov 5 15:09:03.474058 containerd[2056]: time="2025-11-05T15:09:03.474037384Z" level=info msg="connecting to shim 8f7f343591af4eef3782803c020c28152dd40bca1413133c35fc76ded675c701" address="unix:///run/containerd/s/0c60df21afdac4e9a989040a2f8ab9645f48c89de9684acd4802c0fcf870d3ac" protocol=ttrpc version=3 Nov 5 15:09:03.492017 systemd[1]: Started cri-containerd-8f7f343591af4eef3782803c020c28152dd40bca1413133c35fc76ded675c701.scope - libcontainer container 8f7f343591af4eef3782803c020c28152dd40bca1413133c35fc76ded675c701. Nov 5 15:09:03.526169 containerd[2056]: time="2025-11-05T15:09:03.526131742Z" level=info msg="StartContainer for \"8f7f343591af4eef3782803c020c28152dd40bca1413133c35fc76ded675c701\" returns successfully" Nov 5 15:09:03.964564 kubelet[2637]: E1105 15:09:03.964526 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:04.109409 kubelet[2637]: I1105 15:09:04.109345 2637 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-tvb8l" podStartSLOduration=2.976314332 podStartE2EDuration="4.109332588s" podCreationTimestamp="2025-11-05 15:09:00 +0000 UTC" firstStartedPulling="2025-11-05 15:09:02.284549975 +0000 UTC m=+2.773074855" lastFinishedPulling="2025-11-05 15:09:03.417568239 +0000 UTC m=+3.906093111" observedRunningTime="2025-11-05 15:09:04.109316644 +0000 UTC m=+4.597841516" watchObservedRunningTime="2025-11-05 15:09:04.109332588 +0000 UTC m=+4.597857460" Nov 5 15:09:04.965633 kubelet[2637]: E1105 15:09:04.965591 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:05.116907 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2678537209.mount: Deactivated successfully. Nov 5 15:09:05.180526 containerd[2056]: time="2025-11-05T15:09:05.179863106Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:05.182961 containerd[2056]: time="2025-11-05T15:09:05.182931325Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Nov 5 15:09:05.186062 containerd[2056]: time="2025-11-05T15:09:05.186032225Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:05.191641 containerd[2056]: time="2025-11-05T15:09:05.191614902Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:05.192843 containerd[2056]: time="2025-11-05T15:09:05.192812873Z" 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.774757691s" Nov 5 15:09:05.192958 containerd[2056]: time="2025-11-05T15:09:05.192943797Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Nov 5 15:09:05.199763 containerd[2056]: time="2025-11-05T15:09:05.199740023Z" level=info msg="CreateContainer within sandbox \"6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 5 15:09:05.222541 containerd[2056]: time="2025-11-05T15:09:05.221282797Z" level=info msg="Container cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:09:05.240779 containerd[2056]: time="2025-11-05T15:09:05.240738853Z" level=info msg="CreateContainer within sandbox \"6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd\"" Nov 5 15:09:05.241652 containerd[2056]: time="2025-11-05T15:09:05.241614671Z" level=info msg="StartContainer for \"cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd\"" Nov 5 15:09:05.242506 containerd[2056]: time="2025-11-05T15:09:05.242482200Z" level=info msg="connecting to shim cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd" address="unix:///run/containerd/s/b9104678b4fd112dbe79bdd25fd112e90441abf063beedd7fce3ecddacb086da" protocol=ttrpc version=3 Nov 5 15:09:05.264238 systemd[1]: Started cri-containerd-cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd.scope - libcontainer container cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd. Nov 5 15:09:05.284473 systemd[1]: cri-containerd-cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd.scope: Deactivated successfully. Nov 5 15:09:05.288496 containerd[2056]: time="2025-11-05T15:09:05.288456026Z" level=info msg="TaskExit event in podsandbox handler container_id:\"cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd\" id:\"cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd\" pid:2973 exited_at:{seconds:1762355345 nanos:287778838}" Nov 5 15:09:05.288809 containerd[2056]: time="2025-11-05T15:09:05.288668032Z" level=info msg="received exit event container_id:\"cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd\" id:\"cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd\" pid:2973 exited_at:{seconds:1762355345 nanos:287778838}" Nov 5 15:09:05.290098 containerd[2056]: time="2025-11-05T15:09:05.290068650Z" level=info msg="StartContainer for \"cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd\" returns successfully" Nov 5 15:09:05.966600 kubelet[2637]: E1105 15:09:05.966560 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:06.059160 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-cddb2ba47d432b90ae18fa48bf0e3030970e81561c662c0373d7b65cde6278dd-rootfs.mount: Deactivated successfully. Nov 5 15:09:06.102647 containerd[2056]: time="2025-11-05T15:09:06.102619296Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Nov 5 15:09:06.967664 kubelet[2637]: E1105 15:09:06.967619 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:07.968424 kubelet[2637]: E1105 15:09:07.968377 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:08.525649 containerd[2056]: time="2025-11-05T15:09:08.525373972Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:08.529846 containerd[2056]: time="2025-11-05T15:09:08.529809903Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Nov 5 15:09:08.532849 containerd[2056]: time="2025-11-05T15:09:08.532822062Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:08.538101 containerd[2056]: time="2025-11-05T15:09:08.538037786Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:08.539900 containerd[2056]: time="2025-11-05T15:09:08.538667675Z" 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.435965345s" Nov 5 15:09:08.539900 containerd[2056]: time="2025-11-05T15:09:08.538700213Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Nov 5 15:09:08.548990 containerd[2056]: time="2025-11-05T15:09:08.548959940Z" level=info msg="CreateContainer within sandbox \"6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 5 15:09:08.567661 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1182593070.mount: Deactivated successfully. Nov 5 15:09:08.568680 containerd[2056]: time="2025-11-05T15:09:08.568647421Z" level=info msg="Container 6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:09:08.585890 containerd[2056]: time="2025-11-05T15:09:08.585843859Z" level=info msg="CreateContainer within sandbox \"6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6\"" Nov 5 15:09:08.586675 containerd[2056]: time="2025-11-05T15:09:08.586645157Z" level=info msg="StartContainer for \"6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6\"" Nov 5 15:09:08.587488 containerd[2056]: time="2025-11-05T15:09:08.587465249Z" level=info msg="connecting to shim 6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6" address="unix:///run/containerd/s/b9104678b4fd112dbe79bdd25fd112e90441abf063beedd7fce3ecddacb086da" protocol=ttrpc version=3 Nov 5 15:09:08.607036 systemd[1]: Started cri-containerd-6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6.scope - libcontainer container 6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6. Nov 5 15:09:08.627273 systemd[1]: cri-containerd-6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6.scope: Deactivated successfully. Nov 5 15:09:08.629606 containerd[2056]: time="2025-11-05T15:09:08.629565444Z" level=info msg="TaskExit event in podsandbox handler container_id:\"6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6\" id:\"6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6\" pid:3049 exited_at:{seconds:1762355348 nanos:629249667}" Nov 5 15:09:08.632395 containerd[2056]: time="2025-11-05T15:09:08.632355847Z" level=info msg="received exit event container_id:\"6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6\" id:\"6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6\" pid:3049 exited_at:{seconds:1762355348 nanos:629249667}" Nov 5 15:09:08.633368 containerd[2056]: time="2025-11-05T15:09:08.633333027Z" level=info msg="StartContainer for \"6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6\" returns successfully" Nov 5 15:09:08.647937 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6e72c3379f6f63d416f492470491be9e6c452ba903d6081c41b706650cdf61e6-rootfs.mount: Deactivated successfully. Nov 5 15:09:08.726939 kubelet[2637]: I1105 15:09:08.726899 2637 kubelet_node_status.go:439] "Fast updating node status as it just became ready" Nov 5 15:09:08.969140 kubelet[2637]: E1105 15:09:08.969100 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:09.970042 kubelet[2637]: E1105 15:09:09.969993 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:10.119902 containerd[2056]: time="2025-11-05T15:09:10.119481221Z" level=info msg="CreateContainer within sandbox \"6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 5 15:09:10.141128 containerd[2056]: time="2025-11-05T15:09:10.140739548Z" level=info msg="Container 57c2e2cbb14c904cce7a0c01ec3752c33420b4c32c02ab7508e6a26d22b244bf: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:09:10.142528 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1133162412.mount: Deactivated successfully. Nov 5 15:09:10.157740 containerd[2056]: time="2025-11-05T15:09:10.157684978Z" level=info msg="CreateContainer within sandbox \"6b588010ccdf7e9eb0e23036138b13f96f82c9c63f38033716252a39c1353531\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"57c2e2cbb14c904cce7a0c01ec3752c33420b4c32c02ab7508e6a26d22b244bf\"" Nov 5 15:09:10.158763 containerd[2056]: time="2025-11-05T15:09:10.158569510Z" level=info msg="StartContainer for \"57c2e2cbb14c904cce7a0c01ec3752c33420b4c32c02ab7508e6a26d22b244bf\"" Nov 5 15:09:10.159456 containerd[2056]: time="2025-11-05T15:09:10.159419761Z" level=info msg="connecting to shim 57c2e2cbb14c904cce7a0c01ec3752c33420b4c32c02ab7508e6a26d22b244bf" address="unix:///run/containerd/s/b9104678b4fd112dbe79bdd25fd112e90441abf063beedd7fce3ecddacb086da" protocol=ttrpc version=3 Nov 5 15:09:10.177994 systemd[1]: Started cri-containerd-57c2e2cbb14c904cce7a0c01ec3752c33420b4c32c02ab7508e6a26d22b244bf.scope - libcontainer container 57c2e2cbb14c904cce7a0c01ec3752c33420b4c32c02ab7508e6a26d22b244bf. Nov 5 15:09:10.203144 containerd[2056]: time="2025-11-05T15:09:10.203115654Z" level=info msg="StartContainer for \"57c2e2cbb14c904cce7a0c01ec3752c33420b4c32c02ab7508e6a26d22b244bf\" returns successfully" Nov 5 15:09:10.970550 kubelet[2637]: E1105 15:09:10.970499 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:11.373974 systemd-networkd[1641]: flannel.1: Link UP Nov 5 15:09:11.373983 systemd-networkd[1641]: flannel.1: Gained carrier Nov 5 15:09:11.971723 kubelet[2637]: E1105 15:09:11.971619 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:12.608522 update_engine[2021]: I20251105 15:09:12.608389 2021 update_attempter.cc:509] Updating boot flags... Nov 5 15:09:12.965137 systemd-networkd[1641]: flannel.1: Gained IPv6LL Nov 5 15:09:12.972765 kubelet[2637]: E1105 15:09:12.972715 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:13.942753 kubelet[2637]: I1105 15:09:13.942626 2637 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7q7z8" podStartSLOduration=7.68760605 podStartE2EDuration="13.94260706s" podCreationTimestamp="2025-11-05 15:09:00 +0000 UTC" firstStartedPulling="2025-11-05 15:09:02.286573267 +0000 UTC m=+2.775098139" lastFinishedPulling="2025-11-05 15:09:08.541574277 +0000 UTC m=+9.030099149" observedRunningTime="2025-11-05 15:09:11.131947115 +0000 UTC m=+11.620471995" watchObservedRunningTime="2025-11-05 15:09:13.94260706 +0000 UTC m=+14.431131932" Nov 5 15:09:13.956194 systemd[1]: Created slice kubepods-besteffort-pode592786c_52cd_43ac_a001_723a60efbe61.slice - libcontainer container kubepods-besteffort-pode592786c_52cd_43ac_a001_723a60efbe61.slice. Nov 5 15:09:13.964049 kubelet[2637]: I1105 15:09:13.964023 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6b242\" (UniqueName: \"kubernetes.io/projected/e592786c-52cd-43ac-a001-723a60efbe61-kube-api-access-6b242\") pod \"nginx-deployment-bb8f74bfb-h44w4\" (UID: \"e592786c-52cd-43ac-a001-723a60efbe61\") " pod="default/nginx-deployment-bb8f74bfb-h44w4" Nov 5 15:09:13.973849 kubelet[2637]: E1105 15:09:13.973827 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:14.264806 containerd[2056]: time="2025-11-05T15:09:14.264515340Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-h44w4,Uid:e592786c-52cd-43ac-a001-723a60efbe61,Namespace:default,Attempt:0,}" Nov 5 15:09:14.288768 systemd-networkd[1641]: cni0: Link UP Nov 5 15:09:14.288776 systemd-networkd[1641]: cni0: Gained carrier Nov 5 15:09:14.290301 systemd-networkd[1641]: cni0: Lost carrier Nov 5 15:09:14.327217 systemd-networkd[1641]: veth5ebf1555: Link UP Nov 5 15:09:14.334574 kernel: cni0: port 1(veth5ebf1555) entered blocking state Nov 5 15:09:14.334654 kernel: cni0: port 1(veth5ebf1555) entered disabled state Nov 5 15:09:14.338121 kernel: veth5ebf1555: entered allmulticast mode Nov 5 15:09:14.341007 kernel: veth5ebf1555: entered promiscuous mode Nov 5 15:09:14.353713 kernel: cni0: port 1(veth5ebf1555) entered blocking state Nov 5 15:09:14.353846 kernel: cni0: port 1(veth5ebf1555) entered forwarding state Nov 5 15:09:14.353952 systemd-networkd[1641]: veth5ebf1555: Gained carrier Nov 5 15:09:14.354132 systemd-networkd[1641]: cni0: Gained carrier Nov 5 15:09:14.356456 containerd[2056]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000084950), "name":"cbr0", "type":"bridge"} Nov 5 15:09:14.356456 containerd[2056]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:09:14.402025 containerd[2056]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-05T15:09:14.401918131Z" level=info msg="connecting to shim eec564f8ac8dac5fd5810d75f1ef398365fb6b0a75e8fc2ad8d077f9335297eb" address="unix:///run/containerd/s/a1c47c2e774928f0b4ace5049fbe16eac546761bd13c06892c566e40f482d1bb" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:09:14.428000 systemd[1]: Started cri-containerd-eec564f8ac8dac5fd5810d75f1ef398365fb6b0a75e8fc2ad8d077f9335297eb.scope - libcontainer container eec564f8ac8dac5fd5810d75f1ef398365fb6b0a75e8fc2ad8d077f9335297eb. Nov 5 15:09:14.454618 containerd[2056]: time="2025-11-05T15:09:14.454577073Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-h44w4,Uid:e592786c-52cd-43ac-a001-723a60efbe61,Namespace:default,Attempt:0,} returns sandbox id \"eec564f8ac8dac5fd5810d75f1ef398365fb6b0a75e8fc2ad8d077f9335297eb\"" Nov 5 15:09:14.456224 containerd[2056]: time="2025-11-05T15:09:14.455975102Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 15:09:14.974288 kubelet[2637]: E1105 15:09:14.974236 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:15.589043 systemd-networkd[1641]: cni0: Gained IPv6LL Nov 5 15:09:15.974376 kubelet[2637]: E1105 15:09:15.974333 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:16.293032 systemd-networkd[1641]: veth5ebf1555: Gained IPv6LL Nov 5 15:09:16.605938 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3588568956.mount: Deactivated successfully. Nov 5 15:09:16.974800 kubelet[2637]: E1105 15:09:16.974677 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:17.279825 containerd[2056]: time="2025-11-05T15:09:17.279690299Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:17.282347 containerd[2056]: time="2025-11-05T15:09:17.282205307Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 5 15:09:17.285357 containerd[2056]: time="2025-11-05T15:09:17.285334113Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:17.289955 containerd[2056]: time="2025-11-05T15:09:17.289348928Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:17.289955 containerd[2056]: time="2025-11-05T15:09:17.289833499Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 2.833829044s" Nov 5 15:09:17.289955 containerd[2056]: time="2025-11-05T15:09:17.289861108Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 15:09:17.296730 containerd[2056]: time="2025-11-05T15:09:17.296702350Z" level=info msg="CreateContainer within sandbox \"eec564f8ac8dac5fd5810d75f1ef398365fb6b0a75e8fc2ad8d077f9335297eb\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 5 15:09:17.317737 containerd[2056]: time="2025-11-05T15:09:17.317251367Z" level=info msg="Container 82f7976e9e2c0dd65256bfc080d99db18997d345797559038475900517aba3c2: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:09:17.330713 containerd[2056]: time="2025-11-05T15:09:17.330673059Z" level=info msg="CreateContainer within sandbox \"eec564f8ac8dac5fd5810d75f1ef398365fb6b0a75e8fc2ad8d077f9335297eb\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"82f7976e9e2c0dd65256bfc080d99db18997d345797559038475900517aba3c2\"" Nov 5 15:09:17.331905 containerd[2056]: time="2025-11-05T15:09:17.331474201Z" level=info msg="StartContainer for \"82f7976e9e2c0dd65256bfc080d99db18997d345797559038475900517aba3c2\"" Nov 5 15:09:17.332207 containerd[2056]: time="2025-11-05T15:09:17.332183636Z" level=info msg="connecting to shim 82f7976e9e2c0dd65256bfc080d99db18997d345797559038475900517aba3c2" address="unix:///run/containerd/s/a1c47c2e774928f0b4ace5049fbe16eac546761bd13c06892c566e40f482d1bb" protocol=ttrpc version=3 Nov 5 15:09:17.351016 systemd[1]: Started cri-containerd-82f7976e9e2c0dd65256bfc080d99db18997d345797559038475900517aba3c2.scope - libcontainer container 82f7976e9e2c0dd65256bfc080d99db18997d345797559038475900517aba3c2. Nov 5 15:09:17.375906 containerd[2056]: time="2025-11-05T15:09:17.375841502Z" level=info msg="StartContainer for \"82f7976e9e2c0dd65256bfc080d99db18997d345797559038475900517aba3c2\" returns successfully" Nov 5 15:09:17.975289 kubelet[2637]: E1105 15:09:17.975232 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:18.975709 kubelet[2637]: E1105 15:09:18.975662 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:19.963898 kubelet[2637]: E1105 15:09:19.963837 2637 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:19.976081 kubelet[2637]: E1105 15:09:19.976052 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:20.976687 kubelet[2637]: E1105 15:09:20.976646 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:21.976918 kubelet[2637]: E1105 15:09:21.976867 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:22.977411 kubelet[2637]: E1105 15:09:22.977366 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:23.098353 kubelet[2637]: I1105 15:09:23.098235 2637 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-bb8f74bfb-h44w4" podStartSLOduration=7.262954965 podStartE2EDuration="10.098214598s" podCreationTimestamp="2025-11-05 15:09:13 +0000 UTC" firstStartedPulling="2025-11-05 15:09:14.455514445 +0000 UTC m=+14.944039317" lastFinishedPulling="2025-11-05 15:09:17.29077407 +0000 UTC m=+17.779298950" observedRunningTime="2025-11-05 15:09:18.138129703 +0000 UTC m=+18.626654575" watchObservedRunningTime="2025-11-05 15:09:23.098214598 +0000 UTC m=+23.586739470" Nov 5 15:09:23.106463 kubelet[2637]: I1105 15:09:23.106350 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/181165f7-dcc4-469b-b299-14139ac36140-data\") pod \"nfs-server-provisioner-0\" (UID: \"181165f7-dcc4-469b-b299-14139ac36140\") " pod="default/nfs-server-provisioner-0" Nov 5 15:09:23.106463 kubelet[2637]: I1105 15:09:23.106384 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2f95v\" (UniqueName: \"kubernetes.io/projected/181165f7-dcc4-469b-b299-14139ac36140-kube-api-access-2f95v\") pod \"nfs-server-provisioner-0\" (UID: \"181165f7-dcc4-469b-b299-14139ac36140\") " pod="default/nfs-server-provisioner-0" Nov 5 15:09:23.110317 systemd[1]: Created slice kubepods-besteffort-pod181165f7_dcc4_469b_b299_14139ac36140.slice - libcontainer container kubepods-besteffort-pod181165f7_dcc4_469b_b299_14139ac36140.slice. Nov 5 15:09:23.419061 containerd[2056]: time="2025-11-05T15:09:23.419023067Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:181165f7-dcc4-469b-b299-14139ac36140,Namespace:default,Attempt:0,}" Nov 5 15:09:23.444456 systemd-networkd[1641]: vethe19dd8b0: Link UP Nov 5 15:09:23.455091 kernel: cni0: port 2(vethe19dd8b0) entered blocking state Nov 5 15:09:23.455194 kernel: cni0: port 2(vethe19dd8b0) entered disabled state Nov 5 15:09:23.458860 kernel: vethe19dd8b0: entered allmulticast mode Nov 5 15:09:23.459195 kernel: vethe19dd8b0: entered promiscuous mode Nov 5 15:09:23.471486 kernel: cni0: port 2(vethe19dd8b0) entered blocking state Nov 5 15:09:23.471781 kernel: cni0: port 2(vethe19dd8b0) entered forwarding state Nov 5 15:09:23.471910 systemd-networkd[1641]: vethe19dd8b0: Gained carrier Nov 5 15:09:23.473484 containerd[2056]: 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)(0x4000082950), "name":"cbr0", "type":"bridge"} Nov 5 15:09:23.473484 containerd[2056]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:09:23.524670 containerd[2056]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-05T15:09:23.524621712Z" level=info msg="connecting to shim acaf3bc4d869c406f6db2c9bea0238761693131a6ffab62a9fc798ee12bf5284" address="unix:///run/containerd/s/4f4fa3fb87244342880cbf35e472a9d626f487880e2c3f2cdeca04fa539c93dd" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:09:23.548037 systemd[1]: Started cri-containerd-acaf3bc4d869c406f6db2c9bea0238761693131a6ffab62a9fc798ee12bf5284.scope - libcontainer container acaf3bc4d869c406f6db2c9bea0238761693131a6ffab62a9fc798ee12bf5284. Nov 5 15:09:23.581383 containerd[2056]: time="2025-11-05T15:09:23.581341344Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:181165f7-dcc4-469b-b299-14139ac36140,Namespace:default,Attempt:0,} returns sandbox id \"acaf3bc4d869c406f6db2c9bea0238761693131a6ffab62a9fc798ee12bf5284\"" Nov 5 15:09:23.582922 containerd[2056]: time="2025-11-05T15:09:23.582867133Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 5 15:09:23.977646 kubelet[2637]: E1105 15:09:23.977596 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:24.978068 kubelet[2637]: E1105 15:09:24.978013 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:25.318149 systemd-networkd[1641]: vethe19dd8b0: Gained IPv6LL Nov 5 15:09:25.645339 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1785332018.mount: Deactivated successfully. Nov 5 15:09:25.978600 kubelet[2637]: E1105 15:09:25.978560 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:26.980031 kubelet[2637]: E1105 15:09:26.979986 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:27.284466 containerd[2056]: time="2025-11-05T15:09:27.284312831Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:27.287179 containerd[2056]: time="2025-11-05T15:09:27.287140857Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 5 15:09:27.291061 containerd[2056]: time="2025-11-05T15:09:27.291036665Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:27.295207 containerd[2056]: time="2025-11-05T15:09:27.295147944Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:27.296077 containerd[2056]: time="2025-11-05T15:09:27.295595821Z" 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.712691792s" Nov 5 15:09:27.296077 containerd[2056]: time="2025-11-05T15:09:27.295626254Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 5 15:09:27.303136 containerd[2056]: time="2025-11-05T15:09:27.303104526Z" level=info msg="CreateContainer within sandbox \"acaf3bc4d869c406f6db2c9bea0238761693131a6ffab62a9fc798ee12bf5284\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 5 15:09:27.325009 containerd[2056]: time="2025-11-05T15:09:27.324522346Z" level=info msg="Container 1797aaa0f4554d6a5d3d65eb05e314a968672de7a4830dbb5738baf3986c5b0a: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:09:27.327286 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1937156377.mount: Deactivated successfully. Nov 5 15:09:27.342170 containerd[2056]: time="2025-11-05T15:09:27.342103838Z" level=info msg="CreateContainer within sandbox \"acaf3bc4d869c406f6db2c9bea0238761693131a6ffab62a9fc798ee12bf5284\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"1797aaa0f4554d6a5d3d65eb05e314a968672de7a4830dbb5738baf3986c5b0a\"" Nov 5 15:09:27.342984 containerd[2056]: time="2025-11-05T15:09:27.342775329Z" level=info msg="StartContainer for \"1797aaa0f4554d6a5d3d65eb05e314a968672de7a4830dbb5738baf3986c5b0a\"" Nov 5 15:09:27.343748 containerd[2056]: time="2025-11-05T15:09:27.343722821Z" level=info msg="connecting to shim 1797aaa0f4554d6a5d3d65eb05e314a968672de7a4830dbb5738baf3986c5b0a" address="unix:///run/containerd/s/4f4fa3fb87244342880cbf35e472a9d626f487880e2c3f2cdeca04fa539c93dd" protocol=ttrpc version=3 Nov 5 15:09:27.368035 systemd[1]: Started cri-containerd-1797aaa0f4554d6a5d3d65eb05e314a968672de7a4830dbb5738baf3986c5b0a.scope - libcontainer container 1797aaa0f4554d6a5d3d65eb05e314a968672de7a4830dbb5738baf3986c5b0a. Nov 5 15:09:27.400600 containerd[2056]: time="2025-11-05T15:09:27.400554792Z" level=info msg="StartContainer for \"1797aaa0f4554d6a5d3d65eb05e314a968672de7a4830dbb5738baf3986c5b0a\" returns successfully" Nov 5 15:09:27.980432 kubelet[2637]: E1105 15:09:27.980378 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:28.165323 kubelet[2637]: I1105 15:09:28.165259 2637 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.451564956 podStartE2EDuration="5.165244752s" podCreationTimestamp="2025-11-05 15:09:23 +0000 UTC" firstStartedPulling="2025-11-05 15:09:23.582687103 +0000 UTC m=+24.071211975" lastFinishedPulling="2025-11-05 15:09:27.296366899 +0000 UTC m=+27.784891771" observedRunningTime="2025-11-05 15:09:28.165079458 +0000 UTC m=+28.653604354" watchObservedRunningTime="2025-11-05 15:09:28.165244752 +0000 UTC m=+28.653769632" Nov 5 15:09:28.981144 kubelet[2637]: E1105 15:09:28.981097 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:29.981501 kubelet[2637]: E1105 15:09:29.981464 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:30.982401 kubelet[2637]: E1105 15:09:30.982339 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:31.983310 kubelet[2637]: E1105 15:09:31.983262 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:32.643556 systemd[1]: Created slice kubepods-besteffort-podf12f3f9e_9e60_4352_9a4a_633e5989e0e3.slice - libcontainer container kubepods-besteffort-podf12f3f9e_9e60_4352_9a4a_633e5989e0e3.slice. Nov 5 15:09:32.756321 kubelet[2637]: I1105 15:09:32.756204 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-79450874-4d6d-464b-b0be-da775e95aee9\" (UniqueName: \"kubernetes.io/nfs/f12f3f9e-9e60-4352-9a4a-633e5989e0e3-pvc-79450874-4d6d-464b-b0be-da775e95aee9\") pod \"test-pod-1\" (UID: \"f12f3f9e-9e60-4352-9a4a-633e5989e0e3\") " pod="default/test-pod-1" Nov 5 15:09:32.756321 kubelet[2637]: I1105 15:09:32.756248 2637 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vlvkh\" (UniqueName: \"kubernetes.io/projected/f12f3f9e-9e60-4352-9a4a-633e5989e0e3-kube-api-access-vlvkh\") pod \"test-pod-1\" (UID: \"f12f3f9e-9e60-4352-9a4a-633e5989e0e3\") " pod="default/test-pod-1" Nov 5 15:09:32.984408 kubelet[2637]: E1105 15:09:32.984354 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:33.325904 kernel: netfs: FS-Cache loaded Nov 5 15:09:33.483337 kernel: RPC: Registered named UNIX socket transport module. Nov 5 15:09:33.483484 kernel: RPC: Registered udp transport module. Nov 5 15:09:33.483522 kernel: RPC: Registered tcp transport module. Nov 5 15:09:33.489811 kernel: RPC: Registered tcp-with-tls transport module. Nov 5 15:09:33.489920 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 5 15:09:33.901797 kernel: NFS: Registering the id_resolver key type Nov 5 15:09:33.901952 kernel: Key type id_resolver registered Nov 5 15:09:33.901972 kernel: Key type id_legacy registered Nov 5 15:09:33.984902 kubelet[2637]: E1105 15:09:33.984853 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:34.166671 nfsidmap[3676]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.1-a-6d305da4f4' Nov 5 15:09:34.172076 nfsidmap[3677]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.1-a-6d305da4f4' Nov 5 15:09:34.180453 nfsrahead[3679]: setting /var/lib/kubelet/pods/f12f3f9e-9e60-4352-9a4a-633e5989e0e3/volumes/kubernetes.io~nfs/pvc-79450874-4d6d-464b-b0be-da775e95aee9 readahead to 128 Nov 5 15:09:34.459604 containerd[2056]: time="2025-11-05T15:09:34.459504704Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f12f3f9e-9e60-4352-9a4a-633e5989e0e3,Namespace:default,Attempt:0,}" Nov 5 15:09:34.494079 systemd-networkd[1641]: veth38c872aa: Link UP Nov 5 15:09:34.503109 kernel: cni0: port 3(veth38c872aa) entered blocking state Nov 5 15:09:34.503198 kernel: cni0: port 3(veth38c872aa) entered disabled state Nov 5 15:09:34.506675 kernel: veth38c872aa: entered allmulticast mode Nov 5 15:09:34.510245 kernel: veth38c872aa: entered promiscuous mode Nov 5 15:09:34.520964 kernel: cni0: port 3(veth38c872aa) entered blocking state Nov 5 15:09:34.521074 kernel: cni0: port 3(veth38c872aa) entered forwarding state Nov 5 15:09:34.521507 systemd-networkd[1641]: veth38c872aa: Gained carrier Nov 5 15:09:34.523315 containerd[2056]: 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)(0x400008c950), "name":"cbr0", "type":"bridge"} Nov 5 15:09:34.523315 containerd[2056]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:09:34.585322 containerd[2056]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-05T15:09:34.585277021Z" level=info msg="connecting to shim 88dd2eca5abbb6f7fc74f3b5d8f3a7eb7fad0951c6d369ef5f868c62c2754e83" address="unix:///run/containerd/s/ec4f6f8f4247a0fe760830c2dba5abfcfa5f0b1f0e819f9819489e5131e82afa" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:09:34.607088 systemd[1]: Started cri-containerd-88dd2eca5abbb6f7fc74f3b5d8f3a7eb7fad0951c6d369ef5f868c62c2754e83.scope - libcontainer container 88dd2eca5abbb6f7fc74f3b5d8f3a7eb7fad0951c6d369ef5f868c62c2754e83. Nov 5 15:09:34.640819 containerd[2056]: time="2025-11-05T15:09:34.640763981Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f12f3f9e-9e60-4352-9a4a-633e5989e0e3,Namespace:default,Attempt:0,} returns sandbox id \"88dd2eca5abbb6f7fc74f3b5d8f3a7eb7fad0951c6d369ef5f868c62c2754e83\"" Nov 5 15:09:34.642111 containerd[2056]: time="2025-11-05T15:09:34.642076385Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 15:09:34.985540 kubelet[2637]: E1105 15:09:34.985403 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:35.054720 containerd[2056]: time="2025-11-05T15:09:35.054670987Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:09:35.058974 containerd[2056]: time="2025-11-05T15:09:35.058767046Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 5 15:09:35.060780 containerd[2056]: time="2025-11-05T15:09:35.060738480Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 418.629046ms" Nov 5 15:09:35.060993 containerd[2056]: time="2025-11-05T15:09:35.060900214Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 15:09:35.072492 containerd[2056]: time="2025-11-05T15:09:35.072449621Z" level=info msg="CreateContainer within sandbox \"88dd2eca5abbb6f7fc74f3b5d8f3a7eb7fad0951c6d369ef5f868c62c2754e83\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 5 15:09:35.093396 containerd[2056]: time="2025-11-05T15:09:35.093350681Z" level=info msg="Container 10a79c9c9e0baf367d5509d1e54cd1007fb55eab64bd94a7a039232ddf44d97f: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:09:35.117491 containerd[2056]: time="2025-11-05T15:09:35.117400368Z" level=info msg="CreateContainer within sandbox \"88dd2eca5abbb6f7fc74f3b5d8f3a7eb7fad0951c6d369ef5f868c62c2754e83\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"10a79c9c9e0baf367d5509d1e54cd1007fb55eab64bd94a7a039232ddf44d97f\"" Nov 5 15:09:35.118918 containerd[2056]: time="2025-11-05T15:09:35.118177698Z" level=info msg="StartContainer for \"10a79c9c9e0baf367d5509d1e54cd1007fb55eab64bd94a7a039232ddf44d97f\"" Nov 5 15:09:35.119186 containerd[2056]: time="2025-11-05T15:09:35.119165204Z" level=info msg="connecting to shim 10a79c9c9e0baf367d5509d1e54cd1007fb55eab64bd94a7a039232ddf44d97f" address="unix:///run/containerd/s/ec4f6f8f4247a0fe760830c2dba5abfcfa5f0b1f0e819f9819489e5131e82afa" protocol=ttrpc version=3 Nov 5 15:09:35.138049 systemd[1]: Started cri-containerd-10a79c9c9e0baf367d5509d1e54cd1007fb55eab64bd94a7a039232ddf44d97f.scope - libcontainer container 10a79c9c9e0baf367d5509d1e54cd1007fb55eab64bd94a7a039232ddf44d97f. Nov 5 15:09:35.181912 containerd[2056]: time="2025-11-05T15:09:35.181851935Z" level=info msg="StartContainer for \"10a79c9c9e0baf367d5509d1e54cd1007fb55eab64bd94a7a039232ddf44d97f\" returns successfully" Nov 5 15:09:35.986394 kubelet[2637]: E1105 15:09:35.986343 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:36.192903 kubelet[2637]: I1105 15:09:36.192689 2637 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=11.772788332 podStartE2EDuration="12.19267342s" podCreationTimestamp="2025-11-05 15:09:24 +0000 UTC" firstStartedPulling="2025-11-05 15:09:34.641612786 +0000 UTC m=+35.130137658" lastFinishedPulling="2025-11-05 15:09:35.061497874 +0000 UTC m=+35.550022746" observedRunningTime="2025-11-05 15:09:36.192492606 +0000 UTC m=+36.681017478" watchObservedRunningTime="2025-11-05 15:09:36.19267342 +0000 UTC m=+36.681198292" Nov 5 15:09:36.325076 systemd-networkd[1641]: veth38c872aa: Gained IPv6LL Nov 5 15:09:36.987214 kubelet[2637]: E1105 15:09:36.987162 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:37.987588 kubelet[2637]: E1105 15:09:37.987536 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:09:38.988632 kubelet[2637]: E1105 15:09:38.988574 2637 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"