Sep 10 23:55:12.137745 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Sep 10 23:55:12.137765 kernel: Linux version 6.12.46-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Wed Sep 10 22:24:03 -00 2025 Sep 10 23:55:12.137772 kernel: KASLR enabled Sep 10 23:55:12.137776 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Sep 10 23:55:12.137781 kernel: printk: legacy bootconsole [pl11] enabled Sep 10 23:55:12.137784 kernel: efi: EFI v2.7 by EDK II Sep 10 23:55:12.137790 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f20f698 RNG=0x3fd5f998 MEMRESERVE=0x3e477598 Sep 10 23:55:12.137793 kernel: random: crng init done Sep 10 23:55:12.137797 kernel: secureboot: Secure boot disabled Sep 10 23:55:12.137801 kernel: ACPI: Early table checksum verification disabled Sep 10 23:55:12.137805 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) Sep 10 23:55:12.137809 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137813 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137818 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) Sep 10 23:55:12.137823 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137827 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137831 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137836 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137840 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137844 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137849 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Sep 10 23:55:12.137853 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 10 23:55:12.137857 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Sep 10 23:55:12.137861 kernel: ACPI: Use ACPI SPCR as default console: No Sep 10 23:55:12.137865 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 10 23:55:12.137869 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Sep 10 23:55:12.137873 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Sep 10 23:55:12.137877 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Sep 10 23:55:12.137882 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Sep 10 23:55:12.137887 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Sep 10 23:55:12.137891 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Sep 10 23:55:12.137895 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Sep 10 23:55:12.137899 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Sep 10 23:55:12.137903 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Sep 10 23:55:12.137907 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Sep 10 23:55:12.137911 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Sep 10 23:55:12.137915 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Sep 10 23:55:12.137919 kernel: NODE_DATA(0) allocated [mem 0x1bf7fda00-0x1bf804fff] Sep 10 23:55:12.137923 kernel: Zone ranges: Sep 10 23:55:12.137928 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Sep 10 23:55:12.137935 kernel: DMA32 empty Sep 10 23:55:12.137939 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Sep 10 23:55:12.137944 kernel: Device empty Sep 10 23:55:12.137948 kernel: Movable zone start for each node Sep 10 23:55:12.137952 kernel: Early memory node ranges Sep 10 23:55:12.137958 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Sep 10 23:55:12.137962 kernel: node 0: [mem 0x0000000000824000-0x000000003e45ffff] Sep 10 23:55:12.137966 kernel: node 0: [mem 0x000000003e460000-0x000000003e46ffff] Sep 10 23:55:12.137971 kernel: node 0: [mem 0x000000003e470000-0x000000003e54ffff] Sep 10 23:55:12.137975 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] Sep 10 23:55:12.137979 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] Sep 10 23:55:12.137983 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] Sep 10 23:55:12.137988 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] Sep 10 23:55:12.137992 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Sep 10 23:55:12.137996 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Sep 10 23:55:12.138001 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Sep 10 23:55:12.138005 kernel: cma: Reserved 16 MiB at 0x000000003d400000 on node -1 Sep 10 23:55:12.138010 kernel: psci: probing for conduit method from ACPI. Sep 10 23:55:12.138015 kernel: psci: PSCIv1.1 detected in firmware. Sep 10 23:55:12.138019 kernel: psci: Using standard PSCI v0.2 function IDs Sep 10 23:55:12.138023 kernel: psci: MIGRATE_INFO_TYPE not supported. Sep 10 23:55:12.138027 kernel: psci: SMC Calling Convention v1.4 Sep 10 23:55:12.138032 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Sep 10 23:55:12.138036 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Sep 10 23:55:12.138040 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Sep 10 23:55:12.138045 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Sep 10 23:55:12.138049 kernel: pcpu-alloc: [0] 0 [0] 1 Sep 10 23:55:12.138053 kernel: Detected PIPT I-cache on CPU0 Sep 10 23:55:12.138058 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Sep 10 23:55:12.138063 kernel: CPU features: detected: GIC system register CPU interface Sep 10 23:55:12.138067 kernel: CPU features: detected: Spectre-v4 Sep 10 23:55:12.138071 kernel: CPU features: detected: Spectre-BHB Sep 10 23:55:12.138076 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 10 23:55:12.138080 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 10 23:55:12.138084 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Sep 10 23:55:12.138089 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 10 23:55:12.138093 kernel: alternatives: applying boot alternatives Sep 10 23:55:12.138099 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=dd9c14cce645c634e06a91b09405eea80057f02909b9267c482dc457df1cddec Sep 10 23:55:12.138103 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 10 23:55:12.138108 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 10 23:55:12.138113 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 10 23:55:12.138118 kernel: Fallback order for Node 0: 0 Sep 10 23:55:12.138122 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Sep 10 23:55:12.138126 kernel: Policy zone: Normal Sep 10 23:55:12.138130 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 10 23:55:12.138135 kernel: software IO TLB: area num 2. Sep 10 23:55:12.138139 kernel: software IO TLB: mapped [mem 0x0000000036280000-0x000000003a280000] (64MB) Sep 10 23:55:12.138143 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 10 23:55:12.138148 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 10 23:55:12.138153 kernel: rcu: RCU event tracing is enabled. Sep 10 23:55:12.138158 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 10 23:55:12.138163 kernel: Trampoline variant of Tasks RCU enabled. Sep 10 23:55:12.138167 kernel: Tracing variant of Tasks RCU enabled. Sep 10 23:55:12.138171 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 10 23:55:12.138176 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 10 23:55:12.138180 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 10 23:55:12.138185 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 10 23:55:12.138189 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 10 23:55:12.138193 kernel: GICv3: 960 SPIs implemented Sep 10 23:55:12.138197 kernel: GICv3: 0 Extended SPIs implemented Sep 10 23:55:12.138202 kernel: Root IRQ handler: gic_handle_irq Sep 10 23:55:12.138206 kernel: GICv3: GICv3 features: 16 PPIs, RSS Sep 10 23:55:12.138211 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Sep 10 23:55:12.138216 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Sep 10 23:55:12.138220 kernel: ITS: No ITS available, not enabling LPIs Sep 10 23:55:12.138225 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 10 23:55:12.138229 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Sep 10 23:55:12.138233 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 10 23:55:12.138238 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Sep 10 23:55:12.142249 kernel: Console: colour dummy device 80x25 Sep 10 23:55:12.142257 kernel: printk: legacy console [tty1] enabled Sep 10 23:55:12.142262 kernel: ACPI: Core revision 20240827 Sep 10 23:55:12.142267 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Sep 10 23:55:12.142278 kernel: pid_max: default: 32768 minimum: 301 Sep 10 23:55:12.142282 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 10 23:55:12.142287 kernel: landlock: Up and running. Sep 10 23:55:12.142291 kernel: SELinux: Initializing. Sep 10 23:55:12.142296 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 10 23:55:12.142305 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 10 23:55:12.142311 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x1a0000e, misc 0x31e1 Sep 10 23:55:12.142316 kernel: Hyper-V: Host Build 10.0.26100.1261-1-0 Sep 10 23:55:12.142320 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 10 23:55:12.142325 kernel: rcu: Hierarchical SRCU implementation. Sep 10 23:55:12.142331 kernel: rcu: Max phase no-delay instances is 400. Sep 10 23:55:12.142337 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 10 23:55:12.142342 kernel: Remapping and enabling EFI services. Sep 10 23:55:12.142346 kernel: smp: Bringing up secondary CPUs ... Sep 10 23:55:12.142351 kernel: Detected PIPT I-cache on CPU1 Sep 10 23:55:12.142356 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Sep 10 23:55:12.142362 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Sep 10 23:55:12.142367 kernel: smp: Brought up 1 node, 2 CPUs Sep 10 23:55:12.142372 kernel: SMP: Total of 2 processors activated. Sep 10 23:55:12.142377 kernel: CPU: All CPU(s) started at EL1 Sep 10 23:55:12.142381 kernel: CPU features: detected: 32-bit EL0 Support Sep 10 23:55:12.142386 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Sep 10 23:55:12.142391 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 10 23:55:12.142396 kernel: CPU features: detected: Common not Private translations Sep 10 23:55:12.142401 kernel: CPU features: detected: CRC32 instructions Sep 10 23:55:12.142407 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Sep 10 23:55:12.142411 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 10 23:55:12.142416 kernel: CPU features: detected: LSE atomic instructions Sep 10 23:55:12.142421 kernel: CPU features: detected: Privileged Access Never Sep 10 23:55:12.142426 kernel: CPU features: detected: Speculation barrier (SB) Sep 10 23:55:12.142431 kernel: CPU features: detected: TLB range maintenance instructions Sep 10 23:55:12.142435 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 10 23:55:12.142440 kernel: CPU features: detected: Scalable Vector Extension Sep 10 23:55:12.142445 kernel: alternatives: applying system-wide alternatives Sep 10 23:55:12.142451 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Sep 10 23:55:12.142456 kernel: SVE: maximum available vector length 16 bytes per vector Sep 10 23:55:12.142460 kernel: SVE: default vector length 16 bytes per vector Sep 10 23:55:12.142466 kernel: Memory: 3959604K/4194160K available (11136K kernel code, 2436K rwdata, 9084K rodata, 38976K init, 1038K bss, 213368K reserved, 16384K cma-reserved) Sep 10 23:55:12.142471 kernel: devtmpfs: initialized Sep 10 23:55:12.142476 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 10 23:55:12.142481 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 10 23:55:12.142485 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 10 23:55:12.142490 kernel: 0 pages in range for non-PLT usage Sep 10 23:55:12.142496 kernel: 508560 pages in range for PLT usage Sep 10 23:55:12.142501 kernel: pinctrl core: initialized pinctrl subsystem Sep 10 23:55:12.142506 kernel: SMBIOS 3.1.0 present. Sep 10 23:55:12.142511 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 Sep 10 23:55:12.142515 kernel: DMI: Memory slots populated: 2/2 Sep 10 23:55:12.142520 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 10 23:55:12.142525 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 10 23:55:12.142530 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 10 23:55:12.142535 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 10 23:55:12.142541 kernel: audit: initializing netlink subsys (disabled) Sep 10 23:55:12.142545 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Sep 10 23:55:12.142550 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 10 23:55:12.142555 kernel: cpuidle: using governor menu Sep 10 23:55:12.142560 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 10 23:55:12.142565 kernel: ASID allocator initialised with 32768 entries Sep 10 23:55:12.142569 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 10 23:55:12.142574 kernel: Serial: AMBA PL011 UART driver Sep 10 23:55:12.142579 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 10 23:55:12.142585 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 10 23:55:12.142590 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 10 23:55:12.142595 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 10 23:55:12.142600 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 10 23:55:12.142605 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 10 23:55:12.142609 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 10 23:55:12.142614 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 10 23:55:12.142619 kernel: ACPI: Added _OSI(Module Device) Sep 10 23:55:12.142624 kernel: ACPI: Added _OSI(Processor Device) Sep 10 23:55:12.142629 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 10 23:55:12.142634 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 10 23:55:12.142639 kernel: ACPI: Interpreter enabled Sep 10 23:55:12.142644 kernel: ACPI: Using GIC for interrupt routing Sep 10 23:55:12.142649 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Sep 10 23:55:12.142654 kernel: printk: legacy console [ttyAMA0] enabled Sep 10 23:55:12.142658 kernel: printk: legacy bootconsole [pl11] disabled Sep 10 23:55:12.142663 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Sep 10 23:55:12.142668 kernel: ACPI: CPU0 has been hot-added Sep 10 23:55:12.142674 kernel: ACPI: CPU1 has been hot-added Sep 10 23:55:12.142679 kernel: iommu: Default domain type: Translated Sep 10 23:55:12.142683 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 10 23:55:12.142688 kernel: efivars: Registered efivars operations Sep 10 23:55:12.142693 kernel: vgaarb: loaded Sep 10 23:55:12.142698 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 10 23:55:12.142702 kernel: VFS: Disk quotas dquot_6.6.0 Sep 10 23:55:12.142707 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 10 23:55:12.142712 kernel: pnp: PnP ACPI init Sep 10 23:55:12.142718 kernel: pnp: PnP ACPI: found 0 devices Sep 10 23:55:12.142723 kernel: NET: Registered PF_INET protocol family Sep 10 23:55:12.142727 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 10 23:55:12.142732 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 10 23:55:12.142737 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 10 23:55:12.142742 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 10 23:55:12.142747 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 10 23:55:12.142751 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 10 23:55:12.142756 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 10 23:55:12.142762 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 10 23:55:12.142767 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 10 23:55:12.142771 kernel: PCI: CLS 0 bytes, default 64 Sep 10 23:55:12.142776 kernel: kvm [1]: HYP mode not available Sep 10 23:55:12.142781 kernel: Initialise system trusted keyrings Sep 10 23:55:12.142786 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 10 23:55:12.142790 kernel: Key type asymmetric registered Sep 10 23:55:12.142795 kernel: Asymmetric key parser 'x509' registered Sep 10 23:55:12.142800 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 10 23:55:12.142805 kernel: io scheduler mq-deadline registered Sep 10 23:55:12.142810 kernel: io scheduler kyber registered Sep 10 23:55:12.142815 kernel: io scheduler bfq registered Sep 10 23:55:12.142820 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 10 23:55:12.142824 kernel: thunder_xcv, ver 1.0 Sep 10 23:55:12.142829 kernel: thunder_bgx, ver 1.0 Sep 10 23:55:12.142834 kernel: nicpf, ver 1.0 Sep 10 23:55:12.142839 kernel: nicvf, ver 1.0 Sep 10 23:55:12.143012 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 10 23:55:12.143070 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-10T23:55:11 UTC (1757548511) Sep 10 23:55:12.143078 kernel: efifb: probing for efifb Sep 10 23:55:12.143083 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 10 23:55:12.143089 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 10 23:55:12.143094 kernel: efifb: scrolling: redraw Sep 10 23:55:12.143100 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 10 23:55:12.143105 kernel: Console: switching to colour frame buffer device 128x48 Sep 10 23:55:12.143112 kernel: fb0: EFI VGA frame buffer device Sep 10 23:55:12.143119 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Sep 10 23:55:12.143125 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 10 23:55:12.143130 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Sep 10 23:55:12.143135 kernel: watchdog: NMI not fully supported Sep 10 23:55:12.143140 kernel: watchdog: Hard watchdog permanently disabled Sep 10 23:55:12.143145 kernel: NET: Registered PF_INET6 protocol family Sep 10 23:55:12.143149 kernel: Segment Routing with IPv6 Sep 10 23:55:12.143154 kernel: In-situ OAM (IOAM) with IPv6 Sep 10 23:55:12.143159 kernel: NET: Registered PF_PACKET protocol family Sep 10 23:55:12.143165 kernel: Key type dns_resolver registered Sep 10 23:55:12.143169 kernel: registered taskstats version 1 Sep 10 23:55:12.143174 kernel: Loading compiled-in X.509 certificates Sep 10 23:55:12.143179 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: 3c20aab1105575c84ea94c1a59a27813fcebdea7' Sep 10 23:55:12.143184 kernel: Demotion targets for Node 0: null Sep 10 23:55:12.143189 kernel: Key type .fscrypt registered Sep 10 23:55:12.143193 kernel: Key type fscrypt-provisioning registered Sep 10 23:55:12.143198 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 10 23:55:12.143203 kernel: ima: Allocated hash algorithm: sha1 Sep 10 23:55:12.143209 kernel: ima: No architecture policies found Sep 10 23:55:12.143214 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 10 23:55:12.143218 kernel: clk: Disabling unused clocks Sep 10 23:55:12.143223 kernel: PM: genpd: Disabling unused power domains Sep 10 23:55:12.143228 kernel: Warning: unable to open an initial console. Sep 10 23:55:12.143233 kernel: Freeing unused kernel memory: 38976K Sep 10 23:55:12.143238 kernel: Run /init as init process Sep 10 23:55:12.143260 kernel: with arguments: Sep 10 23:55:12.143265 kernel: /init Sep 10 23:55:12.143271 kernel: with environment: Sep 10 23:55:12.143276 kernel: HOME=/ Sep 10 23:55:12.143281 kernel: TERM=linux Sep 10 23:55:12.143286 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 10 23:55:12.143292 systemd[1]: Successfully made /usr/ read-only. Sep 10 23:55:12.143299 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 10 23:55:12.143305 systemd[1]: Detected virtualization microsoft. Sep 10 23:55:12.143311 systemd[1]: Detected architecture arm64. Sep 10 23:55:12.143316 systemd[1]: Running in initrd. Sep 10 23:55:12.143321 systemd[1]: No hostname configured, using default hostname. Sep 10 23:55:12.143327 systemd[1]: Hostname set to . Sep 10 23:55:12.143332 systemd[1]: Initializing machine ID from random generator. Sep 10 23:55:12.143337 systemd[1]: Queued start job for default target initrd.target. Sep 10 23:55:12.143342 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 10 23:55:12.143347 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 10 23:55:12.143354 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 10 23:55:12.143359 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 10 23:55:12.143364 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 10 23:55:12.143370 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 10 23:55:12.143376 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 10 23:55:12.143381 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 10 23:55:12.143387 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 10 23:55:12.143393 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 10 23:55:12.143398 systemd[1]: Reached target paths.target - Path Units. Sep 10 23:55:12.143403 systemd[1]: Reached target slices.target - Slice Units. Sep 10 23:55:12.143409 systemd[1]: Reached target swap.target - Swaps. Sep 10 23:55:12.143414 systemd[1]: Reached target timers.target - Timer Units. Sep 10 23:55:12.143419 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 10 23:55:12.143424 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 10 23:55:12.143429 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 10 23:55:12.143435 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 10 23:55:12.143441 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 10 23:55:12.143446 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 10 23:55:12.143451 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 10 23:55:12.143456 systemd[1]: Reached target sockets.target - Socket Units. Sep 10 23:55:12.143462 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 10 23:55:12.143467 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 10 23:55:12.143472 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 10 23:55:12.143477 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). Sep 10 23:55:12.143484 systemd[1]: Starting systemd-fsck-usr.service... Sep 10 23:55:12.143489 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 10 23:55:12.143494 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 10 23:55:12.143518 systemd-journald[225]: Collecting audit messages is disabled. Sep 10 23:55:12.143534 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:55:12.143541 systemd-journald[225]: Journal started Sep 10 23:55:12.143556 systemd-journald[225]: Runtime Journal (/run/log/journal/81c452d257204ff2b2eaf0a986ea32b7) is 8M, max 78.5M, 70.5M free. Sep 10 23:55:12.143900 systemd-modules-load[227]: Inserted module 'overlay' Sep 10 23:55:12.160147 systemd[1]: Started systemd-journald.service - Journal Service. Sep 10 23:55:12.162773 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 10 23:55:12.188638 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 10 23:55:12.188662 kernel: Bridge firewalling registered Sep 10 23:55:12.177126 systemd-modules-load[227]: Inserted module 'br_netfilter' Sep 10 23:55:12.177766 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 10 23:55:12.186589 systemd[1]: Finished systemd-fsck-usr.service. Sep 10 23:55:12.192937 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 10 23:55:12.200938 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:12.213101 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 10 23:55:12.235876 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 10 23:55:12.242036 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 10 23:55:12.261490 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 10 23:55:12.277575 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 10 23:55:12.293232 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 10 23:55:12.301015 systemd-tmpfiles[250]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 10 23:55:12.303231 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 10 23:55:12.321336 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 10 23:55:12.328986 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 10 23:55:12.352136 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 10 23:55:12.364844 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 10 23:55:12.379912 dracut-cmdline[261]: 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=dd9c14cce645c634e06a91b09405eea80057f02909b9267c482dc457df1cddec Sep 10 23:55:12.411514 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 10 23:55:12.442360 systemd-resolved[262]: Positive Trust Anchors: Sep 10 23:55:12.442377 systemd-resolved[262]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 10 23:55:12.442396 systemd-resolved[262]: 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 Sep 10 23:55:12.444165 systemd-resolved[262]: Defaulting to hostname 'linux'. Sep 10 23:55:12.497141 kernel: SCSI subsystem initialized Sep 10 23:55:12.446034 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 10 23:55:12.504503 kernel: Loading iSCSI transport class v2.0-870. Sep 10 23:55:12.457373 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 10 23:55:12.514253 kernel: iscsi: registered transport (tcp) Sep 10 23:55:12.527465 kernel: iscsi: registered transport (qla4xxx) Sep 10 23:55:12.527534 kernel: QLogic iSCSI HBA Driver Sep 10 23:55:12.541122 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 10 23:55:12.561271 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 10 23:55:12.568413 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 10 23:55:12.621304 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 10 23:55:12.628384 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 10 23:55:12.701265 kernel: raid6: neonx8 gen() 18553 MB/s Sep 10 23:55:12.718247 kernel: raid6: neonx4 gen() 18556 MB/s Sep 10 23:55:12.737248 kernel: raid6: neonx2 gen() 17068 MB/s Sep 10 23:55:12.758248 kernel: raid6: neonx1 gen() 15011 MB/s Sep 10 23:55:12.777247 kernel: raid6: int64x8 gen() 10524 MB/s Sep 10 23:55:12.796248 kernel: raid6: int64x4 gen() 10621 MB/s Sep 10 23:55:12.816248 kernel: raid6: int64x2 gen() 8989 MB/s Sep 10 23:55:12.838415 kernel: raid6: int64x1 gen() 7001 MB/s Sep 10 23:55:12.838421 kernel: raid6: using algorithm neonx4 gen() 18556 MB/s Sep 10 23:55:12.860533 kernel: raid6: .... xor() 15150 MB/s, rmw enabled Sep 10 23:55:12.860610 kernel: raid6: using neon recovery algorithm Sep 10 23:55:12.870940 kernel: xor: measuring software checksum speed Sep 10 23:55:12.871020 kernel: 8regs : 28480 MB/sec Sep 10 23:55:12.873316 kernel: 32regs : 28846 MB/sec Sep 10 23:55:12.875983 kernel: arm64_neon : 37549 MB/sec Sep 10 23:55:12.879316 kernel: xor: using function: arm64_neon (37549 MB/sec) Sep 10 23:55:12.918259 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 10 23:55:12.924707 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 10 23:55:12.934391 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 10 23:55:12.957121 systemd-udevd[474]: Using default interface naming scheme 'v255'. Sep 10 23:55:12.961851 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 10 23:55:12.974860 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 10 23:55:13.009114 dracut-pre-trigger[482]: rd.md=0: removing MD RAID activation Sep 10 23:55:13.034599 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 10 23:55:13.048698 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 10 23:55:13.092660 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 10 23:55:13.107070 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 10 23:55:13.168272 kernel: hv_vmbus: Vmbus version:5.3 Sep 10 23:55:13.173663 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 10 23:55:13.173762 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:13.210455 kernel: hv_vmbus: registering driver hid_hyperv Sep 10 23:55:13.210476 kernel: hv_vmbus: registering driver hv_storvsc Sep 10 23:55:13.210483 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 10 23:55:13.210498 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 10 23:55:13.210504 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Sep 10 23:55:13.179451 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:55:13.270531 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 10 23:55:13.270558 kernel: scsi host0: storvsc_host_t Sep 10 23:55:13.270734 kernel: scsi host1: storvsc_host_t Sep 10 23:55:13.270807 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 10 23:55:13.270880 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 10 23:55:13.270887 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 10 23:55:13.270962 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Sep 10 23:55:13.271025 kernel: hv_vmbus: registering driver hv_netvsc Sep 10 23:55:13.199590 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:55:13.253218 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 10 23:55:13.298471 kernel: PTP clock support registered Sep 10 23:55:13.262478 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 10 23:55:13.811713 kernel: hv_utils: Registering HyperV Utility Driver Sep 10 23:55:13.811743 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 10 23:55:13.811907 kernel: hv_vmbus: registering driver hv_utils Sep 10 23:55:13.811914 kernel: hv_utils: Shutdown IC version 3.2 Sep 10 23:55:13.811921 kernel: hv_utils: Heartbeat IC version 3.0 Sep 10 23:55:13.811927 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 10 23:55:13.811996 kernel: hv_utils: TimeSync IC version 4.0 Sep 10 23:55:13.812003 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 10 23:55:13.812340 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 10 23:55:13.262570 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:13.277865 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 10 23:55:13.838418 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 10 23:55:13.838612 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#66 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 10 23:55:13.279689 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:55:13.849138 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#73 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 10 23:55:13.811737 systemd-resolved[262]: Clock change detected. Flushing caches. Sep 10 23:55:13.822175 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:13.869294 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 10 23:55:13.869317 kernel: hv_netvsc 000d3ac3-3316-000d-3ac3-3316000d3ac3 eth0: VF slot 1 added Sep 10 23:55:13.872856 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 10 23:55:13.876205 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 10 23:55:13.884339 kernel: hv_vmbus: registering driver hv_pci Sep 10 23:55:13.884387 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 10 23:55:13.891781 kernel: hv_pci cc6bbdaa-016e-40bd-8f40-4c257298992d: PCI VMBus probing: Using version 0x10004 Sep 10 23:55:13.891947 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 10 23:55:13.897445 kernel: hv_pci cc6bbdaa-016e-40bd-8f40-4c257298992d: PCI host bridge to bus 016e:00 Sep 10 23:55:13.903867 kernel: pci_bus 016e:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Sep 10 23:55:13.909530 kernel: pci_bus 016e:00: No busn resource found for root bus, will use [bus 00-ff] Sep 10 23:55:13.919437 kernel: pci 016e:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Sep 10 23:55:13.927147 kernel: pci 016e:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Sep 10 23:55:13.933203 kernel: pci 016e:00:02.0: enabling Extended Tags Sep 10 23:55:13.952323 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#106 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Sep 10 23:55:13.971085 kernel: pci 016e:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 016e:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Sep 10 23:55:13.991236 kernel: pci_bus 016e:00: busn_res: [bus 00-ff] end is updated to 00 Sep 10 23:55:13.991386 kernel: pci 016e:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Sep 10 23:55:13.991475 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#46 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Sep 10 23:55:14.053762 kernel: mlx5_core 016e:00:02.0: enabling device (0000 -> 0002) Sep 10 23:55:14.062045 kernel: mlx5_core 016e:00:02.0: PTM is not supported by PCIe Sep 10 23:55:14.062283 kernel: mlx5_core 016e:00:02.0: firmware version: 16.30.5006 Sep 10 23:55:14.242500 kernel: hv_netvsc 000d3ac3-3316-000d-3ac3-3316000d3ac3 eth0: VF registering: eth1 Sep 10 23:55:14.242721 kernel: mlx5_core 016e:00:02.0 eth1: joined to eth0 Sep 10 23:55:14.249107 kernel: mlx5_core 016e:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Sep 10 23:55:14.257096 kernel: mlx5_core 016e:00:02.0 enP366s1: renamed from eth1 Sep 10 23:55:15.142167 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 10 23:55:15.153246 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 10 23:55:15.285598 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 10 23:55:15.291348 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 10 23:55:15.304771 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 10 23:55:15.340360 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 10 23:55:15.418733 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 10 23:55:15.423183 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 10 23:55:15.431338 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 10 23:55:15.440234 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 10 23:55:15.449448 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 10 23:55:15.487440 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 10 23:55:16.348020 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#51 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 10 23:55:16.361175 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 10 23:55:16.361275 disk-uuid[647]: The operation has completed successfully. Sep 10 23:55:16.446161 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 10 23:55:16.446255 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 10 23:55:16.466220 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 10 23:55:16.487682 sh[767]: Success Sep 10 23:55:16.536347 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 10 23:55:16.536415 kernel: device-mapper: uevent: version 1.0.3 Sep 10 23:55:16.542339 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 10 23:55:16.552123 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Sep 10 23:55:17.277300 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 10 23:55:17.287517 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 10 23:55:17.295306 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 10 23:55:17.321088 kernel: BTRFS: device fsid 3b17f37f-d395-4116-a46d-e07f86112ade devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (785) Sep 10 23:55:17.331689 kernel: BTRFS info (device dm-0): first mount of filesystem 3b17f37f-d395-4116-a46d-e07f86112ade Sep 10 23:55:17.331718 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:55:18.075954 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 10 23:55:18.076049 kernel: BTRFS info (device dm-0): enabling free space tree Sep 10 23:55:18.141132 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 10 23:55:18.145532 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 10 23:55:18.152900 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 10 23:55:18.153586 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 10 23:55:18.175780 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 10 23:55:18.206161 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (808) Sep 10 23:55:18.216901 kernel: BTRFS info (device sda6): first mount of filesystem 538ffae8-60fb-4c82-9100-efc4d2404f73 Sep 10 23:55:18.216965 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:55:18.273944 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 10 23:55:18.284763 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 10 23:55:18.320448 kernel: BTRFS info (device sda6): turning on async discard Sep 10 23:55:18.320499 kernel: BTRFS info (device sda6): enabling free space tree Sep 10 23:55:18.322569 systemd-networkd[948]: lo: Link UP Sep 10 23:55:18.333145 kernel: BTRFS info (device sda6): last unmount of filesystem 538ffae8-60fb-4c82-9100-efc4d2404f73 Sep 10 23:55:18.322577 systemd-networkd[948]: lo: Gained carrier Sep 10 23:55:18.323364 systemd-networkd[948]: Enumeration completed Sep 10 23:55:18.324173 systemd-networkd[948]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:55:18.324176 systemd-networkd[948]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 10 23:55:18.325483 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 10 23:55:18.336141 systemd[1]: Reached target network.target - Network. Sep 10 23:55:18.345567 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 10 23:55:18.358877 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 10 23:55:18.425088 kernel: mlx5_core 016e:00:02.0 enP366s1: Link up Sep 10 23:55:18.425368 kernel: buffer_size[0]=0 is not enough for lossless buffer Sep 10 23:55:18.469024 systemd-networkd[948]: enP366s1: Link UP Sep 10 23:55:18.472362 kernel: hv_netvsc 000d3ac3-3316-000d-3ac3-3316000d3ac3 eth0: Data path switched to VF: enP366s1 Sep 10 23:55:18.469127 systemd-networkd[948]: eth0: Link UP Sep 10 23:55:18.469196 systemd-networkd[948]: eth0: Gained carrier Sep 10 23:55:18.469211 systemd-networkd[948]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:55:18.478468 systemd-networkd[948]: enP366s1: Gained carrier Sep 10 23:55:18.500114 systemd-networkd[948]: eth0: DHCPv4 address 10.200.20.14/24, gateway 10.200.20.1 acquired from 168.63.129.16 Sep 10 23:55:20.166199 systemd-networkd[948]: eth0: Gained IPv6LL Sep 10 23:55:20.506482 ignition[956]: Ignition 2.21.0 Sep 10 23:55:20.506494 ignition[956]: Stage: fetch-offline Sep 10 23:55:20.515395 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 10 23:55:20.506577 ignition[956]: no configs at "/usr/lib/ignition/base.d" Sep 10 23:55:20.524668 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 10 23:55:20.506582 ignition[956]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 10 23:55:20.506679 ignition[956]: parsed url from cmdline: "" Sep 10 23:55:20.506681 ignition[956]: no config URL provided Sep 10 23:55:20.506684 ignition[956]: reading system config file "/usr/lib/ignition/user.ign" Sep 10 23:55:20.506690 ignition[956]: no config at "/usr/lib/ignition/user.ign" Sep 10 23:55:20.506693 ignition[956]: failed to fetch config: resource requires networking Sep 10 23:55:20.506827 ignition[956]: Ignition finished successfully Sep 10 23:55:20.560009 ignition[964]: Ignition 2.21.0 Sep 10 23:55:20.560016 ignition[964]: Stage: fetch Sep 10 23:55:20.560212 ignition[964]: no configs at "/usr/lib/ignition/base.d" Sep 10 23:55:20.560225 ignition[964]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 10 23:55:20.560305 ignition[964]: parsed url from cmdline: "" Sep 10 23:55:20.560307 ignition[964]: no config URL provided Sep 10 23:55:20.560310 ignition[964]: reading system config file "/usr/lib/ignition/user.ign" Sep 10 23:55:20.560316 ignition[964]: no config at "/usr/lib/ignition/user.ign" Sep 10 23:55:20.560350 ignition[964]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 10 23:55:20.657378 ignition[964]: GET result: OK Sep 10 23:55:20.657564 ignition[964]: config has been read from IMDS userdata Sep 10 23:55:20.657595 ignition[964]: parsing config with SHA512: 17433d3d4884c476301770d03b5dc886d891c63e3fa1ac5317d7c863028aa8f4fb0803eb355e9db959045d05e4e1729492de6e4e3de49167ce2cf68ad552f42f Sep 10 23:55:20.662872 unknown[964]: fetched base config from "system" Sep 10 23:55:20.663045 ignition[964]: fetch: fetch complete Sep 10 23:55:20.662877 unknown[964]: fetched base config from "system" Sep 10 23:55:20.663048 ignition[964]: fetch: fetch passed Sep 10 23:55:20.662881 unknown[964]: fetched user config from "azure" Sep 10 23:55:20.663104 ignition[964]: Ignition finished successfully Sep 10 23:55:20.665870 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 10 23:55:20.674348 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 10 23:55:20.713601 ignition[971]: Ignition 2.21.0 Sep 10 23:55:20.713615 ignition[971]: Stage: kargs Sep 10 23:55:20.719499 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 10 23:55:20.713768 ignition[971]: no configs at "/usr/lib/ignition/base.d" Sep 10 23:55:20.724625 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 10 23:55:20.713776 ignition[971]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 10 23:55:20.714302 ignition[971]: kargs: kargs passed Sep 10 23:55:20.714351 ignition[971]: Ignition finished successfully Sep 10 23:55:20.758582 ignition[977]: Ignition 2.21.0 Sep 10 23:55:20.758594 ignition[977]: Stage: disks Sep 10 23:55:20.758765 ignition[977]: no configs at "/usr/lib/ignition/base.d" Sep 10 23:55:20.763567 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 10 23:55:20.758773 ignition[977]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 10 23:55:20.769303 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 10 23:55:20.759184 ignition[977]: disks: disks passed Sep 10 23:55:20.778595 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 10 23:55:20.759226 ignition[977]: Ignition finished successfully Sep 10 23:55:20.787962 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 10 23:55:20.796497 systemd[1]: Reached target sysinit.target - System Initialization. Sep 10 23:55:20.804502 systemd[1]: Reached target basic.target - Basic System. Sep 10 23:55:20.811892 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 10 23:55:20.937616 systemd-fsck[985]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 10 23:55:20.946166 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 10 23:55:20.953187 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 10 23:55:25.649091 kernel: EXT4-fs (sda9): mounted filesystem fcae628f-5f9a-4539-a638-93fb1399b5d7 r/w with ordered data mode. Quota mode: none. Sep 10 23:55:25.649828 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 10 23:55:25.653813 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 10 23:55:25.721437 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 10 23:55:25.752907 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (999) Sep 10 23:55:25.752950 kernel: BTRFS info (device sda6): first mount of filesystem 538ffae8-60fb-4c82-9100-efc4d2404f73 Sep 10 23:55:25.753737 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 10 23:55:25.774580 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:55:25.774607 kernel: BTRFS info (device sda6): turning on async discard Sep 10 23:55:25.774615 kernel: BTRFS info (device sda6): enabling free space tree Sep 10 23:55:25.787751 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 10 23:55:25.804235 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 10 23:55:25.804279 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 10 23:55:25.821146 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 10 23:55:25.852187 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 10 23:55:25.861130 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 10 23:55:27.237629 coreos-metadata[1017]: Sep 10 23:55:27.237 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 10 23:55:27.244470 coreos-metadata[1017]: Sep 10 23:55:27.244 INFO Fetch successful Sep 10 23:55:27.244470 coreos-metadata[1017]: Sep 10 23:55:27.244 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 10 23:55:27.258129 coreos-metadata[1017]: Sep 10 23:55:27.257 INFO Fetch successful Sep 10 23:55:27.258129 coreos-metadata[1017]: Sep 10 23:55:27.258 INFO wrote hostname ci-4372.1.0-n-093f1ee795 to /sysroot/etc/hostname Sep 10 23:55:27.263232 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 10 23:55:27.792735 initrd-setup-root[1031]: cut: /sysroot/etc/passwd: No such file or directory Sep 10 23:55:27.921605 initrd-setup-root[1038]: cut: /sysroot/etc/group: No such file or directory Sep 10 23:55:27.955038 initrd-setup-root[1045]: cut: /sysroot/etc/shadow: No such file or directory Sep 10 23:55:27.959931 initrd-setup-root[1052]: cut: /sysroot/etc/gshadow: No such file or directory Sep 10 23:55:30.100095 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 10 23:55:30.106133 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 10 23:55:30.122803 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 10 23:55:30.132477 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 10 23:55:30.142115 kernel: BTRFS info (device sda6): last unmount of filesystem 538ffae8-60fb-4c82-9100-efc4d2404f73 Sep 10 23:55:30.162794 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 10 23:55:30.167174 ignition[1121]: INFO : Ignition 2.21.0 Sep 10 23:55:30.167174 ignition[1121]: INFO : Stage: mount Sep 10 23:55:30.167174 ignition[1121]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 10 23:55:30.167174 ignition[1121]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 10 23:55:30.167174 ignition[1121]: INFO : mount: mount passed Sep 10 23:55:30.167174 ignition[1121]: INFO : Ignition finished successfully Sep 10 23:55:30.169955 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 10 23:55:30.178871 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 10 23:55:30.210017 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 10 23:55:30.241080 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1132) Sep 10 23:55:30.241132 kernel: BTRFS info (device sda6): first mount of filesystem 538ffae8-60fb-4c82-9100-efc4d2404f73 Sep 10 23:55:30.245311 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:55:30.253483 kernel: BTRFS info (device sda6): turning on async discard Sep 10 23:55:30.253521 kernel: BTRFS info (device sda6): enabling free space tree Sep 10 23:55:30.255148 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 10 23:55:30.279169 ignition[1149]: INFO : Ignition 2.21.0 Sep 10 23:55:30.283506 ignition[1149]: INFO : Stage: files Sep 10 23:55:30.283506 ignition[1149]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 10 23:55:30.283506 ignition[1149]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 10 23:55:30.283506 ignition[1149]: DEBUG : files: compiled without relabeling support, skipping Sep 10 23:55:30.345685 ignition[1149]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 10 23:55:30.345685 ignition[1149]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 10 23:55:30.461814 ignition[1149]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 10 23:55:30.467096 ignition[1149]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 10 23:55:30.467096 ignition[1149]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 10 23:55:30.462226 unknown[1149]: wrote ssh authorized keys file for user: core Sep 10 23:55:30.562779 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 10 23:55:30.570696 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 10 23:55:30.598543 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 10 23:55:30.607568 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 10 23:55:30.607568 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:55:30.627654 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:55:30.627654 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:55:30.627654 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Sep 10 23:55:31.137088 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 10 23:55:31.376620 ignition[1149]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:55:31.385496 ignition[1149]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 10 23:55:31.385496 ignition[1149]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 10 23:55:31.385496 ignition[1149]: INFO : files: files passed Sep 10 23:55:31.385496 ignition[1149]: INFO : Ignition finished successfully Sep 10 23:55:31.385265 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 10 23:55:31.399202 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 10 23:55:31.418906 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 10 23:55:31.432322 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 10 23:55:31.432407 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 10 23:55:31.691841 initrd-setup-root-after-ignition[1178]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 10 23:55:31.691841 initrd-setup-root-after-ignition[1178]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 10 23:55:31.704547 initrd-setup-root-after-ignition[1182]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 10 23:55:31.699725 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 10 23:55:31.710207 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 10 23:55:31.721511 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 10 23:55:31.771615 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 10 23:55:31.771702 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 10 23:55:31.777395 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 10 23:55:31.782114 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 10 23:55:31.791084 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 10 23:55:31.791838 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 10 23:55:31.835678 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 10 23:55:31.842503 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 10 23:55:31.869176 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 10 23:55:31.874518 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 10 23:55:31.884772 systemd[1]: Stopped target timers.target - Timer Units. Sep 10 23:55:31.893461 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 10 23:55:31.893579 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 10 23:55:31.906495 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 10 23:55:31.912760 systemd[1]: Stopped target basic.target - Basic System. Sep 10 23:55:31.921574 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 10 23:55:31.929949 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 10 23:55:31.938248 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 10 23:55:31.947611 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 10 23:55:31.956265 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 10 23:55:31.964934 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 10 23:55:31.974546 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 10 23:55:31.982374 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 10 23:55:31.991567 systemd[1]: Stopped target swap.target - Swaps. Sep 10 23:55:31.998501 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 10 23:55:31.998618 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 10 23:55:32.010372 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 10 23:55:32.014718 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 10 23:55:32.023334 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 10 23:55:32.026836 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 10 23:55:32.031657 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 10 23:55:32.031759 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 10 23:55:32.044030 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 10 23:55:32.044175 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 10 23:55:32.054909 systemd[1]: ignition-files.service: Deactivated successfully. Sep 10 23:55:32.054995 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 10 23:55:32.115176 ignition[1202]: INFO : Ignition 2.21.0 Sep 10 23:55:32.115176 ignition[1202]: INFO : Stage: umount Sep 10 23:55:32.115176 ignition[1202]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 10 23:55:32.115176 ignition[1202]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 10 23:55:32.115176 ignition[1202]: INFO : umount: umount passed Sep 10 23:55:32.115176 ignition[1202]: INFO : Ignition finished successfully Sep 10 23:55:32.065555 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 10 23:55:32.065632 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 10 23:55:32.076273 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 10 23:55:32.089278 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 10 23:55:32.103020 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 10 23:55:32.103200 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 10 23:55:32.112376 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 10 23:55:32.112474 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 10 23:55:32.122670 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 10 23:55:32.123115 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 10 23:55:32.130179 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 10 23:55:32.130972 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 10 23:55:32.131051 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 10 23:55:32.142514 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 10 23:55:32.142578 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 10 23:55:32.149378 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 10 23:55:32.149420 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 10 23:55:32.156793 systemd[1]: Stopped target network.target - Network. Sep 10 23:55:32.164942 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 10 23:55:32.164988 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 10 23:55:32.174520 systemd[1]: Stopped target paths.target - Path Units. Sep 10 23:55:32.182202 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 10 23:55:32.185539 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 10 23:55:32.190682 systemd[1]: Stopped target slices.target - Slice Units. Sep 10 23:55:32.199986 systemd[1]: Stopped target sockets.target - Socket Units. Sep 10 23:55:32.210172 systemd[1]: iscsid.socket: Deactivated successfully. Sep 10 23:55:32.210226 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 10 23:55:32.218486 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 10 23:55:32.218513 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 10 23:55:32.227462 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 10 23:55:32.227513 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 10 23:55:32.235944 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 10 23:55:32.235972 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 10 23:55:32.245864 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 10 23:55:32.254007 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 10 23:55:32.274656 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 10 23:55:32.274757 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 10 23:55:32.292977 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 10 23:55:32.293253 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 10 23:55:32.295100 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 10 23:55:32.308045 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 10 23:55:32.308309 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 10 23:55:32.308400 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 10 23:55:32.318983 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 10 23:55:32.498060 kernel: hv_netvsc 000d3ac3-3316-000d-3ac3-3316000d3ac3 eth0: Data path switched from VF: enP366s1 Sep 10 23:55:32.319058 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 10 23:55:32.335141 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 10 23:55:32.344169 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 10 23:55:32.344237 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 10 23:55:32.352396 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 10 23:55:32.352456 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 10 23:55:32.360787 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 10 23:55:32.369527 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 10 23:55:32.369583 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 10 23:55:32.378217 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 10 23:55:32.378276 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 10 23:55:32.390239 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 10 23:55:32.390285 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 10 23:55:32.395125 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 10 23:55:32.395168 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 10 23:55:32.404703 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 10 23:55:32.410580 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 10 23:55:32.410643 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 10 23:55:32.439440 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 10 23:55:32.441137 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 10 23:55:32.449582 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 10 23:55:32.449621 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 10 23:55:32.457133 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 10 23:55:32.457163 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 10 23:55:32.465709 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 10 23:55:32.465761 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 10 23:55:32.484985 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 10 23:55:32.485037 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 10 23:55:32.498147 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 10 23:55:32.498210 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 10 23:55:32.512573 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 10 23:55:32.520325 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 10 23:55:32.520400 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 10 23:55:32.536563 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 10 23:55:32.536619 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 10 23:55:32.550480 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 10 23:55:32.550548 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 10 23:55:32.560445 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 10 23:55:32.560501 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 10 23:55:32.565760 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 10 23:55:32.565801 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:32.580638 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 10 23:55:32.580696 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 10 23:55:32.580720 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 10 23:55:32.580744 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 10 23:55:32.581005 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 10 23:55:32.581158 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 10 23:55:32.591531 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 10 23:55:32.591622 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 10 23:55:32.602352 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 10 23:55:32.611688 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 10 23:55:32.828307 systemd-journald[225]: Received SIGTERM from PID 1 (systemd). Sep 10 23:55:32.666488 systemd[1]: Switching root. Sep 10 23:55:32.828377 systemd-journald[225]: Journal stopped Sep 10 23:55:47.672030 kernel: SELinux: policy capability network_peer_controls=1 Sep 10 23:55:47.672050 kernel: SELinux: policy capability open_perms=1 Sep 10 23:55:47.672058 kernel: SELinux: policy capability extended_socket_class=1 Sep 10 23:55:47.672063 kernel: SELinux: policy capability always_check_network=0 Sep 10 23:55:47.672079 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 10 23:55:47.672085 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 10 23:55:47.672091 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 10 23:55:47.672096 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 10 23:55:47.672101 kernel: SELinux: policy capability userspace_initial_context=0 Sep 10 23:55:47.672109 systemd[1]: Successfully loaded SELinux policy in 327.142ms. Sep 10 23:55:47.672116 kernel: audit: type=1403 audit(1757548534.547:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 10 23:55:47.672122 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.896ms. Sep 10 23:55:47.672129 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 10 23:55:47.672135 systemd[1]: Detected virtualization microsoft. Sep 10 23:55:47.672141 systemd[1]: Detected architecture arm64. Sep 10 23:55:47.672148 systemd[1]: Detected first boot. Sep 10 23:55:47.672154 systemd[1]: Hostname set to . Sep 10 23:55:47.672160 systemd[1]: Initializing machine ID from random generator. Sep 10 23:55:47.672166 zram_generator::config[1244]: No configuration found. Sep 10 23:55:47.672172 kernel: NET: Registered PF_VSOCK protocol family Sep 10 23:55:47.672178 systemd[1]: Populated /etc with preset unit settings. Sep 10 23:55:47.672184 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 10 23:55:47.672191 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 10 23:55:47.672196 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 10 23:55:47.672202 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 10 23:55:47.672208 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 10 23:55:47.672215 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 10 23:55:47.672221 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 10 23:55:47.672228 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 10 23:55:47.672235 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 10 23:55:47.672241 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 10 23:55:47.672247 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 10 23:55:47.672253 systemd[1]: Created slice user.slice - User and Session Slice. Sep 10 23:55:47.672259 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 10 23:55:47.672265 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 10 23:55:47.672272 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 10 23:55:47.672277 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 10 23:55:47.672284 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 10 23:55:47.672290 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 10 23:55:47.672296 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 10 23:55:47.672304 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 10 23:55:47.672310 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 10 23:55:47.672317 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 10 23:55:47.672323 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 10 23:55:47.672329 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 10 23:55:47.672336 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 10 23:55:47.672342 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 10 23:55:47.672348 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 10 23:55:47.672354 systemd[1]: Reached target slices.target - Slice Units. Sep 10 23:55:47.672361 systemd[1]: Reached target swap.target - Swaps. Sep 10 23:55:47.672367 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 10 23:55:47.672373 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 10 23:55:47.672380 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 10 23:55:47.672387 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 10 23:55:47.672393 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 10 23:55:47.672399 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 10 23:55:47.672406 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 10 23:55:47.672412 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 10 23:55:47.672419 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 10 23:55:47.672425 systemd[1]: Mounting media.mount - External Media Directory... Sep 10 23:55:47.672431 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 10 23:55:47.672437 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 10 23:55:47.672444 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 10 23:55:47.672450 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). Sep 10 23:55:47.672457 systemd[1]: Reached target machines.target - Containers. Sep 10 23:55:47.672463 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 10 23:55:47.672470 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 10 23:55:47.672477 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 10 23:55:47.672483 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 10 23:55:47.672489 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 10 23:55:47.672497 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 10 23:55:47.672503 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 10 23:55:47.672509 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 10 23:55:47.672515 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 10 23:55:47.672522 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 10 23:55:47.672529 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 10 23:55:47.672535 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 10 23:55:47.672541 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 10 23:55:47.672547 systemd[1]: Stopped systemd-fsck-usr.service. Sep 10 23:55:47.672553 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). Sep 10 23:55:47.672560 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 10 23:55:47.672565 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 10 23:55:47.672571 kernel: fuse: init (API version 7.41) Sep 10 23:55:47.672578 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 10 23:55:47.672584 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 10 23:55:47.672590 kernel: loop: module loaded Sep 10 23:55:47.672596 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 10 23:55:47.672602 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 10 23:55:47.672608 systemd[1]: verity-setup.service: Deactivated successfully. Sep 10 23:55:47.672614 systemd[1]: Stopped verity-setup.service. Sep 10 23:55:47.672620 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 10 23:55:47.672627 kernel: ACPI: bus type drm_connector registered Sep 10 23:55:47.672634 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 10 23:55:47.672640 systemd[1]: Mounted media.mount - External Media Directory. Sep 10 23:55:47.672646 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 10 23:55:47.672652 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 10 23:55:47.672658 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 10 23:55:47.672664 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 10 23:55:47.672670 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 10 23:55:47.672677 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 10 23:55:47.672684 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 10 23:55:47.672690 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 10 23:55:47.672696 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 10 23:55:47.672715 systemd-journald[1324]: Collecting audit messages is disabled. Sep 10 23:55:47.672729 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 10 23:55:47.672737 systemd-journald[1324]: Journal started Sep 10 23:55:47.672750 systemd-journald[1324]: Runtime Journal (/run/log/journal/394f925cbc5943c99a477e1185e4052d) is 8M, max 78.5M, 70.5M free. Sep 10 23:55:46.569048 systemd[1]: Queued start job for default target multi-user.target. Sep 10 23:55:46.573596 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 10 23:55:46.573991 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 10 23:55:46.574296 systemd[1]: systemd-journald.service: Consumed 2.475s CPU time. Sep 10 23:55:47.678701 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 10 23:55:47.688077 systemd[1]: Started systemd-journald.service - Journal Service. Sep 10 23:55:47.693246 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 10 23:55:47.695039 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 10 23:55:47.701324 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 10 23:55:47.703106 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 10 23:55:47.707706 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 10 23:55:47.707864 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 10 23:55:47.713298 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 10 23:55:47.718959 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 10 23:55:47.724495 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 10 23:55:47.737349 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 10 23:55:47.745208 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 10 23:55:47.765191 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 10 23:55:47.773131 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 10 23:55:47.773169 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 10 23:55:47.779356 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 10 23:55:47.787204 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 10 23:55:47.792435 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 10 23:55:47.797002 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 10 23:55:47.803282 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 10 23:55:47.810117 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 10 23:55:47.811346 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 10 23:55:47.817006 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 10 23:55:47.818220 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 10 23:55:47.825188 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 10 23:55:47.835237 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 10 23:55:47.843313 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 10 23:55:47.849804 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 10 23:55:47.857147 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 10 23:55:47.863415 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 10 23:55:47.883826 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 10 23:55:47.889446 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 10 23:55:47.896251 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 10 23:55:47.968200 systemd-journald[1324]: Time spent on flushing to /var/log/journal/394f925cbc5943c99a477e1185e4052d is 14.640ms for 928 entries. Sep 10 23:55:47.968200 systemd-journald[1324]: System Journal (/var/log/journal/394f925cbc5943c99a477e1185e4052d) is 8M, max 2.6G, 2.6G free. Sep 10 23:55:47.998196 systemd-journald[1324]: Received client request to flush runtime journal. Sep 10 23:55:47.999531 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 10 23:55:48.052769 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 10 23:55:48.053422 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 10 23:55:48.065780 kernel: loop0: detected capacity change from 0 to 138376 Sep 10 23:55:48.119440 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 10 23:55:48.227898 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Sep 10 23:55:48.227912 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Sep 10 23:55:48.231422 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 10 23:55:48.239226 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 10 23:55:49.233094 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 10 23:55:49.357091 kernel: loop1: detected capacity change from 0 to 107312 Sep 10 23:55:49.668607 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 10 23:55:49.674966 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 10 23:55:49.698417 systemd-tmpfiles[1402]: ACLs are not supported, ignoring. Sep 10 23:55:49.698726 systemd-tmpfiles[1402]: ACLs are not supported, ignoring. Sep 10 23:55:49.701973 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 10 23:55:50.362132 kernel: loop2: detected capacity change from 0 to 28936 Sep 10 23:55:51.037773 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 10 23:55:51.047229 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 10 23:55:51.073306 systemd-udevd[1407]: Using default interface naming scheme 'v255'. Sep 10 23:55:51.541104 kernel: loop3: detected capacity change from 0 to 211168 Sep 10 23:55:51.574099 kernel: loop4: detected capacity change from 0 to 138376 Sep 10 23:55:51.592085 kernel: loop5: detected capacity change from 0 to 107312 Sep 10 23:55:51.604093 kernel: loop6: detected capacity change from 0 to 28936 Sep 10 23:55:51.618080 kernel: loop7: detected capacity change from 0 to 211168 Sep 10 23:55:51.629762 (sd-merge)[1410]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-azure'. Sep 10 23:55:51.630207 (sd-merge)[1410]: Merged extensions into '/usr'. Sep 10 23:55:51.633925 systemd[1]: Reload requested from client PID 1381 ('systemd-sysext') (unit systemd-sysext.service)... Sep 10 23:55:51.634053 systemd[1]: Reloading... Sep 10 23:55:51.678103 zram_generator::config[1432]: No configuration found. Sep 10 23:55:51.850040 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 10 23:55:51.947904 systemd[1]: Reloading finished in 313 ms. Sep 10 23:55:51.976118 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 10 23:55:51.999258 systemd[1]: Starting ensure-sysext.service... Sep 10 23:55:52.005217 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 10 23:55:52.091088 systemd[1]: Reload requested from client PID 1491 ('systemctl') (unit ensure-sysext.service)... Sep 10 23:55:52.091366 systemd[1]: Reloading... Sep 10 23:55:52.144156 zram_generator::config[1517]: No configuration found. Sep 10 23:55:52.159584 systemd-tmpfiles[1492]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 10 23:55:52.159613 systemd-tmpfiles[1492]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 10 23:55:52.159870 systemd-tmpfiles[1492]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 10 23:55:52.160008 systemd-tmpfiles[1492]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 10 23:55:52.160980 systemd-tmpfiles[1492]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 10 23:55:52.161567 systemd-tmpfiles[1492]: ACLs are not supported, ignoring. Sep 10 23:55:52.161707 systemd-tmpfiles[1492]: ACLs are not supported, ignoring. Sep 10 23:55:52.218088 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 10 23:55:52.249118 systemd-tmpfiles[1492]: Detected autofs mount point /boot during canonicalization of boot. Sep 10 23:55:52.249129 systemd-tmpfiles[1492]: Skipping /boot Sep 10 23:55:52.256539 systemd-tmpfiles[1492]: Detected autofs mount point /boot during canonicalization of boot. Sep 10 23:55:52.256551 systemd-tmpfiles[1492]: Skipping /boot Sep 10 23:55:52.281836 systemd[1]: Reloading finished in 190 ms. Sep 10 23:55:52.291957 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 10 23:55:52.306241 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 10 23:55:52.340663 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 10 23:55:52.352929 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 10 23:55:52.362887 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 10 23:55:52.371358 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 10 23:55:52.382164 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 10 23:55:52.383254 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 10 23:55:52.400317 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 10 23:55:52.409764 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 10 23:55:52.416334 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 10 23:55:52.416443 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). Sep 10 23:55:52.417187 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 10 23:55:52.418102 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 10 23:55:52.424811 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 10 23:55:52.426321 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 10 23:55:52.434555 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 10 23:55:52.434701 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 10 23:55:52.449697 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 10 23:55:52.457839 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 10 23:55:52.462674 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 10 23:55:52.464098 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 10 23:55:52.474912 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 10 23:55:52.482317 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 10 23:55:52.491315 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 10 23:55:52.498835 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 10 23:55:52.498952 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). Sep 10 23:55:52.499062 systemd[1]: Reached target time-set.target - System Time Set. Sep 10 23:55:52.507802 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 10 23:55:52.507987 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 10 23:55:52.515938 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 10 23:55:52.516107 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 10 23:55:52.524102 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 10 23:55:52.524256 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 10 23:55:52.533712 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 10 23:55:52.533852 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 10 23:55:52.544841 systemd[1]: Finished ensure-sysext.service. Sep 10 23:55:52.554368 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 10 23:55:52.554431 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 10 23:55:52.555911 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 10 23:55:52.614556 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 10 23:55:52.653263 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 10 23:55:52.660954 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 10 23:55:52.764534 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 10 23:55:52.866141 systemd-resolved[1581]: Positive Trust Anchors: Sep 10 23:55:52.866156 systemd-resolved[1581]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 10 23:55:52.866178 systemd-resolved[1581]: 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 Sep 10 23:55:52.902109 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#150 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Sep 10 23:55:52.919314 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 10 23:55:52.948162 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:55:52.974163 kernel: mousedev: PS/2 mouse device common for all mice Sep 10 23:55:52.977676 kernel: hv_vmbus: registering driver hv_balloon Sep 10 23:55:52.977723 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 10 23:55:52.974076 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 10 23:55:52.974238 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:52.986567 kernel: hv_balloon: Memory hot add disabled on ARM64 Sep 10 23:55:52.989552 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:55:52.994315 augenrules[1690]: No rules Sep 10 23:55:52.999494 systemd[1]: audit-rules.service: Deactivated successfully. Sep 10 23:55:52.999674 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 10 23:55:53.007024 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 10 23:55:53.009834 systemd-networkd[1619]: lo: Link UP Sep 10 23:55:53.010792 systemd-networkd[1619]: lo: Gained carrier Sep 10 23:55:53.011934 systemd-networkd[1619]: Enumeration completed Sep 10 23:55:53.013032 systemd-networkd[1619]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:55:53.013146 systemd-networkd[1619]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 10 23:55:53.017845 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 10 23:55:53.028709 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 10 23:55:53.039556 kernel: hv_vmbus: registering driver hyperv_fb Sep 10 23:55:53.039660 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 10 23:55:53.048234 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 10 23:55:53.047348 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 10 23:55:53.059508 kernel: Console: switching to colour dummy device 80x25 Sep 10 23:55:53.069287 kernel: Console: switching to colour frame buffer device 128x48 Sep 10 23:55:53.076364 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 10 23:55:53.076591 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:53.084680 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 10 23:55:53.087171 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:55:53.116376 kernel: mlx5_core 016e:00:02.0 enP366s1: Link up Sep 10 23:55:53.117367 kernel: buffer_size[0]=0 is not enough for lossless buffer Sep 10 23:55:53.135319 systemd-resolved[1581]: Using system hostname 'ci-4372.1.0-n-093f1ee795'. Sep 10 23:55:53.149025 kernel: hv_netvsc 000d3ac3-3316-000d-3ac3-3316000d3ac3 eth0: Data path switched to VF: enP366s1 Sep 10 23:55:53.148410 systemd-networkd[1619]: enP366s1: Link UP Sep 10 23:55:53.148545 systemd-networkd[1619]: eth0: Link UP Sep 10 23:55:53.148548 systemd-networkd[1619]: eth0: Gained carrier Sep 10 23:55:53.148570 systemd-networkd[1619]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:55:53.149131 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 10 23:55:53.154577 systemd[1]: Reached target network.target - Network. Sep 10 23:55:53.159034 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 10 23:55:53.159433 systemd-networkd[1619]: enP366s1: Gained carrier Sep 10 23:55:53.176136 systemd-networkd[1619]: eth0: DHCPv4 address 10.200.20.14/24, gateway 10.200.20.1 acquired from 168.63.129.16 Sep 10 23:55:53.190572 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 10 23:55:53.356796 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 10 23:55:53.362608 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 10 23:55:53.467094 kernel: MACsec IEEE 802.1AE Sep 10 23:55:53.498483 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 10 23:55:54.726204 systemd-networkd[1619]: eth0: Gained IPv6LL Sep 10 23:55:54.728402 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 10 23:55:54.734580 systemd[1]: Reached target network-online.target - Network is Online. Sep 10 23:55:55.962942 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:55:57.738624 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 10 23:55:57.745156 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). Sep 10 23:56:07.265755 ldconfig[1376]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 10 23:56:07.278278 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 10 23:56:07.286024 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 10 23:56:07.332977 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 10 23:56:07.339269 systemd[1]: Reached target sysinit.target - System Initialization. Sep 10 23:56:07.346010 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 10 23:56:07.353212 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 10 23:56:07.361203 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 10 23:56:07.368453 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 10 23:56:07.375570 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 10 23:56:07.381472 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 10 23:56:07.381503 systemd[1]: Reached target paths.target - Path Units. Sep 10 23:56:07.385935 systemd[1]: Reached target timers.target - Timer Units. Sep 10 23:56:07.422365 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 10 23:56:07.428736 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 10 23:56:07.435887 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 10 23:56:07.441977 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 10 23:56:07.448318 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 10 23:56:07.455115 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 10 23:56:07.514942 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 10 23:56:07.520530 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 10 23:56:07.525176 systemd[1]: Reached target sockets.target - Socket Units. Sep 10 23:56:07.528747 systemd[1]: Reached target basic.target - Basic System. Sep 10 23:56:07.532346 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 10 23:56:07.532365 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 10 23:56:07.534294 systemd[1]: Starting chronyd.service - NTP client/server... Sep 10 23:56:07.545202 systemd[1]: Starting containerd.service - containerd container runtime... Sep 10 23:56:07.552371 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 10 23:56:07.567321 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 10 23:56:07.574637 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 10 23:56:07.580675 (chronyd)[1779]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 10 23:56:07.592365 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 10 23:56:07.603917 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 10 23:56:07.608504 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). Sep 10 23:56:07.609456 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 10 23:56:07.614584 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 10 23:56:07.615627 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:56:07.622247 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 10 23:56:07.628321 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 10 23:56:07.633447 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 10 23:56:07.642920 jq[1787]: false Sep 10 23:56:07.643326 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 10 23:56:07.656261 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 10 23:56:07.662038 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 10 23:56:07.664985 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 10 23:56:07.665619 systemd[1]: Starting update-engine.service - Update Engine... Sep 10 23:56:07.671582 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 10 23:56:07.682611 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 10 23:56:07.685045 jq[1802]: true Sep 10 23:56:07.689164 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 10 23:56:07.689337 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 10 23:56:07.692647 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 10 23:56:07.692828 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 10 23:56:07.704504 KVP[1789]: KVP starting; pid is:1789 Sep 10 23:56:07.706036 chronyd[1811]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 10 23:56:07.717896 KVP[1789]: KVP LIC Version: 3.1 Sep 10 23:56:07.720590 kernel: hv_utils: KVP IC version 4.0 Sep 10 23:56:07.725105 jq[1809]: true Sep 10 23:56:07.749205 extend-filesystems[1788]: Found /dev/sda6 Sep 10 23:56:07.758949 systemd[1]: motdgen.service: Deactivated successfully. Sep 10 23:56:07.761462 (ntainerd)[1825]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 10 23:56:07.762226 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 10 23:56:07.779553 systemd-logind[1798]: New seat seat0. Sep 10 23:56:07.780670 systemd-logind[1798]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 10 23:56:07.781186 systemd[1]: Started systemd-logind.service - User Login Management. Sep 10 23:56:07.797500 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 10 23:56:07.798509 chronyd[1811]: Timezone right/UTC failed leap second check, ignoring Sep 10 23:56:07.805196 chronyd[1811]: Loaded seccomp filter (level 2) Sep 10 23:56:07.806010 update_engine[1801]: I20250910 23:56:07.805817 1801 main.cc:92] Flatcar Update Engine starting Sep 10 23:56:07.808146 systemd[1]: Started chronyd.service - NTP client/server. Sep 10 23:56:07.823136 extend-filesystems[1788]: Found /dev/sda9 Sep 10 23:56:07.827538 extend-filesystems[1788]: Checking size of /dev/sda9 Sep 10 23:56:07.878417 bash[1842]: Updated "/home/core/.ssh/authorized_keys" Sep 10 23:56:07.879846 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 10 23:56:07.887403 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 10 23:56:07.894241 extend-filesystems[1788]: Old size kept for /dev/sda9 Sep 10 23:56:07.898568 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 10 23:56:07.901640 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 10 23:56:08.145493 sshd_keygen[1854]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 10 23:56:08.167108 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 10 23:56:08.174528 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 10 23:56:08.184838 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 10 23:56:08.196424 systemd[1]: issuegen.service: Deactivated successfully. Sep 10 23:56:08.197313 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 10 23:56:08.209328 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 10 23:56:08.215548 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 10 23:56:08.262789 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 10 23:56:08.270984 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 10 23:56:08.276399 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 10 23:56:08.284958 systemd[1]: Reached target getty.target - Login Prompts. Sep 10 23:56:08.456208 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:56:08.463450 (kubelet)[1946]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 10 23:56:08.468438 dbus-daemon[1782]: [system] SELinux support is enabled Sep 10 23:56:08.468616 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 10 23:56:08.476413 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). Sep 10 23:56:08.477704 dbus-daemon[1782]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 10 23:56:08.476447 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 10 23:56:08.482315 update_engine[1801]: I20250910 23:56:08.482259 1801 update_check_scheduler.cc:74] Next update check in 9m7s Sep 10 23:56:08.482720 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). Sep 10 23:56:08.482743 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 10 23:56:08.488329 systemd[1]: Started update-engine.service - Update Engine. Sep 10 23:56:08.494743 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 10 23:56:08.588121 coreos-metadata[1781]: Sep 10 23:56:08.588 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 10 23:56:08.593044 coreos-metadata[1781]: Sep 10 23:56:08.593 INFO Fetch successful Sep 10 23:56:08.593210 coreos-metadata[1781]: Sep 10 23:56:08.593 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 10 23:56:08.598555 coreos-metadata[1781]: Sep 10 23:56:08.598 INFO Fetch successful Sep 10 23:56:08.598822 coreos-metadata[1781]: Sep 10 23:56:08.598 INFO Fetching http://168.63.129.16/machine/4518442e-6abb-4ab1-9174-85016edb11bb/89c4d0b3%2D18e1%2D45ad%2Db48a%2Dab0d18c2051a.%5Fci%2D4372.1.0%2Dn%2D093f1ee795?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 10 23:56:08.601388 coreos-metadata[1781]: Sep 10 23:56:08.601 INFO Fetch successful Sep 10 23:56:08.601519 coreos-metadata[1781]: Sep 10 23:56:08.601 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 10 23:56:08.613516 coreos-metadata[1781]: Sep 10 23:56:08.612 INFO Fetch successful Sep 10 23:56:08.820295 locksmithd[1949]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 10 23:56:08.862975 kubelet[1946]: E0910 23:56:08.862914 1946 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" Sep 10 23:56:08.864904 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 10 23:56:08.865010 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 10 23:56:08.865729 systemd[1]: kubelet.service: Consumed 556ms CPU time, 256.7M memory peak. Sep 10 23:56:08.998991 containerd[1825]: time="2025-09-10T23:56:08Z" 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 Sep 10 23:56:09.000103 containerd[1825]: time="2025-09-10T23:56:09.000054648Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 10 23:56:09.005104 containerd[1825]: time="2025-09-10T23:56:09.004962152Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.888µs" Sep 10 23:56:09.005104 containerd[1825]: time="2025-09-10T23:56:09.004996184Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 10 23:56:09.005104 containerd[1825]: time="2025-09-10T23:56:09.005010000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 10 23:56:09.005340 containerd[1825]: time="2025-09-10T23:56:09.005319856Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 10 23:56:09.005409 containerd[1825]: time="2025-09-10T23:56:09.005397056Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 10 23:56:09.005464 containerd[1825]: time="2025-09-10T23:56:09.005453768Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 10 23:56:09.005565 containerd[1825]: time="2025-09-10T23:56:09.005550464Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 10 23:56:09.005611 containerd[1825]: time="2025-09-10T23:56:09.005601128Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 10 23:56:09.005898 containerd[1825]: time="2025-09-10T23:56:09.005876104Z" 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 Sep 10 23:56:09.005960 containerd[1825]: time="2025-09-10T23:56:09.005947320Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 10 23:56:09.006003 containerd[1825]: time="2025-09-10T23:56:09.005992496Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 10 23:56:09.006032 containerd[1825]: time="2025-09-10T23:56:09.006024792Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 10 23:56:09.006162 containerd[1825]: time="2025-09-10T23:56:09.006146888Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 10 23:56:09.006456 containerd[1825]: time="2025-09-10T23:56:09.006435208Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 10 23:56:09.006537 containerd[1825]: time="2025-09-10T23:56:09.006525896Z" 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 Sep 10 23:56:09.006571 containerd[1825]: time="2025-09-10T23:56:09.006564040Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 10 23:56:09.006632 containerd[1825]: time="2025-09-10T23:56:09.006621352Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 10 23:56:09.006912 containerd[1825]: time="2025-09-10T23:56:09.006876376Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 10 23:56:09.006983 containerd[1825]: time="2025-09-10T23:56:09.006966816Z" level=info msg="metadata content store policy set" policy=shared Sep 10 23:56:09.019002 containerd[1825]: time="2025-09-10T23:56:09.018958560Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 10 23:56:09.019079 containerd[1825]: time="2025-09-10T23:56:09.019032128Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 10 23:56:09.019079 containerd[1825]: time="2025-09-10T23:56:09.019043128Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 10 23:56:09.019079 containerd[1825]: time="2025-09-10T23:56:09.019050960Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 10 23:56:09.019079 containerd[1825]: time="2025-09-10T23:56:09.019059064Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 10 23:56:09.019079 containerd[1825]: time="2025-09-10T23:56:09.019065912Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 10 23:56:09.019134 containerd[1825]: time="2025-09-10T23:56:09.019085832Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 10 23:56:09.019134 containerd[1825]: time="2025-09-10T23:56:09.019094512Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 10 23:56:09.019134 containerd[1825]: time="2025-09-10T23:56:09.019102760Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 10 23:56:09.019134 containerd[1825]: time="2025-09-10T23:56:09.019108800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 10 23:56:09.019134 containerd[1825]: time="2025-09-10T23:56:09.019114760Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 10 23:56:09.019134 containerd[1825]: time="2025-09-10T23:56:09.019123144Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 10 23:56:09.019287 containerd[1825]: time="2025-09-10T23:56:09.019267048Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 10 23:56:09.019318 containerd[1825]: time="2025-09-10T23:56:09.019287760Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 10 23:56:09.019318 containerd[1825]: time="2025-09-10T23:56:09.019299384Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019397480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019411216Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019424800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019436160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019445736Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019456768Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019468752Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019480424Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019550112Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019630200Z" level=info msg="Start snapshots syncer" Sep 10 23:56:09.020283 containerd[1825]: time="2025-09-10T23:56:09.019667656Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 10 23:56:09.020452 containerd[1825]: time="2025-09-10T23:56:09.019908576Z" 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\"}" Sep 10 23:56:09.020452 containerd[1825]: time="2025-09-10T23:56:09.019948840Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 10 23:56:09.020721 containerd[1825]: time="2025-09-10T23:56:09.020600312Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 10 23:56:09.020854 containerd[1825]: time="2025-09-10T23:56:09.020836464Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 10 23:56:09.020880 containerd[1825]: time="2025-09-10T23:56:09.020861800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 10 23:56:09.020880 containerd[1825]: time="2025-09-10T23:56:09.020869856Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 10 23:56:09.020880 containerd[1825]: time="2025-09-10T23:56:09.020876712Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 10 23:56:09.020919 containerd[1825]: time="2025-09-10T23:56:09.020885424Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 10 23:56:09.020919 containerd[1825]: time="2025-09-10T23:56:09.020892600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 10 23:56:09.020919 containerd[1825]: time="2025-09-10T23:56:09.020900168Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 10 23:56:09.020952 containerd[1825]: time="2025-09-10T23:56:09.020921352Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 10 23:56:09.020952 containerd[1825]: time="2025-09-10T23:56:09.020929232Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 10 23:56:09.020952 containerd[1825]: time="2025-09-10T23:56:09.020936040Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 10 23:56:09.020989 containerd[1825]: time="2025-09-10T23:56:09.020964080Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 10 23:56:09.020989 containerd[1825]: time="2025-09-10T23:56:09.020980368Z" 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 Sep 10 23:56:09.020989 containerd[1825]: time="2025-09-10T23:56:09.020986680Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 10 23:56:09.021024 containerd[1825]: time="2025-09-10T23:56:09.020992392Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 10 23:56:09.021024 containerd[1825]: time="2025-09-10T23:56:09.020997128Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 10 23:56:09.021024 containerd[1825]: time="2025-09-10T23:56:09.021002984Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 10 23:56:09.021024 containerd[1825]: time="2025-09-10T23:56:09.021010224Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 10 23:56:09.021024 containerd[1825]: time="2025-09-10T23:56:09.021022040Z" level=info msg="runtime interface created" Sep 10 23:56:09.021024 containerd[1825]: time="2025-09-10T23:56:09.021025456Z" level=info msg="created NRI interface" Sep 10 23:56:09.021110 containerd[1825]: time="2025-09-10T23:56:09.021031304Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 10 23:56:09.021110 containerd[1825]: time="2025-09-10T23:56:09.021042336Z" level=info msg="Connect containerd service" Sep 10 23:56:09.021110 containerd[1825]: time="2025-09-10T23:56:09.021062592Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 10 23:56:09.021696 containerd[1825]: time="2025-09-10T23:56:09.021667448Z" 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" Sep 10 23:56:09.435813 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 10 23:56:09.441384 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.739851512Z" level=info msg="Start subscribing containerd event" Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.739922216Z" level=info msg="Start recovering state" Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740014504Z" level=info msg="Start event monitor" Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740013328Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740029384Z" level=info msg="Start cni network conf syncer for default" Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740046128Z" level=info msg="Start streaming server" Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740058360Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740061160Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740063264Z" level=info msg="runtime interface starting up..." Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740122496Z" level=info msg="starting plugins..." Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740139424Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 10 23:56:10.740258 containerd[1825]: time="2025-09-10T23:56:10.740233312Z" level=info msg="containerd successfully booted in 1.741574s" Sep 10 23:56:10.740396 systemd[1]: Started containerd.service - containerd container runtime. Sep 10 23:56:10.746316 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 10 23:56:10.755368 systemd[1]: Startup finished in 1.633s (kernel) + 22.117s (initrd) + 36.533s (userspace) = 1min 285ms. Sep 10 23:56:12.041675 login[1940]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 10 23:56:12.070514 login[1939]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:56:12.075867 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 10 23:56:12.077098 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 10 23:56:12.082625 systemd-logind[1798]: New session 2 of user core. Sep 10 23:56:12.153328 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 10 23:56:12.156391 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 10 23:56:12.198528 (systemd)[1993]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 10 23:56:12.201095 systemd-logind[1798]: New session c1 of user core. Sep 10 23:56:12.423132 waagent[1936]: 2025-09-10T23:56:12.423039Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 10 23:56:12.427945 waagent[1936]: 2025-09-10T23:56:12.427885Z INFO Daemon Daemon OS: flatcar 4372.1.0 Sep 10 23:56:12.431673 waagent[1936]: 2025-09-10T23:56:12.431634Z INFO Daemon Daemon Python: 3.11.12 Sep 10 23:56:12.435352 waagent[1936]: 2025-09-10T23:56:12.435312Z INFO Daemon Daemon Run daemon Sep 10 23:56:12.438524 waagent[1936]: 2025-09-10T23:56:12.438487Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.1.0' Sep 10 23:56:12.446034 waagent[1936]: 2025-09-10T23:56:12.445799Z INFO Daemon Daemon Using waagent for provisioning Sep 10 23:56:12.451602 waagent[1936]: 2025-09-10T23:56:12.451559Z INFO Daemon Daemon Activate resource disk Sep 10 23:56:12.455953 waagent[1936]: 2025-09-10T23:56:12.455918Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 10 23:56:12.465934 waagent[1936]: 2025-09-10T23:56:12.465888Z INFO Daemon Daemon Found device: None Sep 10 23:56:12.470266 waagent[1936]: 2025-09-10T23:56:12.470233Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Sep 10 23:56:12.477471 waagent[1936]: 2025-09-10T23:56:12.477433Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Sep 10 23:56:12.487461 waagent[1936]: 2025-09-10T23:56:12.487418Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 10 23:56:12.492200 waagent[1936]: 2025-09-10T23:56:12.492169Z INFO Daemon Daemon Running default provisioning handler Sep 10 23:56:12.502385 waagent[1936]: 2025-09-10T23:56:12.502329Z 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. Sep 10 23:56:12.512544 waagent[1936]: 2025-09-10T23:56:12.512492Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 10 23:56:12.519380 waagent[1936]: 2025-09-10T23:56:12.519342Z INFO Daemon Daemon cloud-init is enabled: False Sep 10 23:56:12.522890 waagent[1936]: 2025-09-10T23:56:12.522865Z INFO Daemon Daemon Copying ovf-env.xml Sep 10 23:56:12.748953 systemd[1993]: Queued start job for default target default.target. Sep 10 23:56:12.774309 systemd[1993]: Created slice app.slice - User Application Slice. Sep 10 23:56:12.774492 systemd[1993]: Reached target paths.target - Paths. Sep 10 23:56:12.774603 systemd[1993]: Reached target timers.target - Timers. Sep 10 23:56:12.776002 systemd[1993]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 10 23:56:12.780866 waagent[1936]: 2025-09-10T23:56:12.780278Z INFO Daemon Daemon Successfully mounted dvd Sep 10 23:56:12.789265 systemd[1993]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 10 23:56:12.789452 systemd[1993]: Reached target sockets.target - Sockets. Sep 10 23:56:12.789567 systemd[1993]: Reached target basic.target - Basic System. Sep 10 23:56:12.789719 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 10 23:56:12.789818 systemd[1993]: Reached target default.target - Main User Target. Sep 10 23:56:12.790812 systemd[1993]: Startup finished in 584ms. Sep 10 23:56:12.797267 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 10 23:56:12.828405 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 10 23:56:12.829467 waagent[1936]: 2025-09-10T23:56:12.829401Z INFO Daemon Daemon Detect protocol endpoint Sep 10 23:56:12.834357 waagent[1936]: 2025-09-10T23:56:12.834300Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 10 23:56:12.839176 waagent[1936]: 2025-09-10T23:56:12.839129Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 10 23:56:12.845377 waagent[1936]: 2025-09-10T23:56:12.845338Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 10 23:56:12.850151 waagent[1936]: 2025-09-10T23:56:12.850062Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 10 23:56:12.855054 waagent[1936]: 2025-09-10T23:56:12.855019Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 10 23:56:12.979901 waagent[1936]: 2025-09-10T23:56:12.979853Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 10 23:56:12.985804 waagent[1936]: 2025-09-10T23:56:12.985777Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 10 23:56:12.990457 waagent[1936]: 2025-09-10T23:56:12.990423Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 10 23:56:13.042868 login[1940]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:56:13.046541 systemd-logind[1798]: New session 1 of user core. Sep 10 23:56:13.056213 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 10 23:56:13.241112 waagent[1936]: 2025-09-10T23:56:13.240381Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 10 23:56:13.246238 waagent[1936]: 2025-09-10T23:56:13.246137Z INFO Daemon Daemon Forcing an update of the goal state. Sep 10 23:56:13.256351 waagent[1936]: 2025-09-10T23:56:13.256297Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 10 23:56:13.322867 waagent[1936]: 2025-09-10T23:56:13.322508Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 10 23:56:13.327187 waagent[1936]: 2025-09-10T23:56:13.327148Z INFO Daemon Sep 10 23:56:13.329253 waagent[1936]: 2025-09-10T23:56:13.329215Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: b1d66e6c-1abf-46cf-93f8-a510bae4ae7c eTag: 9827136942933590542 source: Fabric] Sep 10 23:56:13.338456 waagent[1936]: 2025-09-10T23:56:13.338360Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 10 23:56:13.345847 waagent[1936]: 2025-09-10T23:56:13.345104Z INFO Daemon Sep 10 23:56:13.348631 waagent[1936]: 2025-09-10T23:56:13.348582Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 10 23:56:13.358587 waagent[1936]: 2025-09-10T23:56:13.358556Z INFO Daemon Daemon Downloading artifacts profile blob Sep 10 23:56:13.426364 waagent[1936]: 2025-09-10T23:56:13.426288Z INFO Daemon Downloaded certificate {'thumbprint': '4E5CABC8E542AC1C1E61E0AA0992181FF4714B78', 'hasPrivateKey': True} Sep 10 23:56:13.434976 waagent[1936]: 2025-09-10T23:56:13.434931Z INFO Daemon Fetch goal state completed Sep 10 23:56:13.446193 waagent[1936]: 2025-09-10T23:56:13.446161Z INFO Daemon Daemon Starting provisioning Sep 10 23:56:13.450960 waagent[1936]: 2025-09-10T23:56:13.450927Z INFO Daemon Daemon Handle ovf-env.xml. Sep 10 23:56:13.454702 waagent[1936]: 2025-09-10T23:56:13.454680Z INFO Daemon Daemon Set hostname [ci-4372.1.0-n-093f1ee795] Sep 10 23:56:13.521805 waagent[1936]: 2025-09-10T23:56:13.521745Z INFO Daemon Daemon Publish hostname [ci-4372.1.0-n-093f1ee795] Sep 10 23:56:13.527193 waagent[1936]: 2025-09-10T23:56:13.527145Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 10 23:56:13.532771 waagent[1936]: 2025-09-10T23:56:13.532734Z INFO Daemon Daemon Primary interface is [eth0] Sep 10 23:56:13.572200 systemd-networkd[1619]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:56:13.572462 systemd-networkd[1619]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 10 23:56:13.572496 systemd-networkd[1619]: eth0: DHCP lease lost Sep 10 23:56:13.573022 waagent[1936]: 2025-09-10T23:56:13.572927Z INFO Daemon Daemon Create user account if not exists Sep 10 23:56:13.577975 waagent[1936]: 2025-09-10T23:56:13.577926Z INFO Daemon Daemon User core already exists, skip useradd Sep 10 23:56:13.582344 waagent[1936]: 2025-09-10T23:56:13.582308Z INFO Daemon Daemon Configure sudoer Sep 10 23:56:13.592901 waagent[1936]: 2025-09-10T23:56:13.592835Z INFO Daemon Daemon Configure sshd Sep 10 23:56:13.601318 waagent[1936]: 2025-09-10T23:56:13.601257Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 10 23:56:13.612559 waagent[1936]: 2025-09-10T23:56:13.612510Z INFO Daemon Daemon Deploy ssh public key. Sep 10 23:56:13.617163 systemd-networkd[1619]: eth0: DHCPv4 address 10.200.20.14/24, gateway 10.200.20.1 acquired from 168.63.129.16 Sep 10 23:56:14.861480 waagent[1936]: 2025-09-10T23:56:14.857475Z INFO Daemon Daemon Provisioning complete Sep 10 23:56:14.872101 waagent[1936]: 2025-09-10T23:56:14.872043Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 10 23:56:14.877522 waagent[1936]: 2025-09-10T23:56:14.877482Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 10 23:56:14.885293 waagent[1936]: 2025-09-10T23:56:14.885262Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 10 23:56:14.984888 waagent[2041]: 2025-09-10T23:56:14.984810Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 10 23:56:14.985709 waagent[2041]: 2025-09-10T23:56:14.985337Z INFO ExtHandler ExtHandler OS: flatcar 4372.1.0 Sep 10 23:56:14.985709 waagent[2041]: 2025-09-10T23:56:14.985397Z INFO ExtHandler ExtHandler Python: 3.11.12 Sep 10 23:56:14.985709 waagent[2041]: 2025-09-10T23:56:14.985435Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Sep 10 23:56:15.120618 waagent[2041]: 2025-09-10T23:56:15.120483Z INFO ExtHandler ExtHandler Distro: flatcar-4372.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 10 23:56:15.120932 waagent[2041]: 2025-09-10T23:56:15.120899Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 10 23:56:15.121065 waagent[2041]: 2025-09-10T23:56:15.121042Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 10 23:56:15.127639 waagent[2041]: 2025-09-10T23:56:15.127590Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 10 23:56:15.134111 waagent[2041]: 2025-09-10T23:56:15.133940Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 10 23:56:15.134435 waagent[2041]: 2025-09-10T23:56:15.134399Z INFO ExtHandler Sep 10 23:56:15.134484 waagent[2041]: 2025-09-10T23:56:15.134467Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 5fdde7a5-b162-44e3-9c01-b18b2df382a0 eTag: 9827136942933590542 source: Fabric] Sep 10 23:56:15.134698 waagent[2041]: 2025-09-10T23:56:15.134675Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 10 23:56:15.135098 waagent[2041]: 2025-09-10T23:56:15.135053Z INFO ExtHandler Sep 10 23:56:15.135139 waagent[2041]: 2025-09-10T23:56:15.135123Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 10 23:56:15.139280 waagent[2041]: 2025-09-10T23:56:15.139252Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 10 23:56:15.198680 waagent[2041]: 2025-09-10T23:56:15.198601Z INFO ExtHandler Downloaded certificate {'thumbprint': '4E5CABC8E542AC1C1E61E0AA0992181FF4714B78', 'hasPrivateKey': True} Sep 10 23:56:15.199117 waagent[2041]: 2025-09-10T23:56:15.199051Z INFO ExtHandler Fetch goal state completed Sep 10 23:56:15.212050 waagent[2041]: 2025-09-10T23:56:15.211991Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Sep 10 23:56:15.215570 waagent[2041]: 2025-09-10T23:56:15.215519Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2041 Sep 10 23:56:15.215680 waagent[2041]: 2025-09-10T23:56:15.215654Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 10 23:56:15.215925 waagent[2041]: 2025-09-10T23:56:15.215898Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 10 23:56:15.216987 waagent[2041]: 2025-09-10T23:56:15.216950Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 10 23:56:15.217342 waagent[2041]: 2025-09-10T23:56:15.217310Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 10 23:56:15.217452 waagent[2041]: 2025-09-10T23:56:15.217431Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 10 23:56:15.217866 waagent[2041]: 2025-09-10T23:56:15.217836Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 10 23:56:15.408095 waagent[2041]: 2025-09-10T23:56:15.407990Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 10 23:56:15.408228 waagent[2041]: 2025-09-10T23:56:15.408200Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 10 23:56:15.412712 waagent[2041]: 2025-09-10T23:56:15.412683Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 10 23:56:15.425309 systemd[1]: Reload requested from client PID 2056 ('systemctl') (unit waagent.service)... Sep 10 23:56:15.425600 systemd[1]: Reloading... Sep 10 23:56:15.496179 zram_generator::config[2094]: No configuration found. Sep 10 23:56:15.566905 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 10 23:56:15.648840 systemd[1]: Reloading finished in 222 ms. Sep 10 23:56:15.662769 waagent[2041]: 2025-09-10T23:56:15.659922Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 10 23:56:15.662769 waagent[2041]: 2025-09-10T23:56:15.660087Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 10 23:56:16.632002 waagent[2041]: 2025-09-10T23:56:16.631232Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 10 23:56:16.632002 waagent[2041]: 2025-09-10T23:56:16.631538Z 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] Sep 10 23:56:16.632353 waagent[2041]: 2025-09-10T23:56:16.632319Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 10 23:56:16.632442 waagent[2041]: 2025-09-10T23:56:16.632399Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 10 23:56:16.632492 waagent[2041]: 2025-09-10T23:56:16.632460Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 10 23:56:16.632780 waagent[2041]: 2025-09-10T23:56:16.632751Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 10 23:56:16.633109 waagent[2041]: 2025-09-10T23:56:16.633062Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 10 23:56:16.633444 waagent[2041]: 2025-09-10T23:56:16.633410Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 10 23:56:16.633565 waagent[2041]: 2025-09-10T23:56:16.633491Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 10 23:56:16.633565 waagent[2041]: 2025-09-10T23:56:16.633517Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 10 23:56:16.633913 waagent[2041]: 2025-09-10T23:56:16.633882Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 10 23:56:16.633967 waagent[2041]: 2025-09-10T23:56:16.633922Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 10 23:56:16.633967 waagent[2041]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 10 23:56:16.633967 waagent[2041]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Sep 10 23:56:16.633967 waagent[2041]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 10 23:56:16.633967 waagent[2041]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 10 23:56:16.633967 waagent[2041]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 10 23:56:16.633967 waagent[2041]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 10 23:56:16.634197 waagent[2041]: 2025-09-10T23:56:16.634161Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 10 23:56:16.634281 waagent[2041]: 2025-09-10T23:56:16.634251Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 10 23:56:16.634403 waagent[2041]: 2025-09-10T23:56:16.634365Z INFO EnvHandler ExtHandler Configure routes Sep 10 23:56:16.634433 waagent[2041]: 2025-09-10T23:56:16.634408Z INFO EnvHandler ExtHandler Gateway:None Sep 10 23:56:16.634450 waagent[2041]: 2025-09-10T23:56:16.634432Z INFO EnvHandler ExtHandler Routes:None Sep 10 23:56:16.634811 waagent[2041]: 2025-09-10T23:56:16.634772Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 10 23:56:16.641997 waagent[2041]: 2025-09-10T23:56:16.640695Z INFO ExtHandler ExtHandler Sep 10 23:56:16.641997 waagent[2041]: 2025-09-10T23:56:16.640768Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 38d189b0-2f33-456d-b4c0-9c83dac9b3a9 correlation a305bcc0-3ba0-4a1d-8807-c2d16afe6d06 created: 2025-09-10T23:53:48.611763Z] Sep 10 23:56:16.641997 waagent[2041]: 2025-09-10T23:56:16.641028Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 10 23:56:16.641997 waagent[2041]: 2025-09-10T23:56:16.641451Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Sep 10 23:56:16.704850 waagent[2041]: 2025-09-10T23:56:16.704789Z 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 Sep 10 23:56:16.704850 waagent[2041]: Try `iptables -h' or 'iptables --help' for more information.) Sep 10 23:56:16.705436 waagent[2041]: 2025-09-10T23:56:16.705405Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 0DC2190F-8886-49DC-901A-A16E589AE9D0;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 10 23:56:16.795487 waagent[2041]: 2025-09-10T23:56:16.795413Z INFO MonitorHandler ExtHandler Network interfaces: Sep 10 23:56:16.795487 waagent[2041]: Executing ['ip', '-a', '-o', 'link']: Sep 10 23:56:16.795487 waagent[2041]: 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 Sep 10 23:56:16.795487 waagent[2041]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c3:33:16 brd ff:ff:ff:ff:ff:ff Sep 10 23:56:16.795487 waagent[2041]: 3: enP366s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c3:33:16 brd ff:ff:ff:ff:ff:ff\ altname enP366p0s2 Sep 10 23:56:16.795487 waagent[2041]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 10 23:56:16.795487 waagent[2041]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 10 23:56:16.795487 waagent[2041]: 2: eth0 inet 10.200.20.14/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 10 23:56:16.795487 waagent[2041]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 10 23:56:16.795487 waagent[2041]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 10 23:56:16.795487 waagent[2041]: 2: eth0 inet6 fe80::20d:3aff:fec3:3316/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 10 23:56:16.987095 waagent[2041]: 2025-09-10T23:56:16.986983Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 10 23:56:16.987095 waagent[2041]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 10 23:56:16.987095 waagent[2041]: pkts bytes target prot opt in out source destination Sep 10 23:56:16.987095 waagent[2041]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 10 23:56:16.987095 waagent[2041]: pkts bytes target prot opt in out source destination Sep 10 23:56:16.987095 waagent[2041]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 10 23:56:16.987095 waagent[2041]: pkts bytes target prot opt in out source destination Sep 10 23:56:16.987095 waagent[2041]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 10 23:56:16.987095 waagent[2041]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 10 23:56:16.987095 waagent[2041]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 10 23:56:16.990020 waagent[2041]: 2025-09-10T23:56:16.989719Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 10 23:56:16.990020 waagent[2041]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 10 23:56:16.990020 waagent[2041]: pkts bytes target prot opt in out source destination Sep 10 23:56:16.990020 waagent[2041]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 10 23:56:16.990020 waagent[2041]: pkts bytes target prot opt in out source destination Sep 10 23:56:16.990020 waagent[2041]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 10 23:56:16.990020 waagent[2041]: pkts bytes target prot opt in out source destination Sep 10 23:56:16.990020 waagent[2041]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 10 23:56:16.990020 waagent[2041]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 10 23:56:16.990020 waagent[2041]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 10 23:56:16.990020 waagent[2041]: 2025-09-10T23:56:16.989935Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 10 23:56:18.922289 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Sep 10 23:56:18.923686 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:56:19.060655 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:56:19.066340 (kubelet)[2190]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 10 23:56:19.113348 kubelet[2190]: E0910 23:56:19.113273 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" Sep 10 23:56:19.116343 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 10 23:56:19.116595 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 10 23:56:19.117192 systemd[1]: kubelet.service: Consumed 114ms CPU time, 105M memory peak. Sep 10 23:56:29.171037 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Sep 10 23:56:29.172946 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:56:29.395357 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:56:29.398031 (kubelet)[2205]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 10 23:56:29.427738 kubelet[2205]: E0910 23:56:29.427606 2205 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" Sep 10 23:56:29.430136 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 10 23:56:29.430368 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 10 23:56:29.430874 systemd[1]: kubelet.service: Consumed 108ms CPU time, 107.4M memory peak. Sep 10 23:56:31.620729 chronyd[1811]: Selected source PHC0 Sep 10 23:56:35.723671 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 10 23:56:35.725728 systemd[1]: Started sshd@0-10.200.20.14:22-10.200.16.10:34434.service - OpenSSH per-connection server daemon (10.200.16.10:34434). Sep 10 23:56:36.386015 sshd[2213]: Accepted publickey for core from 10.200.16.10 port 34434 ssh2: RSA SHA256:B+r04UVeIaDg8udWCHwwlmZ73a9CSNnn6tH6+l72Tuo Sep 10 23:56:36.387119 sshd-session[2213]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:56:36.391324 systemd-logind[1798]: New session 3 of user core. Sep 10 23:56:36.399215 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 10 23:56:36.786504 systemd[1]: Started sshd@1-10.200.20.14:22-10.200.16.10:34450.service - OpenSSH per-connection server daemon (10.200.16.10:34450). Sep 10 23:56:37.244397 sshd[2218]: Accepted publickey for core from 10.200.16.10 port 34450 ssh2: RSA SHA256:B+r04UVeIaDg8udWCHwwlmZ73a9CSNnn6tH6+l72Tuo Sep 10 23:56:37.245558 sshd-session[2218]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:56:37.249188 systemd-logind[1798]: New session 4 of user core. Sep 10 23:56:37.260424 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 10 23:56:37.568998 sshd[2220]: Connection closed by 10.200.16.10 port 34450 Sep 10 23:56:37.569750 sshd-session[2218]: pam_unix(sshd:session): session closed for user core Sep 10 23:56:37.572878 systemd-logind[1798]: Session 4 logged out. Waiting for processes to exit. Sep 10 23:56:37.573151 systemd[1]: sshd@1-10.200.20.14:22-10.200.16.10:34450.service: Deactivated successfully. Sep 10 23:56:37.574425 systemd[1]: session-4.scope: Deactivated successfully. Sep 10 23:56:37.575788 systemd-logind[1798]: Removed session 4. Sep 10 23:56:37.644322 systemd[1]: Started sshd@2-10.200.20.14:22-10.200.16.10:34452.service - OpenSSH per-connection server daemon (10.200.16.10:34452). Sep 10 23:56:38.062299 sshd[2226]: Accepted publickey for core from 10.200.16.10 port 34452 ssh2: RSA SHA256:B+r04UVeIaDg8udWCHwwlmZ73a9CSNnn6tH6+l72Tuo Sep 10 23:56:38.063421 sshd-session[2226]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:56:38.068260 systemd-logind[1798]: New session 5 of user core. Sep 10 23:56:38.074267 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 10 23:56:38.373942 sshd[2228]: Connection closed by 10.200.16.10 port 34452 Sep 10 23:56:38.373406 sshd-session[2226]: pam_unix(sshd:session): session closed for user core Sep 10 23:56:38.376371 systemd-logind[1798]: Session 5 logged out. Waiting for processes to exit. Sep 10 23:56:38.376502 systemd[1]: sshd@2-10.200.20.14:22-10.200.16.10:34452.service: Deactivated successfully. Sep 10 23:56:38.377762 systemd[1]: session-5.scope: Deactivated successfully. Sep 10 23:56:38.379628 systemd-logind[1798]: Removed session 5. Sep 10 23:56:38.451739 systemd[1]: Started sshd@3-10.200.20.14:22-10.200.16.10:34458.service - OpenSSH per-connection server daemon (10.200.16.10:34458). Sep 10 23:56:38.871032 sshd[2234]: Accepted publickey for core from 10.200.16.10 port 34458 ssh2: RSA SHA256:B+r04UVeIaDg8udWCHwwlmZ73a9CSNnn6tH6+l72Tuo Sep 10 23:56:38.872153 sshd-session[2234]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:56:38.875813 systemd-logind[1798]: New session 6 of user core. Sep 10 23:56:38.885233 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 10 23:56:39.190939 sshd[2236]: Connection closed by 10.200.16.10 port 34458 Sep 10 23:56:39.191358 sshd-session[2234]: pam_unix(sshd:session): session closed for user core Sep 10 23:56:39.194683 systemd[1]: sshd@3-10.200.20.14:22-10.200.16.10:34458.service: Deactivated successfully. Sep 10 23:56:39.195925 systemd[1]: session-6.scope: Deactivated successfully. Sep 10 23:56:39.196872 systemd-logind[1798]: Session 6 logged out. Waiting for processes to exit. Sep 10 23:56:39.197951 systemd-logind[1798]: Removed session 6. Sep 10 23:56:39.269693 systemd[1]: Started sshd@4-10.200.20.14:22-10.200.16.10:34468.service - OpenSSH per-connection server daemon (10.200.16.10:34468). Sep 10 23:56:39.618942 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 3. Sep 10 23:56:39.620744 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:56:39.695627 sshd[2242]: Accepted publickey for core from 10.200.16.10 port 34468 ssh2: RSA SHA256:B+r04UVeIaDg8udWCHwwlmZ73a9CSNnn6tH6+l72Tuo Sep 10 23:56:39.696724 sshd-session[2242]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:56:39.700768 systemd-logind[1798]: New session 7 of user core. Sep 10 23:56:39.708237 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 10 23:56:40.138205 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:56:40.145605 (kubelet)[2253]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 10 23:56:40.174574 kubelet[2253]: E0910 23:56:40.174501 2253 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" Sep 10 23:56:40.176752 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 10 23:56:40.176865 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 10 23:56:40.177191 systemd[1]: kubelet.service: Consumed 109ms CPU time, 105.9M memory peak. Sep 10 23:56:40.577213 sudo[2248]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 10 23:56:40.577450 sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 10 23:56:40.969011 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:56:40.969467 systemd[1]: kubelet.service: Consumed 109ms CPU time, 105.9M memory peak. Sep 10 23:56:40.971916 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:56:40.994345 systemd[1]: Reload requested from client PID 2295 ('systemctl') (unit session-7.scope)... Sep 10 23:56:40.994479 systemd[1]: Reloading... Sep 10 23:56:41.085407 zram_generator::config[2349]: No configuration found. Sep 10 23:56:41.114349 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Sep 10 23:56:41.158412 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 10 23:56:41.243153 systemd[1]: Reloading finished in 248 ms. Sep 10 23:56:41.285782 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 10 23:56:41.286007 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 10 23:56:41.286418 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:56:41.286527 systemd[1]: kubelet.service: Consumed 65ms CPU time, 89.4M memory peak. Sep 10 23:56:41.292335 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:56:41.508654 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:56:41.515729 (kubelet)[2404]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 10 23:56:41.572919 kubelet[2404]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 10 23:56:41.572919 kubelet[2404]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 10 23:56:41.572919 kubelet[2404]: 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. Sep 10 23:56:41.572919 kubelet[2404]: I0910 23:56:41.572270 2404 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 10 23:56:42.543423 kubelet[2404]: I0910 23:56:42.543387 2404 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Sep 10 23:56:42.543645 kubelet[2404]: I0910 23:56:42.543636 2404 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 10 23:56:42.543901 kubelet[2404]: I0910 23:56:42.543890 2404 server.go:956] "Client rotation is on, will bootstrap in background" Sep 10 23:56:42.565344 kubelet[2404]: I0910 23:56:42.565301 2404 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 10 23:56:42.576807 kubelet[2404]: I0910 23:56:42.576768 2404 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 10 23:56:42.579450 kubelet[2404]: I0910 23:56:42.579428 2404 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 10 23:56:42.580449 kubelet[2404]: I0910 23:56:42.580410 2404 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 10 23:56:42.580587 kubelet[2404]: I0910 23:56:42.580450 2404 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.20.14","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 10 23:56:42.580689 kubelet[2404]: I0910 23:56:42.580595 2404 topology_manager.go:138] "Creating topology manager with none policy" Sep 10 23:56:42.580689 kubelet[2404]: I0910 23:56:42.580604 2404 container_manager_linux.go:303] "Creating device plugin manager" Sep 10 23:56:42.580753 kubelet[2404]: I0910 23:56:42.580736 2404 state_mem.go:36] "Initialized new in-memory state store" Sep 10 23:56:42.583301 kubelet[2404]: I0910 23:56:42.583279 2404 kubelet.go:480] "Attempting to sync node with API server" Sep 10 23:56:42.583301 kubelet[2404]: I0910 23:56:42.583306 2404 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 10 23:56:42.583413 kubelet[2404]: I0910 23:56:42.583400 2404 kubelet.go:386] "Adding apiserver pod source" Sep 10 23:56:42.583429 kubelet[2404]: I0910 23:56:42.583414 2404 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 10 23:56:42.584112 kubelet[2404]: E0910 23:56:42.584062 2404 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:42.584149 kubelet[2404]: E0910 23:56:42.584134 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:42.586989 kubelet[2404]: I0910 23:56:42.586757 2404 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Sep 10 23:56:42.587313 kubelet[2404]: I0910 23:56:42.587292 2404 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 10 23:56:42.587394 kubelet[2404]: W0910 23:56:42.587370 2404 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 10 23:56:42.589948 kubelet[2404]: I0910 23:56:42.589930 2404 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 10 23:56:42.590032 kubelet[2404]: I0910 23:56:42.590020 2404 server.go:1289] "Started kubelet" Sep 10 23:56:42.590122 kubelet[2404]: I0910 23:56:42.590095 2404 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Sep 10 23:56:42.590741 kubelet[2404]: I0910 23:56:42.590724 2404 server.go:317] "Adding debug handlers to kubelet server" Sep 10 23:56:42.593616 kubelet[2404]: I0910 23:56:42.593560 2404 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 10 23:56:42.593847 kubelet[2404]: I0910 23:56:42.593828 2404 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 10 23:56:42.596989 kubelet[2404]: I0910 23:56:42.596885 2404 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 10 23:56:42.599183 kubelet[2404]: E0910 23:56:42.598186 2404 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.200.20.14.1864112464dbe278 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.200.20.14,UID:10.200.20.14,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.200.20.14,},FirstTimestamp:2025-09-10 23:56:42.589962872 +0000 UTC m=+1.070651843,LastTimestamp:2025-09-10 23:56:42.589962872 +0000 UTC m=+1.070651843,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.200.20.14,}" Sep 10 23:56:42.599284 kubelet[2404]: E0910 23:56:42.599194 2404 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Sep 10 23:56:42.599410 kubelet[2404]: E0910 23:56:42.599296 2404 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.200.20.14\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Sep 10 23:56:42.599582 kubelet[2404]: E0910 23:56:42.599563 2404 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 10 23:56:42.600924 kubelet[2404]: I0910 23:56:42.600352 2404 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 10 23:56:42.601378 kubelet[2404]: E0910 23:56:42.601359 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:42.601422 kubelet[2404]: I0910 23:56:42.601387 2404 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 10 23:56:42.601566 kubelet[2404]: I0910 23:56:42.601548 2404 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 10 23:56:42.601607 kubelet[2404]: I0910 23:56:42.601597 2404 reconciler.go:26] "Reconciler: start to sync state" Sep 10 23:56:42.602426 kubelet[2404]: I0910 23:56:42.602396 2404 factory.go:223] Registration of the systemd container factory successfully Sep 10 23:56:42.602518 kubelet[2404]: I0910 23:56:42.602482 2404 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 Sep 10 23:56:42.603961 kubelet[2404]: I0910 23:56:42.603933 2404 factory.go:223] Registration of the containerd container factory successfully Sep 10 23:56:42.626334 kubelet[2404]: E0910 23:56:42.626295 2404 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.200.20.14\" not found" node="10.200.20.14" Sep 10 23:56:42.629487 kubelet[2404]: I0910 23:56:42.629465 2404 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 10 23:56:42.629672 kubelet[2404]: I0910 23:56:42.629618 2404 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 10 23:56:42.629738 kubelet[2404]: I0910 23:56:42.629729 2404 state_mem.go:36] "Initialized new in-memory state store" Sep 10 23:56:42.635628 kubelet[2404]: I0910 23:56:42.635364 2404 policy_none.go:49] "None policy: Start" Sep 10 23:56:42.635628 kubelet[2404]: I0910 23:56:42.635391 2404 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 10 23:56:42.635628 kubelet[2404]: I0910 23:56:42.635401 2404 state_mem.go:35] "Initializing new in-memory state store" Sep 10 23:56:42.643716 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 10 23:56:42.653777 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 10 23:56:42.656507 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 10 23:56:42.664108 kubelet[2404]: E0910 23:56:42.664081 2404 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 10 23:56:42.665221 kubelet[2404]: I0910 23:56:42.665195 2404 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 10 23:56:42.665361 kubelet[2404]: I0910 23:56:42.665325 2404 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 10 23:56:42.665784 kubelet[2404]: I0910 23:56:42.665714 2404 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 10 23:56:42.666989 kubelet[2404]: E0910 23:56:42.666931 2404 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 10 23:56:42.666989 kubelet[2404]: E0910 23:56:42.666968 2404 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.20.14\" not found" Sep 10 23:56:42.742134 kubelet[2404]: I0910 23:56:42.742077 2404 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Sep 10 23:56:42.743625 kubelet[2404]: I0910 23:56:42.743599 2404 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Sep 10 23:56:42.743625 kubelet[2404]: I0910 23:56:42.743622 2404 status_manager.go:230] "Starting to sync pod status with apiserver" Sep 10 23:56:42.743735 kubelet[2404]: I0910 23:56:42.743650 2404 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 10 23:56:42.743735 kubelet[2404]: I0910 23:56:42.743654 2404 kubelet.go:2436] "Starting kubelet main sync loop" Sep 10 23:56:42.743735 kubelet[2404]: E0910 23:56:42.743694 2404 kubelet.go:2460] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 10 23:56:42.766557 kubelet[2404]: I0910 23:56:42.766528 2404 kubelet_node_status.go:75] "Attempting to register node" node="10.200.20.14" Sep 10 23:56:42.777610 kubelet[2404]: I0910 23:56:42.777574 2404 kubelet_node_status.go:78] "Successfully registered node" node="10.200.20.14" Sep 10 23:56:42.777610 kubelet[2404]: E0910 23:56:42.777614 2404 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.200.20.14\": node \"10.200.20.14\" not found" Sep 10 23:56:42.791280 kubelet[2404]: E0910 23:56:42.791244 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:42.892666 kubelet[2404]: E0910 23:56:42.891837 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:42.992502 kubelet[2404]: E0910 23:56:42.992421 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.032344 sudo[2248]: pam_unix(sudo:session): session closed for user root Sep 10 23:56:43.092771 kubelet[2404]: E0910 23:56:43.092730 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.110101 sshd[2247]: Connection closed by 10.200.16.10 port 34468 Sep 10 23:56:43.110656 sshd-session[2242]: pam_unix(sshd:session): session closed for user core Sep 10 23:56:43.113517 systemd-logind[1798]: Session 7 logged out. Waiting for processes to exit. Sep 10 23:56:43.113642 systemd[1]: sshd@4-10.200.20.14:22-10.200.16.10:34468.service: Deactivated successfully. Sep 10 23:56:43.117426 systemd[1]: session-7.scope: Deactivated successfully. Sep 10 23:56:43.117582 systemd[1]: session-7.scope: Consumed 280ms CPU time, 73.1M memory peak. Sep 10 23:56:43.119756 systemd-logind[1798]: Removed session 7. Sep 10 23:56:43.193237 kubelet[2404]: E0910 23:56:43.193191 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.293663 kubelet[2404]: E0910 23:56:43.293626 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.394095 kubelet[2404]: E0910 23:56:43.394043 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.494717 kubelet[2404]: E0910 23:56:43.494569 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.546066 kubelet[2404]: I0910 23:56:43.546023 2404 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 10 23:56:43.546494 kubelet[2404]: I0910 23:56:43.546445 2404 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Sep 10 23:56:43.546494 kubelet[2404]: I0910 23:56:43.546455 2404 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Sep 10 23:56:43.584427 kubelet[2404]: E0910 23:56:43.584395 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:43.595684 kubelet[2404]: E0910 23:56:43.595669 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.696676 kubelet[2404]: E0910 23:56:43.696637 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.797884 kubelet[2404]: E0910 23:56:43.797585 2404 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.14\" not found" Sep 10 23:56:43.899215 kubelet[2404]: I0910 23:56:43.899180 2404 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 10 23:56:43.900014 containerd[1825]: time="2025-09-10T23:56:43.899676422Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 10 23:56:43.900321 kubelet[2404]: I0910 23:56:43.899834 2404 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 10 23:56:44.585107 kubelet[2404]: E0910 23:56:44.584972 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:44.587080 kubelet[2404]: I0910 23:56:44.587064 2404 apiserver.go:52] "Watching apiserver" Sep 10 23:56:44.601772 systemd[1]: Created slice kubepods-burstable-podf3751e0d_bbe3_4ba6_916e_40c179ed79c2.slice - libcontainer container kubepods-burstable-podf3751e0d_bbe3_4ba6_916e_40c179ed79c2.slice. Sep 10 23:56:44.602060 kubelet[2404]: I0910 23:56:44.601893 2404 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 10 23:56:44.611518 kubelet[2404]: I0910 23:56:44.611451 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f3751e0d-bbe3-4ba6-916e-40c179ed79c2-run\") pod \"kube-flannel-ds-vldvk\" (UID: \"f3751e0d-bbe3-4ba6-916e-40c179ed79c2\") " pod="kube-flannel/kube-flannel-ds-vldvk" Sep 10 23:56:44.611518 kubelet[2404]: I0910 23:56:44.611474 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f3751e0d-bbe3-4ba6-916e-40c179ed79c2-cni-plugin\") pod \"kube-flannel-ds-vldvk\" (UID: \"f3751e0d-bbe3-4ba6-916e-40c179ed79c2\") " pod="kube-flannel/kube-flannel-ds-vldvk" Sep 10 23:56:44.611518 kubelet[2404]: I0910 23:56:44.611486 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f3751e0d-bbe3-4ba6-916e-40c179ed79c2-cni\") pod \"kube-flannel-ds-vldvk\" (UID: \"f3751e0d-bbe3-4ba6-916e-40c179ed79c2\") " pod="kube-flannel/kube-flannel-ds-vldvk" Sep 10 23:56:44.611518 kubelet[2404]: I0910 23:56:44.611496 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f3751e0d-bbe3-4ba6-916e-40c179ed79c2-flannel-cfg\") pod \"kube-flannel-ds-vldvk\" (UID: \"f3751e0d-bbe3-4ba6-916e-40c179ed79c2\") " pod="kube-flannel/kube-flannel-ds-vldvk" Sep 10 23:56:44.611518 kubelet[2404]: I0910 23:56:44.611505 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/f2a55f38-b739-4f0f-ac0d-3471e835d448-kube-proxy\") pod \"kube-proxy-ccr7c\" (UID: \"f2a55f38-b739-4f0f-ac0d-3471e835d448\") " pod="kube-system/kube-proxy-ccr7c" Sep 10 23:56:44.611683 kubelet[2404]: I0910 23:56:44.611515 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f2a55f38-b739-4f0f-ac0d-3471e835d448-xtables-lock\") pod \"kube-proxy-ccr7c\" (UID: \"f2a55f38-b739-4f0f-ac0d-3471e835d448\") " pod="kube-system/kube-proxy-ccr7c" Sep 10 23:56:44.611683 kubelet[2404]: I0910 23:56:44.611534 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/f2a55f38-b739-4f0f-ac0d-3471e835d448-lib-modules\") pod \"kube-proxy-ccr7c\" (UID: \"f2a55f38-b739-4f0f-ac0d-3471e835d448\") " pod="kube-system/kube-proxy-ccr7c" Sep 10 23:56:44.611683 kubelet[2404]: I0910 23:56:44.611544 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rfphs\" (UniqueName: \"kubernetes.io/projected/f2a55f38-b739-4f0f-ac0d-3471e835d448-kube-api-access-rfphs\") pod \"kube-proxy-ccr7c\" (UID: \"f2a55f38-b739-4f0f-ac0d-3471e835d448\") " pod="kube-system/kube-proxy-ccr7c" Sep 10 23:56:44.611683 kubelet[2404]: I0910 23:56:44.611554 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f3751e0d-bbe3-4ba6-916e-40c179ed79c2-xtables-lock\") pod \"kube-flannel-ds-vldvk\" (UID: \"f3751e0d-bbe3-4ba6-916e-40c179ed79c2\") " pod="kube-flannel/kube-flannel-ds-vldvk" Sep 10 23:56:44.611683 kubelet[2404]: I0910 23:56:44.611569 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xhckf\" (UniqueName: \"kubernetes.io/projected/f3751e0d-bbe3-4ba6-916e-40c179ed79c2-kube-api-access-xhckf\") pod \"kube-flannel-ds-vldvk\" (UID: \"f3751e0d-bbe3-4ba6-916e-40c179ed79c2\") " pod="kube-flannel/kube-flannel-ds-vldvk" Sep 10 23:56:44.614930 systemd[1]: Created slice kubepods-besteffort-podf2a55f38_b739_4f0f_ac0d_3471e835d448.slice - libcontainer container kubepods-besteffort-podf2a55f38_b739_4f0f_ac0d_3471e835d448.slice. Sep 10 23:56:44.911930 containerd[1825]: time="2025-09-10T23:56:44.911808818Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vldvk,Uid:f3751e0d-bbe3-4ba6-916e-40c179ed79c2,Namespace:kube-flannel,Attempt:0,}" Sep 10 23:56:44.927817 containerd[1825]: time="2025-09-10T23:56:44.927780150Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ccr7c,Uid:f2a55f38-b739-4f0f-ac0d-3471e835d448,Namespace:kube-system,Attempt:0,}" Sep 10 23:56:45.586029 kubelet[2404]: E0910 23:56:45.585973 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:45.918012 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1619393901.mount: Deactivated successfully. Sep 10 23:56:45.948647 containerd[1825]: time="2025-09-10T23:56:45.948593764Z" 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\"}" Sep 10 23:56:45.960580 containerd[1825]: time="2025-09-10T23:56:45.960539718Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Sep 10 23:56:45.964628 containerd[1825]: time="2025-09-10T23:56:45.964590953Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 10 23:56:45.967404 containerd[1825]: time="2025-09-10T23:56:45.967371729Z" 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\"}" Sep 10 23:56:45.970610 containerd[1825]: time="2025-09-10T23:56:45.970555638Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 10 23:56:45.973751 containerd[1825]: time="2025-09-10T23:56:45.973724819Z" 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\"}" Sep 10 23:56:45.974302 containerd[1825]: time="2025-09-10T23:56:45.974277798Z" 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 1.028549056s" Sep 10 23:56:45.975602 containerd[1825]: time="2025-09-10T23:56:45.975566818Z" 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 1.045694084s" Sep 10 23:56:46.046997 containerd[1825]: time="2025-09-10T23:56:46.046911405Z" level=info msg="connecting to shim 4e690d72cf42e97e993195715c2de642470e12e479e27983c21abf90ec367a42" address="unix:///run/containerd/s/10cbfe710abe0a90ebfb9b0c3349c6b9e403aeb5b43599f43a6f95017b5c8d78" namespace=k8s.io protocol=ttrpc version=3 Sep 10 23:56:46.059778 containerd[1825]: time="2025-09-10T23:56:46.059568743Z" level=info msg="connecting to shim 3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3" address="unix:///run/containerd/s/aebc2db5100ef785b4621dc03f3eb8ac04deb9f901cb71ecf37522b680e45056" namespace=k8s.io protocol=ttrpc version=3 Sep 10 23:56:46.074310 systemd[1]: Started cri-containerd-4e690d72cf42e97e993195715c2de642470e12e479e27983c21abf90ec367a42.scope - libcontainer container 4e690d72cf42e97e993195715c2de642470e12e479e27983c21abf90ec367a42. Sep 10 23:56:46.080261 systemd[1]: Started cri-containerd-3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3.scope - libcontainer container 3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3. Sep 10 23:56:46.148058 containerd[1825]: time="2025-09-10T23:56:46.148012764Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ccr7c,Uid:f2a55f38-b739-4f0f-ac0d-3471e835d448,Namespace:kube-system,Attempt:0,} returns sandbox id \"4e690d72cf42e97e993195715c2de642470e12e479e27983c21abf90ec367a42\"" Sep 10 23:56:46.149900 containerd[1825]: time="2025-09-10T23:56:46.149858771Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Sep 10 23:56:46.157271 containerd[1825]: time="2025-09-10T23:56:46.157238065Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vldvk,Uid:f3751e0d-bbe3-4ba6-916e-40c179ed79c2,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3\"" Sep 10 23:56:46.586838 kubelet[2404]: E0910 23:56:46.586791 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:47.255251 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1216055484.mount: Deactivated successfully. Sep 10 23:56:47.517762 containerd[1825]: time="2025-09-10T23:56:47.517212281Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:47.520454 containerd[1825]: time="2025-09-10T23:56:47.520416655Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240106" Sep 10 23:56:47.525552 containerd[1825]: time="2025-09-10T23:56:47.525436084Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:47.536642 containerd[1825]: time="2025-09-10T23:56:47.536567482Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:47.536881 containerd[1825]: time="2025-09-10T23:56:47.536848283Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.386959327s" Sep 10 23:56:47.536881 containerd[1825]: time="2025-09-10T23:56:47.536878892Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Sep 10 23:56:47.538621 containerd[1825]: time="2025-09-10T23:56:47.538262780Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 10 23:56:47.548342 containerd[1825]: time="2025-09-10T23:56:47.548301941Z" level=info msg="CreateContainer within sandbox \"4e690d72cf42e97e993195715c2de642470e12e479e27983c21abf90ec367a42\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 10 23:56:47.587547 kubelet[2404]: E0910 23:56:47.587487 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:47.591338 containerd[1825]: time="2025-09-10T23:56:47.591233423Z" level=info msg="Container 7b2eb906ecc3e268a48a1d4ff3d6efe9c88f7e55101260828619f2932e763fbc: CDI devices from CRI Config.CDIDevices: []" Sep 10 23:56:47.606667 containerd[1825]: time="2025-09-10T23:56:47.606555661Z" level=info msg="CreateContainer within sandbox \"4e690d72cf42e97e993195715c2de642470e12e479e27983c21abf90ec367a42\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"7b2eb906ecc3e268a48a1d4ff3d6efe9c88f7e55101260828619f2932e763fbc\"" Sep 10 23:56:47.607650 containerd[1825]: time="2025-09-10T23:56:47.607594193Z" level=info msg="StartContainer for \"7b2eb906ecc3e268a48a1d4ff3d6efe9c88f7e55101260828619f2932e763fbc\"" Sep 10 23:56:47.608995 containerd[1825]: time="2025-09-10T23:56:47.608932239Z" level=info msg="connecting to shim 7b2eb906ecc3e268a48a1d4ff3d6efe9c88f7e55101260828619f2932e763fbc" address="unix:///run/containerd/s/10cbfe710abe0a90ebfb9b0c3349c6b9e403aeb5b43599f43a6f95017b5c8d78" protocol=ttrpc version=3 Sep 10 23:56:47.626252 systemd[1]: Started cri-containerd-7b2eb906ecc3e268a48a1d4ff3d6efe9c88f7e55101260828619f2932e763fbc.scope - libcontainer container 7b2eb906ecc3e268a48a1d4ff3d6efe9c88f7e55101260828619f2932e763fbc. Sep 10 23:56:47.656065 containerd[1825]: time="2025-09-10T23:56:47.656032784Z" level=info msg="StartContainer for \"7b2eb906ecc3e268a48a1d4ff3d6efe9c88f7e55101260828619f2932e763fbc\" returns successfully" Sep 10 23:56:47.768086 kubelet[2404]: I0910 23:56:47.767729 2404 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-ccr7c" podStartSLOduration=4.379021428 podStartE2EDuration="5.767711262s" podCreationTimestamp="2025-09-10 23:56:42 +0000 UTC" firstStartedPulling="2025-09-10 23:56:46.149373395 +0000 UTC m=+4.630062350" lastFinishedPulling="2025-09-10 23:56:47.538063229 +0000 UTC m=+6.018752184" observedRunningTime="2025-09-10 23:56:47.767650947 +0000 UTC m=+6.248339894" watchObservedRunningTime="2025-09-10 23:56:47.767711262 +0000 UTC m=+6.248400209" Sep 10 23:56:48.588111 kubelet[2404]: E0910 23:56:48.587593 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:49.125089 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3253915192.mount: Deactivated successfully. Sep 10 23:56:49.202835 containerd[1825]: time="2025-09-10T23:56:49.202184880Z" 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\"}" Sep 10 23:56:49.206172 containerd[1825]: time="2025-09-10T23:56:49.206138765Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Sep 10 23:56:49.209856 containerd[1825]: time="2025-09-10T23:56:49.209818473Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:49.213461 containerd[1825]: time="2025-09-10T23:56:49.213412962Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:49.214186 containerd[1825]: time="2025-09-10T23:56:49.214162469Z" 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.675860055s" Sep 10 23:56:49.214290 containerd[1825]: time="2025-09-10T23:56:49.214275105Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Sep 10 23:56:49.220920 containerd[1825]: time="2025-09-10T23:56:49.220885445Z" level=info msg="CreateContainer within sandbox \"3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 10 23:56:49.245624 containerd[1825]: time="2025-09-10T23:56:49.245241854Z" level=info msg="Container fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42: CDI devices from CRI Config.CDIDevices: []" Sep 10 23:56:49.262698 containerd[1825]: time="2025-09-10T23:56:49.262634148Z" level=info msg="CreateContainer within sandbox \"3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42\"" Sep 10 23:56:49.263445 containerd[1825]: time="2025-09-10T23:56:49.263412320Z" level=info msg="StartContainer for \"fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42\"" Sep 10 23:56:49.264408 containerd[1825]: time="2025-09-10T23:56:49.264331697Z" level=info msg="connecting to shim fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42" address="unix:///run/containerd/s/aebc2db5100ef785b4621dc03f3eb8ac04deb9f901cb71ecf37522b680e45056" protocol=ttrpc version=3 Sep 10 23:56:49.278223 systemd[1]: Started cri-containerd-fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42.scope - libcontainer container fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42. Sep 10 23:56:49.300988 systemd[1]: cri-containerd-fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42.scope: Deactivated successfully. Sep 10 23:56:49.303650 containerd[1825]: time="2025-09-10T23:56:49.303611960Z" level=info msg="TaskExit event in podsandbox handler container_id:\"fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42\" id:\"fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42\" pid:2734 exited_at:{seconds:1757548609 nanos:302996946}" Sep 10 23:56:49.304610 containerd[1825]: time="2025-09-10T23:56:49.304580819Z" level=info msg="received exit event container_id:\"fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42\" id:\"fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42\" pid:2734 exited_at:{seconds:1757548609 nanos:302996946}" Sep 10 23:56:49.305718 containerd[1825]: time="2025-09-10T23:56:49.305643681Z" level=info msg="StartContainer for \"fc1218918ddba9ecb1758b87c14fda486300694b12f26cc466c601314c1cfc42\" returns successfully" Sep 10 23:56:49.588297 kubelet[2404]: E0910 23:56:49.588243 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:50.589425 kubelet[2404]: E0910 23:56:50.589374 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:50.764078 containerd[1825]: time="2025-09-10T23:56:50.764037123Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 10 23:56:51.589752 kubelet[2404]: E0910 23:56:51.589715 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:52.590477 kubelet[2404]: E0910 23:56:52.590432 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:52.967014 containerd[1825]: time="2025-09-10T23:56:52.966251685Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:52.969133 containerd[1825]: time="2025-09-10T23:56:52.969105347Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Sep 10 23:56:52.972857 containerd[1825]: time="2025-09-10T23:56:52.972821832Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:52.976933 containerd[1825]: time="2025-09-10T23:56:52.976894138Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:56:52.977894 containerd[1825]: time="2025-09-10T23:56:52.977866165Z" 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.213374658s" Sep 10 23:56:52.977894 containerd[1825]: time="2025-09-10T23:56:52.977891822Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Sep 10 23:56:52.983929 containerd[1825]: time="2025-09-10T23:56:52.983896885Z" level=info msg="CreateContainer within sandbox \"3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 10 23:56:53.004311 containerd[1825]: time="2025-09-10T23:56:53.004264526Z" level=info msg="Container 36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85: CDI devices from CRI Config.CDIDevices: []" Sep 10 23:56:53.028264 containerd[1825]: time="2025-09-10T23:56:53.028221320Z" level=info msg="CreateContainer within sandbox \"3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85\"" Sep 10 23:56:53.029096 containerd[1825]: time="2025-09-10T23:56:53.028914233Z" level=info msg="StartContainer for \"36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85\"" Sep 10 23:56:53.029900 containerd[1825]: time="2025-09-10T23:56:53.029870315Z" level=info msg="connecting to shim 36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85" address="unix:///run/containerd/s/aebc2db5100ef785b4621dc03f3eb8ac04deb9f901cb71ecf37522b680e45056" protocol=ttrpc version=3 Sep 10 23:56:53.049230 systemd[1]: Started cri-containerd-36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85.scope - libcontainer container 36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85. Sep 10 23:56:53.068887 systemd[1]: cri-containerd-36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85.scope: Deactivated successfully. Sep 10 23:56:53.071013 containerd[1825]: time="2025-09-10T23:56:53.070978236Z" level=info msg="TaskExit event in podsandbox handler container_id:\"36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85\" id:\"36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85\" pid:2810 exited_at:{seconds:1757548613 nanos:70660072}" Sep 10 23:56:53.075241 containerd[1825]: time="2025-09-10T23:56:53.075155017Z" level=info msg="received exit event container_id:\"36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85\" id:\"36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85\" pid:2810 exited_at:{seconds:1757548613 nanos:70660072}" Sep 10 23:56:53.078220 kubelet[2404]: I0910 23:56:53.077499 2404 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 10 23:56:53.078625 containerd[1825]: time="2025-09-10T23:56:53.078459975Z" level=info msg="StartContainer for \"36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85\" returns successfully" Sep 10 23:56:53.092934 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-36b0461f608d89d7af5dd9ad80f151d343715d7bc4587ea3780af18afe6b8e85-rootfs.mount: Deactivated successfully. Sep 10 23:56:53.470974 update_engine[1801]: I20250910 23:56:53.470481 1801 update_attempter.cc:509] Updating boot flags... Sep 10 23:56:53.590860 kubelet[2404]: E0910 23:56:53.590822 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:53.796115 containerd[1825]: time="2025-09-10T23:56:53.795843962Z" level=info msg="CreateContainer within sandbox \"3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 10 23:56:53.842532 containerd[1825]: time="2025-09-10T23:56:53.842487888Z" level=info msg="Container 9cbba3ac8ddd5bcd909bb544dc80289307d16346ba97c968b3071c50541811a9: CDI devices from CRI Config.CDIDevices: []" Sep 10 23:56:53.858208 containerd[1825]: time="2025-09-10T23:56:53.858143545Z" level=info msg="CreateContainer within sandbox \"3711ce41ae0b625751200db0d4ea8cc27bec5b4bcdc85d7d2ea0a09a1f5de7a3\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"9cbba3ac8ddd5bcd909bb544dc80289307d16346ba97c968b3071c50541811a9\"" Sep 10 23:56:53.859105 containerd[1825]: time="2025-09-10T23:56:53.858963830Z" level=info msg="StartContainer for \"9cbba3ac8ddd5bcd909bb544dc80289307d16346ba97c968b3071c50541811a9\"" Sep 10 23:56:53.859849 containerd[1825]: time="2025-09-10T23:56:53.859826301Z" level=info msg="connecting to shim 9cbba3ac8ddd5bcd909bb544dc80289307d16346ba97c968b3071c50541811a9" address="unix:///run/containerd/s/aebc2db5100ef785b4621dc03f3eb8ac04deb9f901cb71ecf37522b680e45056" protocol=ttrpc version=3 Sep 10 23:56:53.877231 systemd[1]: Started cri-containerd-9cbba3ac8ddd5bcd909bb544dc80289307d16346ba97c968b3071c50541811a9.scope - libcontainer container 9cbba3ac8ddd5bcd909bb544dc80289307d16346ba97c968b3071c50541811a9. Sep 10 23:56:53.906181 containerd[1825]: time="2025-09-10T23:56:53.906130335Z" level=info msg="StartContainer for \"9cbba3ac8ddd5bcd909bb544dc80289307d16346ba97c968b3071c50541811a9\" returns successfully" Sep 10 23:56:54.003489 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3379875821.mount: Deactivated successfully. Sep 10 23:56:54.590978 kubelet[2404]: E0910 23:56:54.590923 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:54.850396 kubelet[2404]: I0910 23:56:54.850198 2404 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-vldvk" podStartSLOduration=6.029562121 podStartE2EDuration="12.850181055s" podCreationTimestamp="2025-09-10 23:56:42 +0000 UTC" firstStartedPulling="2025-09-10 23:56:46.158297661 +0000 UTC m=+4.638986608" lastFinishedPulling="2025-09-10 23:56:52.978916595 +0000 UTC m=+11.459605542" observedRunningTime="2025-09-10 23:56:54.85005533 +0000 UTC m=+13.330744277" watchObservedRunningTime="2025-09-10 23:56:54.850181055 +0000 UTC m=+13.330870002" Sep 10 23:56:55.222217 systemd-networkd[1619]: flannel.1: Link UP Sep 10 23:56:55.222223 systemd-networkd[1619]: flannel.1: Gained carrier Sep 10 23:56:55.591573 kubelet[2404]: E0910 23:56:55.591436 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:56.486239 systemd-networkd[1619]: flannel.1: Gained IPv6LL Sep 10 23:56:56.592565 kubelet[2404]: E0910 23:56:56.592510 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:56.652638 systemd[1]: Created slice kubepods-besteffort-pod1cc05c74_359a_46c4_920e_065df2162138.slice - libcontainer container kubepods-besteffort-pod1cc05c74_359a_46c4_920e_065df2162138.slice. Sep 10 23:56:56.688086 kubelet[2404]: I0910 23:56:56.688035 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g2656\" (UniqueName: \"kubernetes.io/projected/1cc05c74-359a-46c4-920e-065df2162138-kube-api-access-g2656\") pod \"nginx-deployment-7fcdb87857-jz5t4\" (UID: \"1cc05c74-359a-46c4-920e-065df2162138\") " pod="default/nginx-deployment-7fcdb87857-jz5t4" Sep 10 23:56:56.955347 containerd[1825]: time="2025-09-10T23:56:56.955309157Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-jz5t4,Uid:1cc05c74-359a-46c4-920e-065df2162138,Namespace:default,Attempt:0,}" Sep 10 23:56:56.974042 systemd-networkd[1619]: cni0: Link UP Sep 10 23:56:56.974047 systemd-networkd[1619]: cni0: Gained carrier Sep 10 23:56:56.974731 systemd-networkd[1619]: cni0: Lost carrier Sep 10 23:56:57.039849 systemd-networkd[1619]: veth94f51855: Link UP Sep 10 23:56:57.046389 kernel: cni0: port 1(veth94f51855) entered blocking state Sep 10 23:56:57.046480 kernel: cni0: port 1(veth94f51855) entered disabled state Sep 10 23:56:57.049889 kernel: veth94f51855: entered allmulticast mode Sep 10 23:56:57.053091 kernel: veth94f51855: entered promiscuous mode Sep 10 23:56:57.062449 kernel: cni0: port 1(veth94f51855) entered blocking state Sep 10 23:56:57.062657 kernel: cni0: port 1(veth94f51855) entered forwarding state Sep 10 23:56:57.062663 systemd-networkd[1619]: veth94f51855: Gained carrier Sep 10 23:56:57.062985 systemd-networkd[1619]: cni0: Gained carrier Sep 10 23:56:57.065986 containerd[1825]: 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"} Sep 10 23:56:57.065986 containerd[1825]: delegateAdd: netconf sent to delegate plugin: Sep 10 23:56:57.110012 containerd[1825]: {"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-09-10T23:56:57.109637134Z" level=info msg="connecting to shim 20edfe9da9bd0e7149fd24ddaf8ca2af21532581b213b0d922dd0a0a49f09b72" address="unix:///run/containerd/s/ed7684add9b575cacc176ef1cfd3fbc71013063ae3a6aa09bb076205c5d47d7d" namespace=k8s.io protocol=ttrpc version=3 Sep 10 23:56:57.126209 systemd[1]: Started cri-containerd-20edfe9da9bd0e7149fd24ddaf8ca2af21532581b213b0d922dd0a0a49f09b72.scope - libcontainer container 20edfe9da9bd0e7149fd24ddaf8ca2af21532581b213b0d922dd0a0a49f09b72. Sep 10 23:56:57.154020 containerd[1825]: time="2025-09-10T23:56:57.153987856Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-jz5t4,Uid:1cc05c74-359a-46c4-920e-065df2162138,Namespace:default,Attempt:0,} returns sandbox id \"20edfe9da9bd0e7149fd24ddaf8ca2af21532581b213b0d922dd0a0a49f09b72\"" Sep 10 23:56:57.155372 containerd[1825]: time="2025-09-10T23:56:57.155323933Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 10 23:56:57.592923 kubelet[2404]: E0910 23:56:57.592871 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:58.470220 systemd-networkd[1619]: cni0: Gained IPv6LL Sep 10 23:56:58.593893 kubelet[2404]: E0910 23:56:58.593855 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:56:58.726227 systemd-networkd[1619]: veth94f51855: Gained IPv6LL Sep 10 23:56:59.566446 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3737541764.mount: Deactivated successfully. Sep 10 23:56:59.594583 kubelet[2404]: E0910 23:56:59.594546 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:00.263667 containerd[1825]: time="2025-09-10T23:57:00.263591472Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:57:00.269960 containerd[1825]: time="2025-09-10T23:57:00.269745031Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 10 23:57:00.273006 containerd[1825]: time="2025-09-10T23:57:00.272959128Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:57:00.277207 containerd[1825]: time="2025-09-10T23:57:00.277146870Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:57:00.277877 containerd[1825]: time="2025-09-10T23:57:00.277759306Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 3.122386748s" Sep 10 23:57:00.277877 containerd[1825]: time="2025-09-10T23:57:00.277794308Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 10 23:57:00.284854 containerd[1825]: time="2025-09-10T23:57:00.284463386Z" level=info msg="CreateContainer within sandbox \"20edfe9da9bd0e7149fd24ddaf8ca2af21532581b213b0d922dd0a0a49f09b72\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 10 23:57:00.305657 containerd[1825]: time="2025-09-10T23:57:00.305348628Z" level=info msg="Container 13d2451e876a2af1d29b867c287a9d66361290f6de5f40e6764de1c1d8b79df1: CDI devices from CRI Config.CDIDevices: []" Sep 10 23:57:00.318139 containerd[1825]: time="2025-09-10T23:57:00.318064692Z" level=info msg="CreateContainer within sandbox \"20edfe9da9bd0e7149fd24ddaf8ca2af21532581b213b0d922dd0a0a49f09b72\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"13d2451e876a2af1d29b867c287a9d66361290f6de5f40e6764de1c1d8b79df1\"" Sep 10 23:57:00.319088 containerd[1825]: time="2025-09-10T23:57:00.319021336Z" level=info msg="StartContainer for \"13d2451e876a2af1d29b867c287a9d66361290f6de5f40e6764de1c1d8b79df1\"" Sep 10 23:57:00.319881 containerd[1825]: time="2025-09-10T23:57:00.319839885Z" level=info msg="connecting to shim 13d2451e876a2af1d29b867c287a9d66361290f6de5f40e6764de1c1d8b79df1" address="unix:///run/containerd/s/ed7684add9b575cacc176ef1cfd3fbc71013063ae3a6aa09bb076205c5d47d7d" protocol=ttrpc version=3 Sep 10 23:57:00.338232 systemd[1]: Started cri-containerd-13d2451e876a2af1d29b867c287a9d66361290f6de5f40e6764de1c1d8b79df1.scope - libcontainer container 13d2451e876a2af1d29b867c287a9d66361290f6de5f40e6764de1c1d8b79df1. Sep 10 23:57:00.363385 containerd[1825]: time="2025-09-10T23:57:00.363346760Z" level=info msg="StartContainer for \"13d2451e876a2af1d29b867c287a9d66361290f6de5f40e6764de1c1d8b79df1\" returns successfully" Sep 10 23:57:00.595174 kubelet[2404]: E0910 23:57:00.595033 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:01.595193 kubelet[2404]: E0910 23:57:01.595137 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:02.584049 kubelet[2404]: E0910 23:57:02.583909 2404 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:02.596331 kubelet[2404]: E0910 23:57:02.596220 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:03.596888 kubelet[2404]: E0910 23:57:03.596828 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:04.597353 kubelet[2404]: E0910 23:57:04.597299 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:05.598163 kubelet[2404]: E0910 23:57:05.598121 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:05.604829 kubelet[2404]: I0910 23:57:05.604725 2404 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-jz5t4" podStartSLOduration=6.4808437770000005 podStartE2EDuration="9.604708215s" podCreationTimestamp="2025-09-10 23:56:56 +0000 UTC" firstStartedPulling="2025-09-10 23:56:57.154874864 +0000 UTC m=+15.635563811" lastFinishedPulling="2025-09-10 23:57:00.278739302 +0000 UTC m=+18.759428249" observedRunningTime="2025-09-10 23:57:00.807053514 +0000 UTC m=+19.287742461" watchObservedRunningTime="2025-09-10 23:57:05.604708215 +0000 UTC m=+24.085397162" Sep 10 23:57:05.619762 systemd[1]: Created slice kubepods-besteffort-poda0e42d18_1791_4845_81ec_c81518c379f3.slice - libcontainer container kubepods-besteffort-poda0e42d18_1791_4845_81ec_c81518c379f3.slice. Sep 10 23:57:05.639730 kubelet[2404]: I0910 23:57:05.639686 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7vb5w\" (UniqueName: \"kubernetes.io/projected/a0e42d18-1791-4845-81ec-c81518c379f3-kube-api-access-7vb5w\") pod \"nfs-server-provisioner-0\" (UID: \"a0e42d18-1791-4845-81ec-c81518c379f3\") " pod="default/nfs-server-provisioner-0" Sep 10 23:57:05.639730 kubelet[2404]: I0910 23:57:05.639729 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/a0e42d18-1791-4845-81ec-c81518c379f3-data\") pod \"nfs-server-provisioner-0\" (UID: \"a0e42d18-1791-4845-81ec-c81518c379f3\") " pod="default/nfs-server-provisioner-0" Sep 10 23:57:05.922973 containerd[1825]: time="2025-09-10T23:57:05.922877285Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a0e42d18-1791-4845-81ec-c81518c379f3,Namespace:default,Attempt:0,}" Sep 10 23:57:05.941811 systemd-networkd[1619]: vethb5caea99: Link UP Sep 10 23:57:05.950458 kernel: cni0: port 2(vethb5caea99) entered blocking state Sep 10 23:57:05.950564 kernel: cni0: port 2(vethb5caea99) entered disabled state Sep 10 23:57:05.954879 kernel: vethb5caea99: entered allmulticast mode Sep 10 23:57:05.958288 kernel: vethb5caea99: entered promiscuous mode Sep 10 23:57:05.968738 kernel: cni0: port 2(vethb5caea99) entered blocking state Sep 10 23:57:05.968823 kernel: cni0: port 2(vethb5caea99) entered forwarding state Sep 10 23:57:05.969232 systemd-networkd[1619]: vethb5caea99: Gained carrier Sep 10 23:57:05.971592 containerd[1825]: 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"} Sep 10 23:57:05.971592 containerd[1825]: delegateAdd: netconf sent to delegate plugin: Sep 10 23:57:06.008938 containerd[1825]: {"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-09-10T23:57:06.008884959Z" level=info msg="connecting to shim 2f6196288807b753e6c7bd97335083d6a741f3462a5c5039e95ef57b4c5e17ff" address="unix:///run/containerd/s/f3a6fc8335a48c37512bbf0a4fc10923bd30428e99f1cbe2c28f4c17e50bc258" namespace=k8s.io protocol=ttrpc version=3 Sep 10 23:57:06.035256 systemd[1]: Started cri-containerd-2f6196288807b753e6c7bd97335083d6a741f3462a5c5039e95ef57b4c5e17ff.scope - libcontainer container 2f6196288807b753e6c7bd97335083d6a741f3462a5c5039e95ef57b4c5e17ff. Sep 10 23:57:06.067122 containerd[1825]: time="2025-09-10T23:57:06.067058145Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a0e42d18-1791-4845-81ec-c81518c379f3,Namespace:default,Attempt:0,} returns sandbox id \"2f6196288807b753e6c7bd97335083d6a741f3462a5c5039e95ef57b4c5e17ff\"" Sep 10 23:57:06.068619 containerd[1825]: time="2025-09-10T23:57:06.068486878Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 10 23:57:06.599039 kubelet[2404]: E0910 23:57:06.598991 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:07.558202 systemd-networkd[1619]: vethb5caea99: Gained IPv6LL Sep 10 23:57:07.599385 kubelet[2404]: E0910 23:57:07.599340 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:08.104175 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3111743468.mount: Deactivated successfully. Sep 10 23:57:08.600199 kubelet[2404]: E0910 23:57:08.600157 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:09.600411 containerd[1825]: time="2025-09-10T23:57:09.600353813Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:57:09.601088 kubelet[2404]: E0910 23:57:09.601047 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:09.603603 containerd[1825]: time="2025-09-10T23:57:09.603567508Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Sep 10 23:57:09.606876 containerd[1825]: time="2025-09-10T23:57:09.606808012Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:57:09.613518 containerd[1825]: time="2025-09-10T23:57:09.613449146Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:57:09.614215 containerd[1825]: time="2025-09-10T23:57:09.614097938Z" 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.54558429s" Sep 10 23:57:09.614215 containerd[1825]: time="2025-09-10T23:57:09.614128811Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 10 23:57:09.620857 containerd[1825]: time="2025-09-10T23:57:09.620824219Z" level=info msg="CreateContainer within sandbox \"2f6196288807b753e6c7bd97335083d6a741f3462a5c5039e95ef57b4c5e17ff\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 10 23:57:09.640593 containerd[1825]: time="2025-09-10T23:57:09.640543949Z" level=info msg="Container 93c093b60b2cad936660958dfbff24a144537c2035a0ff10f8ffe4304f572468: CDI devices from CRI Config.CDIDevices: []" Sep 10 23:57:09.654685 containerd[1825]: time="2025-09-10T23:57:09.654554476Z" level=info msg="CreateContainer within sandbox \"2f6196288807b753e6c7bd97335083d6a741f3462a5c5039e95ef57b4c5e17ff\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"93c093b60b2cad936660958dfbff24a144537c2035a0ff10f8ffe4304f572468\"" Sep 10 23:57:09.655331 containerd[1825]: time="2025-09-10T23:57:09.655060279Z" level=info msg="StartContainer for \"93c093b60b2cad936660958dfbff24a144537c2035a0ff10f8ffe4304f572468\"" Sep 10 23:57:09.656168 containerd[1825]: time="2025-09-10T23:57:09.656147295Z" level=info msg="connecting to shim 93c093b60b2cad936660958dfbff24a144537c2035a0ff10f8ffe4304f572468" address="unix:///run/containerd/s/f3a6fc8335a48c37512bbf0a4fc10923bd30428e99f1cbe2c28f4c17e50bc258" protocol=ttrpc version=3 Sep 10 23:57:09.674226 systemd[1]: Started cri-containerd-93c093b60b2cad936660958dfbff24a144537c2035a0ff10f8ffe4304f572468.scope - libcontainer container 93c093b60b2cad936660958dfbff24a144537c2035a0ff10f8ffe4304f572468. Sep 10 23:57:09.704476 containerd[1825]: time="2025-09-10T23:57:09.704369201Z" level=info msg="StartContainer for \"93c093b60b2cad936660958dfbff24a144537c2035a0ff10f8ffe4304f572468\" returns successfully" Sep 10 23:57:10.601418 kubelet[2404]: E0910 23:57:10.601361 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:11.602086 kubelet[2404]: E0910 23:57:11.602030 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:12.602978 kubelet[2404]: E0910 23:57:12.602931 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:13.603325 kubelet[2404]: E0910 23:57:13.603281 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:14.604081 kubelet[2404]: E0910 23:57:14.604027 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:15.065837 kubelet[2404]: I0910 23:57:15.065769 2404 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.519037447 podStartE2EDuration="10.065751803s" podCreationTimestamp="2025-09-10 23:57:05 +0000 UTC" firstStartedPulling="2025-09-10 23:57:06.068267598 +0000 UTC m=+24.548956545" lastFinishedPulling="2025-09-10 23:57:09.614981954 +0000 UTC m=+28.095670901" observedRunningTime="2025-09-10 23:57:09.850732557 +0000 UTC m=+28.331421504" watchObservedRunningTime="2025-09-10 23:57:15.065751803 +0000 UTC m=+33.546440750" Sep 10 23:57:15.169226 systemd[1]: Created slice kubepods-besteffort-pod7d7c1147_b15d_4356_9980_4de97334e631.slice - libcontainer container kubepods-besteffort-pod7d7c1147_b15d_4356_9980_4de97334e631.slice. Sep 10 23:57:15.204133 kubelet[2404]: I0910 23:57:15.204090 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-be73ad5b-f9d6-4d9f-9cb6-1a54750dbd1e\" (UniqueName: \"kubernetes.io/nfs/7d7c1147-b15d-4356-9980-4de97334e631-pvc-be73ad5b-f9d6-4d9f-9cb6-1a54750dbd1e\") pod \"test-pod-1\" (UID: \"7d7c1147-b15d-4356-9980-4de97334e631\") " pod="default/test-pod-1" Sep 10 23:57:15.204133 kubelet[2404]: I0910 23:57:15.204133 2404 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hn2rf\" (UniqueName: \"kubernetes.io/projected/7d7c1147-b15d-4356-9980-4de97334e631-kube-api-access-hn2rf\") pod \"test-pod-1\" (UID: \"7d7c1147-b15d-4356-9980-4de97334e631\") " pod="default/test-pod-1" Sep 10 23:57:15.604765 kubelet[2404]: E0910 23:57:15.604717 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:15.605119 kernel: netfs: FS-Cache loaded Sep 10 23:57:15.757091 kernel: RPC: Registered named UNIX socket transport module. Sep 10 23:57:15.757217 kernel: RPC: Registered udp transport module. Sep 10 23:57:15.757231 kernel: RPC: Registered tcp transport module. Sep 10 23:57:15.763204 kernel: RPC: Registered tcp-with-tls transport module. Sep 10 23:57:15.763241 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 10 23:57:16.209506 kernel: NFS: Registering the id_resolver key type Sep 10 23:57:16.209647 kernel: Key type id_resolver registered Sep 10 23:57:16.209664 kernel: Key type id_legacy registered Sep 10 23:57:16.605447 kubelet[2404]: E0910 23:57:16.605327 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:16.888629 nfsidmap[3481]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '1.0-n-093f1ee795' Sep 10 23:57:16.975227 nfsidmap[3482]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '1.0-n-093f1ee795' Sep 10 23:57:16.983660 nfsrahead[3484]: setting /var/lib/kubelet/pods/7d7c1147-b15d-4356-9980-4de97334e631/volumes/kubernetes.io~nfs/pvc-be73ad5b-f9d6-4d9f-9cb6-1a54750dbd1e readahead to 128 Sep 10 23:57:17.272586 containerd[1825]: time="2025-09-10T23:57:17.272524494Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:7d7c1147-b15d-4356-9980-4de97334e631,Namespace:default,Attempt:0,}" Sep 10 23:57:17.298647 systemd-networkd[1619]: veth53a97f83: Link UP Sep 10 23:57:17.309395 kernel: cni0: port 3(veth53a97f83) entered blocking state Sep 10 23:57:17.309484 kernel: cni0: port 3(veth53a97f83) entered disabled state Sep 10 23:57:17.313210 kernel: veth53a97f83: entered allmulticast mode Sep 10 23:57:17.316901 kernel: veth53a97f83: entered promiscuous mode Sep 10 23:57:17.328012 kernel: cni0: port 3(veth53a97f83) entered blocking state Sep 10 23:57:17.328140 kernel: cni0: port 3(veth53a97f83) entered forwarding state Sep 10 23:57:17.328057 systemd-networkd[1619]: veth53a97f83: Gained carrier Sep 10 23:57:17.330142 containerd[1825]: 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"} Sep 10 23:57:17.330142 containerd[1825]: delegateAdd: netconf sent to delegate plugin: Sep 10 23:57:17.369737 containerd[1825]: {"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-09-10T23:57:17.369698674Z" level=info msg="connecting to shim 0981115916bc10e800148e3025edbef6bbf23063f68b69bb509b750c6ae7b28b" address="unix:///run/containerd/s/747d4a4353d86996f54cb0004e87c946cec192abbb60a68a72971fe3fda31131" namespace=k8s.io protocol=ttrpc version=3 Sep 10 23:57:17.386206 systemd[1]: Started cri-containerd-0981115916bc10e800148e3025edbef6bbf23063f68b69bb509b750c6ae7b28b.scope - libcontainer container 0981115916bc10e800148e3025edbef6bbf23063f68b69bb509b750c6ae7b28b. Sep 10 23:57:17.414903 containerd[1825]: time="2025-09-10T23:57:17.414851107Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:7d7c1147-b15d-4356-9980-4de97334e631,Namespace:default,Attempt:0,} returns sandbox id \"0981115916bc10e800148e3025edbef6bbf23063f68b69bb509b750c6ae7b28b\"" Sep 10 23:57:17.416384 containerd[1825]: time="2025-09-10T23:57:17.416360082Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 10 23:57:17.606413 kubelet[2404]: E0910 23:57:17.606280 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:17.750100 containerd[1825]: time="2025-09-10T23:57:17.749980795Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:57:17.752880 containerd[1825]: time="2025-09-10T23:57:17.752850724Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 10 23:57:17.755661 containerd[1825]: time="2025-09-10T23:57:17.755609768Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 338.984132ms" Sep 10 23:57:17.755661 containerd[1825]: time="2025-09-10T23:57:17.755649873Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 10 23:57:17.765372 containerd[1825]: time="2025-09-10T23:57:17.765330721Z" level=info msg="CreateContainer within sandbox \"0981115916bc10e800148e3025edbef6bbf23063f68b69bb509b750c6ae7b28b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 10 23:57:17.808478 containerd[1825]: time="2025-09-10T23:57:17.808436039Z" level=info msg="Container 1104ff75b358e2ee1baeb482d6ef88d5374f809ee8fd8979fe7cea658373a2ec: CDI devices from CRI Config.CDIDevices: []" Sep 10 23:57:17.833061 containerd[1825]: time="2025-09-10T23:57:17.832968178Z" level=info msg="CreateContainer within sandbox \"0981115916bc10e800148e3025edbef6bbf23063f68b69bb509b750c6ae7b28b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"1104ff75b358e2ee1baeb482d6ef88d5374f809ee8fd8979fe7cea658373a2ec\"" Sep 10 23:57:17.833592 containerd[1825]: time="2025-09-10T23:57:17.833537647Z" level=info msg="StartContainer for \"1104ff75b358e2ee1baeb482d6ef88d5374f809ee8fd8979fe7cea658373a2ec\"" Sep 10 23:57:17.834332 containerd[1825]: time="2025-09-10T23:57:17.834308155Z" level=info msg="connecting to shim 1104ff75b358e2ee1baeb482d6ef88d5374f809ee8fd8979fe7cea658373a2ec" address="unix:///run/containerd/s/747d4a4353d86996f54cb0004e87c946cec192abbb60a68a72971fe3fda31131" protocol=ttrpc version=3 Sep 10 23:57:17.855245 systemd[1]: Started cri-containerd-1104ff75b358e2ee1baeb482d6ef88d5374f809ee8fd8979fe7cea658373a2ec.scope - libcontainer container 1104ff75b358e2ee1baeb482d6ef88d5374f809ee8fd8979fe7cea658373a2ec. Sep 10 23:57:17.882509 containerd[1825]: time="2025-09-10T23:57:17.882275162Z" level=info msg="StartContainer for \"1104ff75b358e2ee1baeb482d6ef88d5374f809ee8fd8979fe7cea658373a2ec\" returns successfully" Sep 10 23:57:18.607588 kubelet[2404]: E0910 23:57:18.607535 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:18.630345 systemd-networkd[1619]: veth53a97f83: Gained IPv6LL Sep 10 23:57:19.608002 kubelet[2404]: E0910 23:57:19.607954 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:20.609081 kubelet[2404]: E0910 23:57:20.609031 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:21.610242 kubelet[2404]: E0910 23:57:21.610192 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:22.584053 kubelet[2404]: E0910 23:57:22.583995 2404 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:22.611356 kubelet[2404]: E0910 23:57:22.611326 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:23.611941 kubelet[2404]: E0910 23:57:23.611903 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:57:24.612425 kubelet[2404]: E0910 23:57:24.612376 2404 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"