Mar 12 02:57:19.054415 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Mar 12 02:57:19.054432 kernel: Linux version 6.12.74-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT Wed Mar 11 22:58:42 -00 2026 Mar 12 02:57:19.054439 kernel: KASLR enabled Mar 12 02:57:19.054443 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Mar 12 02:57:19.054446 kernel: printk: legacy bootconsole [pl11] enabled Mar 12 02:57:19.054451 kernel: efi: EFI v2.7 by EDK II Mar 12 02:57:19.054456 kernel: efi: ACPI 2.0=0x3f979018 SMBIOS=0x3f8a0000 SMBIOS 3.0=0x3f880000 MEMATTR=0x3e89d018 RNG=0x3f979998 MEMRESERVE=0x3db83598 Mar 12 02:57:19.054460 kernel: random: crng init done Mar 12 02:57:19.054464 kernel: secureboot: Secure boot disabled Mar 12 02:57:19.054468 kernel: ACPI: Early table checksum verification disabled Mar 12 02:57:19.054472 kernel: ACPI: RSDP 0x000000003F979018 000024 (v02 VRTUAL) Mar 12 02:57:19.054476 kernel: ACPI: XSDT 0x000000003F979F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054479 kernel: ACPI: FACP 0x000000003F979C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054483 kernel: ACPI: DSDT 0x000000003F95A018 01E046 (v02 MSFTVM DSDT01 00000001 INTL 20230628) Mar 12 02:57:19.054489 kernel: ACPI: DBG2 0x000000003F979B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054493 kernel: ACPI: GTDT 0x000000003F979D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054498 kernel: ACPI: OEM0 0x000000003F979098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054502 kernel: ACPI: SPCR 0x000000003F979A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054506 kernel: ACPI: APIC 0x000000003F979818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054511 kernel: ACPI: SRAT 0x000000003F979198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054516 kernel: ACPI: PPTT 0x000000003F979418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Mar 12 02:57:19.054520 kernel: ACPI: BGRT 0x000000003F979E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 12 02:57:19.054524 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Mar 12 02:57:19.054528 kernel: ACPI: Use ACPI SPCR as default console: Yes Mar 12 02:57:19.054532 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Mar 12 02:57:19.054537 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Mar 12 02:57:19.054541 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Mar 12 02:57:19.054545 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Mar 12 02:57:19.054549 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Mar 12 02:57:19.054553 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Mar 12 02:57:19.054558 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Mar 12 02:57:19.054562 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Mar 12 02:57:19.054567 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Mar 12 02:57:19.054571 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Mar 12 02:57:19.054575 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Mar 12 02:57:19.054579 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Mar 12 02:57:19.054583 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Mar 12 02:57:19.054587 kernel: NODE_DATA(0) allocated [mem 0x1bf7ffa00-0x1bf806fff] Mar 12 02:57:19.054591 kernel: Zone ranges: Mar 12 02:57:19.054596 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Mar 12 02:57:19.054602 kernel: DMA32 empty Mar 12 02:57:19.054606 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Mar 12 02:57:19.054611 kernel: Device empty Mar 12 02:57:19.054615 kernel: Movable zone start for each node Mar 12 02:57:19.054619 kernel: Early memory node ranges Mar 12 02:57:19.054624 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Mar 12 02:57:19.054629 kernel: node 0: [mem 0x0000000000824000-0x000000003f38ffff] Mar 12 02:57:19.054633 kernel: node 0: [mem 0x000000003f390000-0x000000003f93ffff] Mar 12 02:57:19.054638 kernel: node 0: [mem 0x000000003f940000-0x000000003f9effff] Mar 12 02:57:19.054642 kernel: node 0: [mem 0x000000003f9f0000-0x000000003fdeffff] Mar 12 02:57:19.054646 kernel: node 0: [mem 0x000000003fdf0000-0x000000003fffffff] Mar 12 02:57:19.054651 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Mar 12 02:57:19.054655 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Mar 12 02:57:19.054659 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Mar 12 02:57:19.054664 kernel: cma: Reserved 16 MiB at 0x000000003ca00000 on node -1 Mar 12 02:57:19.054668 kernel: psci: probing for conduit method from ACPI. Mar 12 02:57:19.054672 kernel: psci: PSCIv1.3 detected in firmware. Mar 12 02:57:19.054677 kernel: psci: Using standard PSCI v0.2 function IDs Mar 12 02:57:19.054682 kernel: psci: MIGRATE_INFO_TYPE not supported. Mar 12 02:57:19.054686 kernel: psci: SMC Calling Convention v1.4 Mar 12 02:57:19.054690 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Mar 12 02:57:19.054695 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Mar 12 02:57:19.054699 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Mar 12 02:57:19.054703 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Mar 12 02:57:19.054708 kernel: pcpu-alloc: [0] 0 [0] 1 Mar 12 02:57:19.054712 kernel: Detected PIPT I-cache on CPU0 Mar 12 02:57:19.054717 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Mar 12 02:57:19.054721 kernel: CPU features: detected: GIC system register CPU interface Mar 12 02:57:19.054725 kernel: CPU features: detected: Spectre-v4 Mar 12 02:57:19.054730 kernel: CPU features: detected: Spectre-BHB Mar 12 02:57:19.054735 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 12 02:57:19.054739 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 12 02:57:19.054744 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Mar 12 02:57:19.054748 kernel: CPU features: detected: SSBS not fully self-synchronizing Mar 12 02:57:19.054752 kernel: alternatives: applying boot alternatives Mar 12 02:57:19.054757 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=2acf88d04fc3ef96b26cdc5f6b546a4363b33b9eef9645fad2961c4f57aac66f Mar 12 02:57:19.054762 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 12 02:57:19.054767 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 12 02:57:19.054771 kernel: Fallback order for Node 0: 0 Mar 12 02:57:19.054775 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Mar 12 02:57:19.054780 kernel: Policy zone: Normal Mar 12 02:57:19.054785 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 12 02:57:19.054789 kernel: software IO TLB: area num 2. Mar 12 02:57:19.054793 kernel: software IO TLB: mapped [mem 0x0000000035900000-0x0000000039900000] (64MB) Mar 12 02:57:19.054798 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Mar 12 02:57:19.054802 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 12 02:57:19.054807 kernel: rcu: RCU event tracing is enabled. Mar 12 02:57:19.054812 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Mar 12 02:57:19.054816 kernel: Trampoline variant of Tasks RCU enabled. Mar 12 02:57:19.054821 kernel: Tracing variant of Tasks RCU enabled. Mar 12 02:57:19.054825 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 12 02:57:19.054829 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Mar 12 02:57:19.054834 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Mar 12 02:57:19.054839 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Mar 12 02:57:19.054843 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 12 02:57:19.054848 kernel: GICv3: 960 SPIs implemented Mar 12 02:57:19.054852 kernel: GICv3: 0 Extended SPIs implemented Mar 12 02:57:19.054856 kernel: Root IRQ handler: gic_handle_irq Mar 12 02:57:19.054861 kernel: GICv3: GICv3 features: 16 PPIs, RSS Mar 12 02:57:19.054865 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Mar 12 02:57:19.054869 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Mar 12 02:57:19.054874 kernel: ITS: No ITS available, not enabling LPIs Mar 12 02:57:19.054878 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 12 02:57:19.054883 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Mar 12 02:57:19.054888 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 12 02:57:19.054892 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Mar 12 02:57:19.054897 kernel: Console: colour dummy device 80x25 Mar 12 02:57:19.054901 kernel: printk: legacy console [tty1] enabled Mar 12 02:57:19.054906 kernel: ACPI: Core revision 20240827 Mar 12 02:57:19.054910 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Mar 12 02:57:19.054915 kernel: pid_max: default: 32768 minimum: 301 Mar 12 02:57:19.054920 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Mar 12 02:57:19.054924 kernel: landlock: Up and running. Mar 12 02:57:19.054929 kernel: SELinux: Initializing. Mar 12 02:57:19.054934 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 12 02:57:19.054938 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 12 02:57:19.054952 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0xa0000e, misc 0x31e1 Mar 12 02:57:19.054957 kernel: Hyper-V: Host Build 10.0.26102.1212-1-0 Mar 12 02:57:19.054965 kernel: Hyper-V: enabling crash_kexec_post_notifiers Mar 12 02:57:19.054971 kernel: rcu: Hierarchical SRCU implementation. Mar 12 02:57:19.054975 kernel: rcu: Max phase no-delay instances is 400. Mar 12 02:57:19.054980 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Mar 12 02:57:19.054985 kernel: Remapping and enabling EFI services. Mar 12 02:57:19.054990 kernel: smp: Bringing up secondary CPUs ... Mar 12 02:57:19.054995 kernel: Detected PIPT I-cache on CPU1 Mar 12 02:57:19.055000 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Mar 12 02:57:19.055005 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Mar 12 02:57:19.055010 kernel: smp: Brought up 1 node, 2 CPUs Mar 12 02:57:19.055014 kernel: SMP: Total of 2 processors activated. Mar 12 02:57:19.055019 kernel: CPU: All CPU(s) started at EL1 Mar 12 02:57:19.055025 kernel: CPU features: detected: 32-bit EL0 Support Mar 12 02:57:19.055030 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Mar 12 02:57:19.055034 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 12 02:57:19.055039 kernel: CPU features: detected: Common not Private translations Mar 12 02:57:19.055044 kernel: CPU features: detected: CRC32 instructions Mar 12 02:57:19.055049 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Mar 12 02:57:19.055053 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Mar 12 02:57:19.055058 kernel: CPU features: detected: LSE atomic instructions Mar 12 02:57:19.055063 kernel: CPU features: detected: Privileged Access Never Mar 12 02:57:19.055068 kernel: CPU features: detected: Speculation barrier (SB) Mar 12 02:57:19.055073 kernel: CPU features: detected: TLB range maintenance instructions Mar 12 02:57:19.055078 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Mar 12 02:57:19.055083 kernel: CPU features: detected: Scalable Vector Extension Mar 12 02:57:19.055087 kernel: alternatives: applying system-wide alternatives Mar 12 02:57:19.055092 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Mar 12 02:57:19.055097 kernel: SVE: maximum available vector length 16 bytes per vector Mar 12 02:57:19.055102 kernel: SVE: default vector length 16 bytes per vector Mar 12 02:57:19.055107 kernel: Memory: 3952828K/4194160K available (11200K kernel code, 2458K rwdata, 9088K rodata, 39552K init, 1038K bss, 220144K reserved, 16384K cma-reserved) Mar 12 02:57:19.055112 kernel: devtmpfs: initialized Mar 12 02:57:19.055117 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 12 02:57:19.055122 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Mar 12 02:57:19.055127 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Mar 12 02:57:19.055131 kernel: 0 pages in range for non-PLT usage Mar 12 02:57:19.055136 kernel: 508400 pages in range for PLT usage Mar 12 02:57:19.055141 kernel: pinctrl core: initialized pinctrl subsystem Mar 12 02:57:19.055146 kernel: SMBIOS 3.1.0 present. Mar 12 02:57:19.055151 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 06/10/2025 Mar 12 02:57:19.055156 kernel: DMI: Memory slots populated: 2/2 Mar 12 02:57:19.055161 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 12 02:57:19.055165 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 12 02:57:19.055170 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 12 02:57:19.055175 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 12 02:57:19.055180 kernel: audit: initializing netlink subsys (disabled) Mar 12 02:57:19.055185 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Mar 12 02:57:19.055189 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 12 02:57:19.055195 kernel: cpuidle: using governor menu Mar 12 02:57:19.055200 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 12 02:57:19.055204 kernel: ASID allocator initialised with 32768 entries Mar 12 02:57:19.055209 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 12 02:57:19.055214 kernel: Serial: AMBA PL011 UART driver Mar 12 02:57:19.055218 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 12 02:57:19.055223 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Mar 12 02:57:19.055228 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Mar 12 02:57:19.055233 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Mar 12 02:57:19.055238 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 12 02:57:19.055243 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Mar 12 02:57:19.055248 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Mar 12 02:57:19.055252 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Mar 12 02:57:19.055257 kernel: ACPI: Added _OSI(Module Device) Mar 12 02:57:19.055262 kernel: ACPI: Added _OSI(Processor Device) Mar 12 02:57:19.055266 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 12 02:57:19.055271 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 12 02:57:19.055276 kernel: ACPI: Interpreter enabled Mar 12 02:57:19.055281 kernel: ACPI: Using GIC for interrupt routing Mar 12 02:57:19.055286 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Mar 12 02:57:19.055291 kernel: printk: legacy console [ttyAMA0] enabled Mar 12 02:57:19.055296 kernel: printk: legacy bootconsole [pl11] disabled Mar 12 02:57:19.055300 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Mar 12 02:57:19.055305 kernel: ACPI: CPU0 has been hot-added Mar 12 02:57:19.055310 kernel: ACPI: CPU1 has been hot-added Mar 12 02:57:19.055314 kernel: iommu: Default domain type: Translated Mar 12 02:57:19.055319 kernel: iommu: DMA domain TLB invalidation policy: strict mode Mar 12 02:57:19.055325 kernel: efivars: Registered efivars operations Mar 12 02:57:19.055329 kernel: vgaarb: loaded Mar 12 02:57:19.055334 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 12 02:57:19.055339 kernel: VFS: Disk quotas dquot_6.6.0 Mar 12 02:57:19.055343 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 12 02:57:19.055348 kernel: pnp: PnP ACPI init Mar 12 02:57:19.055353 kernel: pnp: PnP ACPI: found 0 devices Mar 12 02:57:19.055357 kernel: NET: Registered PF_INET protocol family Mar 12 02:57:19.055362 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 12 02:57:19.055367 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 12 02:57:19.055373 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 12 02:57:19.055377 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 12 02:57:19.055382 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 12 02:57:19.055387 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 12 02:57:19.055392 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 12 02:57:19.055397 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 12 02:57:19.055401 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 12 02:57:19.055406 kernel: PCI: CLS 0 bytes, default 64 Mar 12 02:57:19.055411 kernel: kvm [1]: HYP mode not available Mar 12 02:57:19.055416 kernel: Initialise system trusted keyrings Mar 12 02:57:19.055421 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 12 02:57:19.055426 kernel: Key type asymmetric registered Mar 12 02:57:19.055430 kernel: Asymmetric key parser 'x509' registered Mar 12 02:57:19.055435 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 12 02:57:19.055440 kernel: io scheduler mq-deadline registered Mar 12 02:57:19.055445 kernel: io scheduler kyber registered Mar 12 02:57:19.055449 kernel: io scheduler bfq registered Mar 12 02:57:19.055454 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 12 02:57:19.055460 kernel: thunder_xcv, ver 1.0 Mar 12 02:57:19.055464 kernel: thunder_bgx, ver 1.0 Mar 12 02:57:19.055469 kernel: nicpf, ver 1.0 Mar 12 02:57:19.055474 kernel: nicvf, ver 1.0 Mar 12 02:57:19.055570 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 12 02:57:19.055627 kernel: rtc-efi rtc-efi.0: setting system clock to 2026-03-12T02:57:18 UTC (1773284238) Mar 12 02:57:19.055634 kernel: efifb: probing for efifb Mar 12 02:57:19.055640 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 12 02:57:19.055644 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 12 02:57:19.055649 kernel: efifb: scrolling: redraw Mar 12 02:57:19.055654 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 12 02:57:19.055659 kernel: Console: switching to colour frame buffer device 128x48 Mar 12 02:57:19.055663 kernel: fb0: EFI VGA frame buffer device Mar 12 02:57:19.055668 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Mar 12 02:57:19.055673 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 12 02:57:19.055678 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Mar 12 02:57:19.055683 kernel: NET: Registered PF_INET6 protocol family Mar 12 02:57:19.055688 kernel: watchdog: NMI not fully supported Mar 12 02:57:19.055693 kernel: watchdog: Hard watchdog permanently disabled Mar 12 02:57:19.055698 kernel: Segment Routing with IPv6 Mar 12 02:57:19.055702 kernel: In-situ OAM (IOAM) with IPv6 Mar 12 02:57:19.055707 kernel: NET: Registered PF_PACKET protocol family Mar 12 02:57:19.055712 kernel: Key type dns_resolver registered Mar 12 02:57:19.055716 kernel: registered taskstats version 1 Mar 12 02:57:19.055721 kernel: Loading compiled-in X.509 certificates Mar 12 02:57:19.055726 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.74-flatcar: 5af49ccdcfac64f04a0fbbbc8f2f4ea7a0542b05' Mar 12 02:57:19.055732 kernel: Demotion targets for Node 0: null Mar 12 02:57:19.055736 kernel: Key type .fscrypt registered Mar 12 02:57:19.055741 kernel: Key type fscrypt-provisioning registered Mar 12 02:57:19.055746 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 12 02:57:19.055750 kernel: ima: Allocated hash algorithm: sha1 Mar 12 02:57:19.055755 kernel: ima: No architecture policies found Mar 12 02:57:19.055760 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Mar 12 02:57:19.055765 kernel: clk: Disabling unused clocks Mar 12 02:57:19.055769 kernel: PM: genpd: Disabling unused power domains Mar 12 02:57:19.055775 kernel: Warning: unable to open an initial console. Mar 12 02:57:19.055780 kernel: Freeing unused kernel memory: 39552K Mar 12 02:57:19.055784 kernel: Run /init as init process Mar 12 02:57:19.055789 kernel: with arguments: Mar 12 02:57:19.055794 kernel: /init Mar 12 02:57:19.055798 kernel: with environment: Mar 12 02:57:19.055803 kernel: HOME=/ Mar 12 02:57:19.055808 kernel: TERM=linux Mar 12 02:57:19.055813 systemd[1]: Successfully made /usr/ read-only. Mar 12 02:57:19.055821 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) Mar 12 02:57:19.055826 systemd[1]: Detected virtualization microsoft. Mar 12 02:57:19.055831 systemd[1]: Detected architecture arm64. Mar 12 02:57:19.055836 systemd[1]: Running in initrd. Mar 12 02:57:19.055841 systemd[1]: No hostname configured, using default hostname. Mar 12 02:57:19.055847 systemd[1]: Hostname set to . Mar 12 02:57:19.055852 systemd[1]: Initializing machine ID from random generator. Mar 12 02:57:19.055857 systemd[1]: Queued start job for default target initrd.target. Mar 12 02:57:19.055863 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 12 02:57:19.055868 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 12 02:57:19.055873 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 12 02:57:19.055879 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 12 02:57:19.055884 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 12 02:57:19.055890 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 12 02:57:19.055896 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 12 02:57:19.055902 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 12 02:57:19.055907 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 12 02:57:19.055912 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 12 02:57:19.055917 systemd[1]: Reached target paths.target - Path Units. Mar 12 02:57:19.055923 systemd[1]: Reached target slices.target - Slice Units. Mar 12 02:57:19.055928 systemd[1]: Reached target swap.target - Swaps. Mar 12 02:57:19.055933 systemd[1]: Reached target timers.target - Timer Units. Mar 12 02:57:19.055939 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 12 02:57:19.055949 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 12 02:57:19.055954 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 12 02:57:19.055960 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 12 02:57:19.055965 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 12 02:57:19.055970 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 12 02:57:19.055975 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 12 02:57:19.055980 systemd[1]: Reached target sockets.target - Socket Units. Mar 12 02:57:19.055986 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 12 02:57:19.055992 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 12 02:57:19.055997 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 12 02:57:19.056002 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). Mar 12 02:57:19.056008 systemd[1]: Starting systemd-fsck-usr.service... Mar 12 02:57:19.056013 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 12 02:57:19.056018 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 12 02:57:19.056033 systemd-journald[225]: Collecting audit messages is disabled. Mar 12 02:57:19.056047 systemd-journald[225]: Journal started Mar 12 02:57:19.056061 systemd-journald[225]: Runtime Journal (/run/log/journal/469556421483424cb42b8e248aa3ef3c) is 8M, max 78.3M, 70.3M free. Mar 12 02:57:19.068427 systemd-modules-load[227]: Inserted module 'overlay' Mar 12 02:57:19.077175 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 12 02:57:19.088954 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 12 02:57:19.098420 systemd[1]: Started systemd-journald.service - Journal Service. Mar 12 02:57:19.099516 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 12 02:57:19.110929 kernel: Bridge firewalling registered Mar 12 02:57:19.105657 systemd-modules-load[227]: Inserted module 'br_netfilter' Mar 12 02:57:19.108289 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 12 02:57:19.117187 systemd[1]: Finished systemd-fsck-usr.service. Mar 12 02:57:19.124591 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 12 02:57:19.133643 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 12 02:57:19.143238 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 12 02:57:19.157871 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 12 02:57:19.175053 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 12 02:57:19.187033 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 12 02:57:19.196957 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 12 02:57:19.205471 systemd-tmpfiles[249]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Mar 12 02:57:19.219138 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 12 02:57:19.224417 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 12 02:57:19.234692 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 12 02:57:19.247029 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 12 02:57:19.264057 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 12 02:57:19.274961 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 12 02:57:19.299020 dracut-cmdline[263]: 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=2acf88d04fc3ef96b26cdc5f6b546a4363b33b9eef9645fad2961c4f57aac66f Mar 12 02:57:19.292089 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 12 02:57:19.332193 systemd-resolved[267]: Positive Trust Anchors: Mar 12 02:57:19.332202 systemd-resolved[267]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 12 02:57:19.332220 systemd-resolved[267]: 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 Mar 12 02:57:19.333788 systemd-resolved[267]: Defaulting to hostname 'linux'. Mar 12 02:57:19.334827 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 12 02:57:19.340029 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 12 02:57:19.397954 kernel: SCSI subsystem initialized Mar 12 02:57:19.403955 kernel: Loading iSCSI transport class v2.0-870. Mar 12 02:57:19.411961 kernel: iscsi: registered transport (tcp) Mar 12 02:57:19.424460 kernel: iscsi: registered transport (qla4xxx) Mar 12 02:57:19.424470 kernel: QLogic iSCSI HBA Driver Mar 12 02:57:19.437301 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 12 02:57:19.459191 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 12 02:57:19.466212 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 12 02:57:19.514421 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 12 02:57:19.519635 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 12 02:57:19.592954 kernel: raid6: neonx8 gen() 18580 MB/s Mar 12 02:57:19.610952 kernel: raid6: neonx4 gen() 18572 MB/s Mar 12 02:57:19.629950 kernel: raid6: neonx2 gen() 17061 MB/s Mar 12 02:57:19.648967 kernel: raid6: neonx1 gen() 15070 MB/s Mar 12 02:57:19.668954 kernel: raid6: int64x8 gen() 10535 MB/s Mar 12 02:57:19.687949 kernel: raid6: int64x4 gen() 10608 MB/s Mar 12 02:57:19.706951 kernel: raid6: int64x2 gen() 8994 MB/s Mar 12 02:57:19.728901 kernel: raid6: int64x1 gen() 7004 MB/s Mar 12 02:57:19.728911 kernel: raid6: using algorithm neonx8 gen() 18580 MB/s Mar 12 02:57:19.751428 kernel: raid6: .... xor() 14902 MB/s, rmw enabled Mar 12 02:57:19.751479 kernel: raid6: using neon recovery algorithm Mar 12 02:57:19.759322 kernel: xor: measuring software checksum speed Mar 12 02:57:19.759329 kernel: 8regs : 28661 MB/sec Mar 12 02:57:19.761816 kernel: 32regs : 28775 MB/sec Mar 12 02:57:19.764283 kernel: arm64_neon : 37559 MB/sec Mar 12 02:57:19.767503 kernel: xor: using function: arm64_neon (37559 MB/sec) Mar 12 02:57:19.805964 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 12 02:57:19.811344 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 12 02:57:19.822118 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 12 02:57:19.847334 systemd-udevd[478]: Using default interface naming scheme 'v255'. Mar 12 02:57:19.851252 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 12 02:57:19.862563 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 12 02:57:19.895602 dracut-pre-trigger[488]: rd.md=0: removing MD RAID activation Mar 12 02:57:19.913065 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 12 02:57:19.923169 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 12 02:57:19.966116 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 12 02:57:19.979860 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 12 02:57:20.038790 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 12 02:57:20.071457 kernel: hv_vmbus: Vmbus version:5.3 Mar 12 02:57:20.071475 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 12 02:57:20.071482 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 12 02:57:20.071488 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 Mar 12 02:57:20.071495 kernel: hv_vmbus: registering driver hid_hyperv Mar 12 02:57:20.038905 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 12 02:57:20.085225 kernel: hv_vmbus: registering driver hv_netvsc Mar 12 02:57:20.066883 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 12 02:57:20.096030 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 12 02:57:20.080749 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 12 02:57:20.123072 kernel: hv_vmbus: registering driver hv_storvsc Mar 12 02:57:20.123096 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 12 02:57:20.123104 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 12 02:57:20.123220 kernel: scsi host1: storvsc_host_t Mar 12 02:57:20.123238 kernel: scsi host0: storvsc_host_t Mar 12 02:57:20.131277 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 12 02:57:20.137971 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Mar 12 02:57:20.138868 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 12 02:57:20.160241 kernel: hv_netvsc 000d3a6d-ff73-000d-3a6d-ff73000d3a6d eth0: VF slot 1 added Mar 12 02:57:20.160379 kernel: PTP clock support registered Mar 12 02:57:20.174006 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 12 02:57:20.174161 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 12 02:57:20.177073 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 12 02:57:20.184996 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 12 02:57:20.185149 kernel: hv_utils: Registering HyperV Utility Driver Mar 12 02:57:20.185157 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 12 02:57:20.185232 kernel: hv_vmbus: registering driver hv_utils Mar 12 02:57:20.190932 kernel: hv_vmbus: registering driver hv_pci Mar 12 02:57:20.190968 kernel: hv_utils: Shutdown IC version 3.2 Mar 12 02:57:20.197399 kernel: hv_utils: Heartbeat IC version 3.0 Mar 12 02:57:20.200185 kernel: hv_utils: TimeSync IC version 4.0 Mar 12 02:57:20.290284 kernel: hv_pci 82dd4b01-bc0e-45aa-b84f-a40c5a812e54: PCI VMBus probing: Using version 0x10004 Mar 12 02:57:20.285449 systemd-resolved[267]: Clock change detected. Flushing caches. Mar 12 02:57:20.312597 kernel: hv_pci 82dd4b01-bc0e-45aa-b84f-a40c5a812e54: PCI host bridge to bus bc0e:00 Mar 12 02:57:20.312732 kernel: pci_bus bc0e:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Mar 12 02:57:20.317078 kernel: pci_bus bc0e:00: No busn resource found for root bus, will use [bus 00-ff] Mar 12 02:57:20.322996 kernel: pci bc0e:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Mar 12 02:57:20.323032 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 12 02:57:20.329011 kernel: pci bc0e:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Mar 12 02:57:20.329031 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 12 02:57:20.335373 kernel: pci bc0e:00:02.0: enabling Extended Tags Mar 12 02:57:20.353136 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 12 02:57:20.353306 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 12 02:57:20.353316 kernel: pci bc0e:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at bc0e:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Mar 12 02:57:20.355070 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 12 02:57:20.364395 kernel: pci_bus bc0e:00: busn_res: [bus 00-ff] end is updated to 00 Mar 12 02:57:20.364522 kernel: pci bc0e:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Mar 12 02:57:20.378971 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#146 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Mar 12 02:57:20.400990 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#183 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Mar 12 02:57:20.429404 kernel: mlx5_core bc0e:00:02.0: enabling device (0000 -> 0002) Mar 12 02:57:20.437236 kernel: mlx5_core bc0e:00:02.0: PTM is not supported by PCIe Mar 12 02:57:20.437342 kernel: mlx5_core bc0e:00:02.0: firmware version: 16.30.5026 Mar 12 02:57:20.612539 kernel: hv_netvsc 000d3a6d-ff73-000d-3a6d-ff73000d3a6d eth0: VF registering: eth1 Mar 12 02:57:20.612747 kernel: mlx5_core bc0e:00:02.0 eth1: joined to eth0 Mar 12 02:57:20.619033 kernel: mlx5_core bc0e:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Mar 12 02:57:20.629068 kernel: mlx5_core bc0e:00:02.0 enP48142s1: renamed from eth1 Mar 12 02:57:20.840016 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Mar 12 02:57:20.959331 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Mar 12 02:57:20.964841 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Mar 12 02:57:20.981498 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Mar 12 02:57:20.991368 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 12 02:57:21.002898 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 12 02:57:21.012280 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 12 02:57:21.017194 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 12 02:57:21.026779 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 12 02:57:21.036120 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 12 02:57:21.061660 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 12 02:57:21.089689 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 12 02:57:21.095620 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 12 02:57:22.116008 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 12 02:57:22.116474 disk-uuid[656]: The operation has completed successfully. Mar 12 02:57:22.186948 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 12 02:57:22.187034 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 12 02:57:22.210364 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 12 02:57:22.231090 sh[822]: Success Mar 12 02:57:22.264459 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 12 02:57:22.264495 kernel: device-mapper: uevent: version 1.0.3 Mar 12 02:57:22.269464 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Mar 12 02:57:22.278975 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Mar 12 02:57:22.531294 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 12 02:57:22.545288 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 12 02:57:22.549893 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 12 02:57:22.576738 kernel: BTRFS: device fsid 367033b5-6658-46e0-b104-cd609725a5d6 devid 1 transid 35 /dev/mapper/usr (254:0) scanned by mount (840) Mar 12 02:57:22.576769 kernel: BTRFS info (device dm-0): first mount of filesystem 367033b5-6658-46e0-b104-cd609725a5d6 Mar 12 02:57:22.581356 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Mar 12 02:57:22.862583 kernel: BTRFS info (device dm-0 state E): disabling log replay at mount time Mar 12 02:57:22.862657 kernel: BTRFS info (device dm-0 state E): enabling free space tree Mar 12 02:57:22.896799 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 12 02:57:22.900878 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Mar 12 02:57:22.908732 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 12 02:57:22.909385 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 12 02:57:22.934851 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 12 02:57:22.967007 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (873) Mar 12 02:57:22.977493 kernel: BTRFS info (device sda6): first mount of filesystem 46247c0a-a0c4-47ba-b6b0-658854ed6c55 Mar 12 02:57:22.977539 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 12 02:57:23.003006 kernel: BTRFS info (device sda6): turning on async discard Mar 12 02:57:23.003346 kernel: BTRFS info (device sda6): enabling free space tree Mar 12 02:57:23.012009 kernel: BTRFS info (device sda6): last unmount of filesystem 46247c0a-a0c4-47ba-b6b0-658854ed6c55 Mar 12 02:57:23.014025 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 12 02:57:23.019375 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 12 02:57:23.057601 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 12 02:57:23.070336 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 12 02:57:23.104417 systemd-networkd[1009]: lo: Link UP Mar 12 02:57:23.104427 systemd-networkd[1009]: lo: Gained carrier Mar 12 02:57:23.105549 systemd-networkd[1009]: Enumeration completed Mar 12 02:57:23.106150 systemd-networkd[1009]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 12 02:57:23.106153 systemd-networkd[1009]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 12 02:57:23.107181 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 12 02:57:23.115180 systemd[1]: Reached target network.target - Network. Mar 12 02:57:23.181981 kernel: mlx5_core bc0e:00:02.0 enP48142s1: Link up Mar 12 02:57:23.213140 kernel: hv_netvsc 000d3a6d-ff73-000d-3a6d-ff73000d3a6d eth0: Data path switched to VF: enP48142s1 Mar 12 02:57:23.212792 systemd-networkd[1009]: enP48142s1: Link UP Mar 12 02:57:23.212850 systemd-networkd[1009]: eth0: Link UP Mar 12 02:57:23.212938 systemd-networkd[1009]: eth0: Gained carrier Mar 12 02:57:23.212952 systemd-networkd[1009]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 12 02:57:23.223105 systemd-networkd[1009]: enP48142s1: Gained carrier Mar 12 02:57:23.237992 systemd-networkd[1009]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 12 02:57:24.004006 ignition[966]: Ignition 2.22.0 Mar 12 02:57:24.004019 ignition[966]: Stage: fetch-offline Mar 12 02:57:24.008046 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 12 02:57:24.004132 ignition[966]: no configs at "/usr/lib/ignition/base.d" Mar 12 02:57:24.015177 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Mar 12 02:57:24.004138 ignition[966]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 12 02:57:24.004223 ignition[966]: parsed url from cmdline: "" Mar 12 02:57:24.004228 ignition[966]: no config URL provided Mar 12 02:57:24.004231 ignition[966]: reading system config file "/usr/lib/ignition/user.ign" Mar 12 02:57:24.004236 ignition[966]: no config at "/usr/lib/ignition/user.ign" Mar 12 02:57:24.004239 ignition[966]: failed to fetch config: resource requires networking Mar 12 02:57:24.004376 ignition[966]: Ignition finished successfully Mar 12 02:57:24.054089 ignition[1020]: Ignition 2.22.0 Mar 12 02:57:24.054099 ignition[1020]: Stage: fetch Mar 12 02:57:24.054288 ignition[1020]: no configs at "/usr/lib/ignition/base.d" Mar 12 02:57:24.054295 ignition[1020]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 12 02:57:24.054360 ignition[1020]: parsed url from cmdline: "" Mar 12 02:57:24.054362 ignition[1020]: no config URL provided Mar 12 02:57:24.054365 ignition[1020]: reading system config file "/usr/lib/ignition/user.ign" Mar 12 02:57:24.054371 ignition[1020]: no config at "/usr/lib/ignition/user.ign" Mar 12 02:57:24.054385 ignition[1020]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 12 02:57:24.124027 ignition[1020]: GET result: OK Mar 12 02:57:24.124067 ignition[1020]: config has been read from IMDS userdata Mar 12 02:57:24.126024 unknown[1020]: fetched base config from "system" Mar 12 02:57:24.124084 ignition[1020]: parsing config with SHA512: fd25b8a239ff0ae8ea9820ec22d46bc448ba067b10efeb3b72b6a87fb9e11ed606251a59786f87f99c9259a77477c2d3895f3eee7feb5f9d51831fd05c345acf Mar 12 02:57:24.126029 unknown[1020]: fetched base config from "system" Mar 12 02:57:24.126230 ignition[1020]: fetch: fetch complete Mar 12 02:57:24.126032 unknown[1020]: fetched user config from "azure" Mar 12 02:57:24.126233 ignition[1020]: fetch: fetch passed Mar 12 02:57:24.131478 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Mar 12 02:57:24.126272 ignition[1020]: Ignition finished successfully Mar 12 02:57:24.139230 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 12 02:57:24.175024 ignition[1027]: Ignition 2.22.0 Mar 12 02:57:24.175036 ignition[1027]: Stage: kargs Mar 12 02:57:24.175195 ignition[1027]: no configs at "/usr/lib/ignition/base.d" Mar 12 02:57:24.180633 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 12 02:57:24.175202 ignition[1027]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 12 02:57:24.190082 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 12 02:57:24.175563 ignition[1027]: kargs: kargs passed Mar 12 02:57:24.175594 ignition[1027]: Ignition finished successfully Mar 12 02:57:24.226514 ignition[1034]: Ignition 2.22.0 Mar 12 02:57:24.226529 ignition[1034]: Stage: disks Mar 12 02:57:24.230549 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 12 02:57:24.226683 ignition[1034]: no configs at "/usr/lib/ignition/base.d" Mar 12 02:57:24.237791 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 12 02:57:24.226690 ignition[1034]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 12 02:57:24.246895 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 12 02:57:24.227035 ignition[1034]: disks: disks passed Mar 12 02:57:24.256482 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 12 02:57:24.227067 ignition[1034]: Ignition finished successfully Mar 12 02:57:24.266275 systemd[1]: Reached target sysinit.target - System Initialization. Mar 12 02:57:24.275996 systemd[1]: Reached target basic.target - Basic System. Mar 12 02:57:24.286496 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 12 02:57:24.375766 systemd-fsck[1042]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Mar 12 02:57:24.383467 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 12 02:57:24.389763 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 12 02:57:24.636979 kernel: EXT4-fs (sda9): mounted filesystem ee35d325-c1b4-4946-897e-e080dd3c2049 r/w with ordered data mode. Quota mode: none. Mar 12 02:57:24.637115 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 12 02:57:24.640834 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 12 02:57:24.663761 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 12 02:57:24.678329 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 12 02:57:24.692730 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Mar 12 02:57:24.709471 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1056) Mar 12 02:57:24.709758 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 12 02:57:24.712042 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 12 02:57:24.733406 kernel: BTRFS info (device sda6): first mount of filesystem 46247c0a-a0c4-47ba-b6b0-658854ed6c55 Mar 12 02:57:24.733422 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 12 02:57:24.739864 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 12 02:57:24.745068 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 12 02:57:24.764749 kernel: BTRFS info (device sda6): turning on async discard Mar 12 02:57:24.764770 kernel: BTRFS info (device sda6): enabling free space tree Mar 12 02:57:24.762007 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 12 02:57:25.191067 coreos-metadata[1058]: Mar 12 02:57:25.191 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 12 02:57:25.199592 coreos-metadata[1058]: Mar 12 02:57:25.199 INFO Fetch successful Mar 12 02:57:25.203963 coreos-metadata[1058]: Mar 12 02:57:25.203 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 12 02:57:25.212984 coreos-metadata[1058]: Mar 12 02:57:25.212 INFO Fetch successful Mar 12 02:57:25.225341 coreos-metadata[1058]: Mar 12 02:57:25.225 INFO wrote hostname ci-4459.2.4-n-97f68a91ee to /sysroot/etc/hostname Mar 12 02:57:25.232745 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 12 02:57:25.285083 systemd-networkd[1009]: eth0: Gained IPv6LL Mar 12 02:57:25.488281 initrd-setup-root[1086]: cut: /sysroot/etc/passwd: No such file or directory Mar 12 02:57:25.524390 initrd-setup-root[1093]: cut: /sysroot/etc/group: No such file or directory Mar 12 02:57:25.530288 initrd-setup-root[1100]: cut: /sysroot/etc/shadow: No such file or directory Mar 12 02:57:25.551294 initrd-setup-root[1107]: cut: /sysroot/etc/gshadow: No such file or directory Mar 12 02:57:26.556638 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 12 02:57:26.562813 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 12 02:57:26.592603 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 12 02:57:26.608644 kernel: BTRFS info (device sda6): last unmount of filesystem 46247c0a-a0c4-47ba-b6b0-658854ed6c55 Mar 12 02:57:26.606700 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 12 02:57:26.631615 ignition[1174]: INFO : Ignition 2.22.0 Mar 12 02:57:26.631615 ignition[1174]: INFO : Stage: mount Mar 12 02:57:26.631615 ignition[1174]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 12 02:57:26.631615 ignition[1174]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 12 02:57:26.657927 ignition[1174]: INFO : mount: mount passed Mar 12 02:57:26.657927 ignition[1174]: INFO : Ignition finished successfully Mar 12 02:57:26.634835 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 12 02:57:26.642139 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 12 02:57:26.664020 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 12 02:57:26.674335 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 12 02:57:26.705975 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1186) Mar 12 02:57:26.716287 kernel: BTRFS info (device sda6): first mount of filesystem 46247c0a-a0c4-47ba-b6b0-658854ed6c55 Mar 12 02:57:26.716316 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 12 02:57:26.725660 kernel: BTRFS info (device sda6): turning on async discard Mar 12 02:57:26.725683 kernel: BTRFS info (device sda6): enabling free space tree Mar 12 02:57:26.727121 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 12 02:57:26.756632 ignition[1203]: INFO : Ignition 2.22.0 Mar 12 02:57:26.756632 ignition[1203]: INFO : Stage: files Mar 12 02:57:26.763254 ignition[1203]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 12 02:57:26.763254 ignition[1203]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 12 02:57:26.763254 ignition[1203]: DEBUG : files: compiled without relabeling support, skipping Mar 12 02:57:26.763254 ignition[1203]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 12 02:57:26.763254 ignition[1203]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 12 02:57:26.831323 ignition[1203]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 12 02:57:26.836818 ignition[1203]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 12 02:57:26.836818 ignition[1203]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 12 02:57:26.831705 unknown[1203]: wrote ssh authorized keys file for user: core Mar 12 02:57:26.891098 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Mar 12 02:57:26.898647 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Mar 12 02:57:26.909439 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 12 02:57:26.916891 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 12 02:57:26.916891 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Mar 12 02:57:26.936250 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Mar 12 02:57:26.936250 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Mar 12 02:57:26.936250 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.35.1-arm64.raw: attempt #1 Mar 12 02:57:27.282126 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Mar 12 02:57:28.319922 ignition[1203]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Mar 12 02:57:28.330253 ignition[1203]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 12 02:57:28.330253 ignition[1203]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 12 02:57:28.330253 ignition[1203]: INFO : files: files passed Mar 12 02:57:28.330253 ignition[1203]: INFO : Ignition finished successfully Mar 12 02:57:28.330544 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 12 02:57:28.343236 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 12 02:57:28.372656 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 12 02:57:28.563576 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 12 02:57:28.563713 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 12 02:57:28.582904 initrd-setup-root-after-ignition[1232]: grep: Mar 12 02:57:28.587680 initrd-setup-root-after-ignition[1235]: grep: Mar 12 02:57:28.587680 initrd-setup-root-after-ignition[1232]: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 12 02:57:28.587680 initrd-setup-root-after-ignition[1232]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 12 02:57:28.608331 initrd-setup-root-after-ignition[1235]: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 12 02:57:28.601292 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 12 02:57:28.614034 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 12 02:57:28.626196 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 12 02:57:28.664557 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 12 02:57:28.664665 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 12 02:57:28.675880 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 12 02:57:28.686500 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 12 02:57:28.696792 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 12 02:57:28.697527 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 12 02:57:28.740114 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 12 02:57:28.747492 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 12 02:57:28.775095 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 12 02:57:28.780898 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 12 02:57:28.792429 systemd[1]: Stopped target timers.target - Timer Units. Mar 12 02:57:28.803410 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 12 02:57:28.803512 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 12 02:57:28.818235 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 12 02:57:28.823650 systemd[1]: Stopped target basic.target - Basic System. Mar 12 02:57:28.834864 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 12 02:57:28.845527 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 12 02:57:28.855604 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 12 02:57:28.867422 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Mar 12 02:57:28.879037 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 12 02:57:28.889127 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 12 02:57:28.900545 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 12 02:57:28.910669 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 12 02:57:28.920795 systemd[1]: Stopped target swap.target - Swaps. Mar 12 02:57:28.929365 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 12 02:57:28.929478 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 12 02:57:28.942432 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 12 02:57:28.947844 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 12 02:57:28.958860 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 12 02:57:28.958931 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 12 02:57:28.970151 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 12 02:57:28.970260 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 12 02:57:28.984634 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 12 02:57:28.984718 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 12 02:57:28.990609 systemd[1]: ignition-files.service: Deactivated successfully. Mar 12 02:57:28.990678 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 12 02:57:29.054451 ignition[1256]: INFO : Ignition 2.22.0 Mar 12 02:57:29.054451 ignition[1256]: INFO : Stage: umount Mar 12 02:57:29.054451 ignition[1256]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 12 02:57:29.054451 ignition[1256]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 12 02:57:29.054451 ignition[1256]: INFO : umount: umount passed Mar 12 02:57:29.054451 ignition[1256]: INFO : Ignition finished successfully Mar 12 02:57:28.998370 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 12 02:57:28.998433 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 12 02:57:29.009925 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 12 02:57:29.033892 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 12 02:57:29.041206 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 12 02:57:29.044996 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 12 02:57:29.052130 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 12 02:57:29.052209 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 12 02:57:29.067423 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 12 02:57:29.067519 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 12 02:57:29.074571 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 12 02:57:29.074652 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 12 02:57:29.083404 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 12 02:57:29.083444 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 12 02:57:29.093488 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 12 02:57:29.093519 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Mar 12 02:57:29.101836 systemd[1]: Stopped target network.target - Network. Mar 12 02:57:29.108882 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 12 02:57:29.108919 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 12 02:57:29.118499 systemd[1]: Stopped target paths.target - Path Units. Mar 12 02:57:29.127238 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 12 02:57:29.130980 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 12 02:57:29.136871 systemd[1]: Stopped target slices.target - Slice Units. Mar 12 02:57:29.144123 systemd[1]: Stopped target sockets.target - Socket Units. Mar 12 02:57:29.152191 systemd[1]: iscsid.socket: Deactivated successfully. Mar 12 02:57:29.152229 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 12 02:57:29.160665 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 12 02:57:29.160693 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 12 02:57:29.169407 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 12 02:57:29.169453 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 12 02:57:29.177987 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 12 02:57:29.178015 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 12 02:57:29.186244 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 12 02:57:29.194210 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 12 02:57:29.202422 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 12 02:57:29.202506 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 12 02:57:29.216954 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 12 02:57:29.217039 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 12 02:57:29.234162 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 12 02:57:29.413240 kernel: hv_netvsc 000d3a6d-ff73-000d-3a6d-ff73000d3a6d eth0: Data path switched from VF: enP48142s1 Mar 12 02:57:29.234339 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 12 02:57:29.234417 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 12 02:57:29.246512 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 12 02:57:29.248816 systemd[1]: Stopped target network-pre.target - Preparation for Network. Mar 12 02:57:29.255742 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 12 02:57:29.255786 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 12 02:57:29.266319 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 12 02:57:29.282556 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 12 02:57:29.282640 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 12 02:57:29.291573 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 12 02:57:29.291617 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 12 02:57:29.303044 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 12 02:57:29.303086 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 12 02:57:29.308098 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 12 02:57:29.308136 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 12 02:57:29.320677 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 12 02:57:29.329181 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 12 02:57:29.329251 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 12 02:57:29.329282 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 12 02:57:29.352268 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 12 02:57:29.352439 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 12 02:57:29.361425 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 12 02:57:29.361458 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 12 02:57:29.370841 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 12 02:57:29.370866 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 12 02:57:29.378747 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 12 02:57:29.378798 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 12 02:57:29.392018 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 12 02:57:29.392063 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 12 02:57:29.413303 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 12 02:57:29.413349 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 12 02:57:29.424261 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 12 02:57:29.439797 systemd[1]: systemd-network-generator.service: Deactivated successfully. Mar 12 02:57:29.439853 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Mar 12 02:57:29.450072 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 12 02:57:29.450140 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 12 02:57:29.455440 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Mar 12 02:57:29.455480 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 12 02:57:29.475814 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 12 02:57:29.475852 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 12 02:57:29.480983 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 12 02:57:29.481012 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 12 02:57:29.495823 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Mar 12 02:57:29.495864 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Mar 12 02:57:29.495887 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 12 02:57:29.495912 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 12 02:57:29.496196 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 12 02:57:29.496285 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 12 02:57:29.503218 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 12 02:57:29.503288 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 12 02:57:32.174094 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 12 02:57:32.174199 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 12 02:57:32.178774 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 12 02:57:32.186478 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 12 02:57:32.186531 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 12 02:57:32.194775 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 12 02:57:32.216242 systemd[1]: Switching root. Mar 12 02:57:33.912394 systemd-journald[225]: Journal stopped Mar 12 02:57:41.572108 systemd-journald[225]: Received SIGTERM from PID 1 (systemd). Mar 12 02:57:41.572129 kernel: SELinux: policy capability network_peer_controls=1 Mar 12 02:57:41.572137 kernel: SELinux: policy capability open_perms=1 Mar 12 02:57:41.572142 kernel: SELinux: policy capability extended_socket_class=1 Mar 12 02:57:41.572149 kernel: SELinux: policy capability always_check_network=0 Mar 12 02:57:41.572154 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 12 02:57:41.572160 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 12 02:57:41.572165 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 12 02:57:41.572170 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 12 02:57:41.572175 kernel: SELinux: policy capability userspace_initial_context=0 Mar 12 02:57:41.572181 kernel: audit: type=1403 audit(1773284257.565:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 12 02:57:41.572188 systemd[1]: Successfully loaded SELinux policy in 176.616ms. Mar 12 02:57:41.572194 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 4.308ms. Mar 12 02:57:41.572201 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) Mar 12 02:57:41.572208 systemd[1]: Detected virtualization microsoft. Mar 12 02:57:41.572215 systemd[1]: Detected architecture arm64. Mar 12 02:57:41.572221 systemd[1]: Detected first boot. Mar 12 02:57:41.572227 systemd[1]: Hostname set to . Mar 12 02:57:41.572233 systemd[1]: Initializing machine ID from random generator. Mar 12 02:57:41.572239 zram_generator::config[1299]: No configuration found. Mar 12 02:57:41.572246 kernel: NET: Registered PF_VSOCK protocol family Mar 12 02:57:41.572251 systemd[1]: Populated /etc with preset unit settings. Mar 12 02:57:41.572259 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 12 02:57:41.572266 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 12 02:57:41.572272 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 12 02:57:41.572277 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 12 02:57:41.572284 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 12 02:57:41.572290 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 12 02:57:41.572296 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 12 02:57:41.572302 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 12 02:57:41.572309 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 12 02:57:41.572315 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 12 02:57:41.572321 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 12 02:57:41.572327 systemd[1]: Created slice user.slice - User and Session Slice. Mar 12 02:57:41.572333 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 12 02:57:41.572339 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 12 02:57:41.572345 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 12 02:57:41.572352 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 12 02:57:41.572359 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 12 02:57:41.572365 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 12 02:57:41.572373 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Mar 12 02:57:41.572379 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 12 02:57:41.572385 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 12 02:57:41.572391 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 12 02:57:41.572397 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 12 02:57:41.572403 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 12 02:57:41.572410 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 12 02:57:41.572417 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 12 02:57:41.572423 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 12 02:57:41.572429 systemd[1]: Reached target slices.target - Slice Units. Mar 12 02:57:41.572435 systemd[1]: Reached target swap.target - Swaps. Mar 12 02:57:41.572441 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 12 02:57:41.572447 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 12 02:57:41.572455 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 12 02:57:41.572461 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 12 02:57:41.572468 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 12 02:57:41.572474 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 12 02:57:41.572480 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 12 02:57:41.572486 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 12 02:57:41.572493 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 12 02:57:41.572500 systemd[1]: Mounting media.mount - External Media Directory... Mar 12 02:57:41.572506 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 12 02:57:41.572512 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 12 02:57:41.572518 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 12 02:57:41.572524 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). Mar 12 02:57:41.572531 systemd[1]: Reached target machines.target - Containers. Mar 12 02:57:41.572537 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 12 02:57:41.572544 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 12 02:57:41.572550 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 12 02:57:41.572556 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 12 02:57:41.572563 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 12 02:57:41.572569 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 12 02:57:41.572575 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 12 02:57:41.572581 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 12 02:57:41.572587 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 12 02:57:41.572594 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 12 02:57:41.572601 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 12 02:57:41.572607 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 12 02:57:41.572613 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 12 02:57:41.572620 systemd[1]: Stopped systemd-fsck-usr.service. Mar 12 02:57:41.572626 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). Mar 12 02:57:41.572632 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 12 02:57:41.572639 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 12 02:57:41.572645 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 12 02:57:41.572652 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 12 02:57:41.572658 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 12 02:57:41.572664 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 12 02:57:41.572670 systemd[1]: verity-setup.service: Deactivated successfully. Mar 12 02:57:41.572676 systemd[1]: Stopped verity-setup.service. Mar 12 02:57:41.572682 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 12 02:57:41.572688 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 12 02:57:41.572694 systemd[1]: Mounted media.mount - External Media Directory. Mar 12 02:57:41.572701 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 12 02:57:41.572708 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 12 02:57:41.572727 systemd-journald[1382]: Collecting audit messages is disabled. Mar 12 02:57:41.572740 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 12 02:57:41.572748 systemd-journald[1382]: Journal started Mar 12 02:57:41.572763 systemd-journald[1382]: Runtime Journal (/run/log/journal/676d05f22cfb41cda9d96d64766fd501) is 8M, max 78.3M, 70.3M free. Mar 12 02:57:40.253847 systemd[1]: Queued start job for default target multi-user.target. Mar 12 02:57:40.258398 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Mar 12 02:57:40.258771 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 12 02:57:40.259046 systemd[1]: systemd-journald.service: Consumed 2.406s CPU time. Mar 12 02:57:41.589865 systemd[1]: Started systemd-journald.service - Journal Service. Mar 12 02:57:41.590603 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 12 02:57:41.593977 kernel: fuse: init (API version 7.41) Mar 12 02:57:41.600331 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 12 02:57:41.600482 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 12 02:57:41.605351 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 12 02:57:41.605472 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 12 02:57:41.610450 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 12 02:57:41.610567 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 12 02:57:41.615555 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 12 02:57:41.615676 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 12 02:57:41.625955 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 12 02:57:41.636053 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 12 02:57:41.650079 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 12 02:57:41.655454 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 12 02:57:41.660407 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 12 02:57:41.674310 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 12 02:57:41.679705 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 12 02:57:41.685304 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 12 02:57:41.690128 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 12 02:57:41.690153 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 12 02:57:41.695083 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 12 02:57:41.701014 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 12 02:57:41.705814 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 12 02:57:41.790979 kernel: loop: module loaded Mar 12 02:57:42.182101 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 12 02:57:42.196195 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 12 02:57:42.204541 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 12 02:57:42.208143 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 12 02:57:42.219092 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 12 02:57:42.230979 kernel: ACPI: bus type drm_connector registered Mar 12 02:57:42.233128 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 12 02:57:42.238155 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 12 02:57:42.238287 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 12 02:57:42.242876 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 12 02:57:42.243098 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 12 02:57:42.247866 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 12 02:57:42.253448 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 12 02:57:42.261363 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 12 02:57:42.470300 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 12 02:57:42.475238 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 12 02:57:42.482094 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 12 02:57:42.497396 systemd-journald[1382]: Time spent on flushing to /var/log/journal/676d05f22cfb41cda9d96d64766fd501 is 9.974ms for 916 entries. Mar 12 02:57:42.497396 systemd-journald[1382]: System Journal (/var/log/journal/676d05f22cfb41cda9d96d64766fd501) is 8M, max 2.6G, 2.6G free. Mar 12 02:57:43.368368 systemd-journald[1382]: Received client request to flush runtime journal. Mar 12 02:57:43.368431 kernel: loop0: detected capacity change from 0 to 100632 Mar 12 02:57:42.497298 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 12 02:57:42.507486 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 12 02:57:42.509590 systemd-tmpfiles[1418]: ACLs are not supported, ignoring. Mar 12 02:57:42.509598 systemd-tmpfiles[1418]: ACLs are not supported, ignoring. Mar 12 02:57:42.519113 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 12 02:57:42.527197 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 12 02:57:43.370570 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 12 02:57:43.428135 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 12 02:57:45.657537 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 12 02:57:45.665178 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 12 02:57:45.682474 systemd-tmpfiles[1456]: ACLs are not supported, ignoring. Mar 12 02:57:45.682489 systemd-tmpfiles[1456]: ACLs are not supported, ignoring. Mar 12 02:57:45.684969 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 12 02:57:46.963350 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 12 02:57:46.964682 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 12 02:57:46.971936 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 12 02:57:46.978545 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 12 02:57:47.005158 systemd-udevd[1461]: Using default interface naming scheme 'v255'. Mar 12 02:57:47.375979 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 12 02:57:47.764979 kernel: loop1: detected capacity change from 0 to 197488 Mar 12 02:57:48.322984 kernel: loop2: detected capacity change from 0 to 119840 Mar 12 02:57:48.809534 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 12 02:57:48.819812 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 12 02:57:48.855051 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Mar 12 02:57:49.240586 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 12 02:57:49.458896 kernel: hv_vmbus: registering driver hv_balloon Mar 12 02:57:49.459014 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 12 02:57:49.463718 kernel: hv_balloon: Memory hot add disabled on ARM64 Mar 12 02:57:49.495024 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#172 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Mar 12 02:57:49.586041 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 12 02:57:49.635984 kernel: mousedev: PS/2 mouse device common for all mice Mar 12 02:57:49.874084 kernel: hv_vmbus: registering driver hyperv_fb Mar 12 02:57:49.881974 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 12 02:57:49.882081 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 12 02:57:49.885058 kernel: Console: switching to colour dummy device 80x25 Mar 12 02:57:49.890753 kernel: Console: switching to colour frame buffer device 128x48 Mar 12 02:57:49.941141 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 12 02:57:49.949354 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 12 02:57:49.949638 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 12 02:57:49.955129 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 12 02:57:50.227429 systemd-networkd[1486]: lo: Link UP Mar 12 02:57:50.227436 systemd-networkd[1486]: lo: Gained carrier Mar 12 02:57:50.228423 systemd-networkd[1486]: Enumeration completed Mar 12 02:57:50.228508 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 12 02:57:50.229251 systemd-networkd[1486]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 12 02:57:50.229259 systemd-networkd[1486]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 12 02:57:50.234120 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 12 02:57:50.241080 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 12 02:57:50.275973 kernel: mlx5_core bc0e:00:02.0 enP48142s1: Link up Mar 12 02:57:50.299397 kernel: hv_netvsc 000d3a6d-ff73-000d-3a6d-ff73000d3a6d eth0: Data path switched to VF: enP48142s1 Mar 12 02:57:50.300120 systemd-networkd[1486]: enP48142s1: Link UP Mar 12 02:57:50.300248 systemd-networkd[1486]: eth0: Link UP Mar 12 02:57:50.300251 systemd-networkd[1486]: eth0: Gained carrier Mar 12 02:57:50.300271 systemd-networkd[1486]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 12 02:57:50.306167 systemd-networkd[1486]: enP48142s1: Gained carrier Mar 12 02:57:50.313259 systemd-networkd[1486]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 12 02:57:50.679151 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 12 02:57:50.684686 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 12 02:57:50.738128 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 12 02:57:51.122648 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 12 02:57:51.169988 kernel: MACsec IEEE 802.1AE Mar 12 02:57:51.653175 systemd-networkd[1486]: eth0: Gained IPv6LL Mar 12 02:57:51.655126 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 12 02:57:52.877990 kernel: loop3: detected capacity change from 0 to 27936 Mar 12 02:57:53.576757 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 12 02:57:55.547984 kernel: loop4: detected capacity change from 0 to 100632 Mar 12 02:57:56.518985 kernel: loop5: detected capacity change from 0 to 197488 Mar 12 02:57:56.565987 kernel: loop6: detected capacity change from 0 to 119840 Mar 12 02:57:57.471989 kernel: loop7: detected capacity change from 0 to 27936 Mar 12 02:57:57.478822 (sd-merge)[1615]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-azure'. Mar 12 02:57:57.479220 (sd-merge)[1615]: Merged extensions into '/usr'. Mar 12 02:57:57.482706 systemd[1]: Reload requested from client PID 1435 ('systemd-sysext') (unit systemd-sysext.service)... Mar 12 02:57:57.482904 systemd[1]: Reloading... Mar 12 02:57:57.533008 zram_generator::config[1640]: No configuration found. Mar 12 02:57:57.709048 systemd[1]: Reloading finished in 225 ms. Mar 12 02:57:57.725160 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 12 02:57:57.736955 systemd[1]: Starting ensure-sysext.service... Mar 12 02:57:57.743093 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 12 02:57:57.759321 systemd-tmpfiles[1699]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Mar 12 02:57:57.759341 systemd-tmpfiles[1699]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Mar 12 02:57:57.759494 systemd-tmpfiles[1699]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 12 02:57:57.759632 systemd-tmpfiles[1699]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 12 02:57:57.760478 systemd-tmpfiles[1699]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 12 02:57:57.760763 systemd-tmpfiles[1699]: ACLs are not supported, ignoring. Mar 12 02:57:57.760922 systemd-tmpfiles[1699]: ACLs are not supported, ignoring. Mar 12 02:57:57.760928 systemd[1]: Reload requested from client PID 1698 ('systemctl') (unit ensure-sysext.service)... Mar 12 02:57:57.760941 systemd[1]: Reloading... Mar 12 02:57:57.777328 systemd-tmpfiles[1699]: Detected autofs mount point /boot during canonicalization of boot. Mar 12 02:57:57.777486 systemd-tmpfiles[1699]: Skipping /boot Mar 12 02:57:57.785533 systemd-tmpfiles[1699]: Detected autofs mount point /boot during canonicalization of boot. Mar 12 02:57:57.785650 systemd-tmpfiles[1699]: Skipping /boot Mar 12 02:57:57.813989 zram_generator::config[1728]: No configuration found. Mar 12 02:57:57.968174 systemd[1]: Reloading finished in 206 ms. Mar 12 02:57:57.985114 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 12 02:57:58.003093 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 12 02:57:58.008812 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 12 02:57:58.020144 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 12 02:57:58.028157 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 12 02:57:58.033903 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 12 02:57:58.045429 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 12 02:57:58.049272 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 12 02:57:58.059180 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 12 02:57:58.069628 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 12 02:57:58.078071 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 12 02:57:58.088001 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 12 02:57:58.088118 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). Mar 12 02:57:58.088224 systemd[1]: Reached target time-set.target - System Time Set. Mar 12 02:57:58.094586 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 12 02:57:58.096639 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 12 02:57:58.101914 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 12 02:57:58.102067 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 12 02:57:58.106620 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 12 02:57:58.106743 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 12 02:57:58.113868 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 12 02:57:58.114009 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 12 02:57:58.122682 systemd[1]: Finished ensure-sysext.service. Mar 12 02:57:58.129268 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 12 02:57:58.137073 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 12 02:57:58.137133 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 12 02:57:58.139218 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 12 02:57:58.145312 systemd-resolved[1789]: Positive Trust Anchors: Mar 12 02:57:58.145744 systemd-resolved[1789]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 12 02:57:58.145767 systemd-resolved[1789]: 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 Mar 12 02:57:58.164882 systemd-resolved[1789]: Using system hostname 'ci-4459.2.4-n-97f68a91ee'. Mar 12 02:57:58.166645 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 12 02:57:58.171294 systemd[1]: Reached target network.target - Network. Mar 12 02:57:58.175140 systemd[1]: Reached target network-online.target - Network is Online. Mar 12 02:57:58.179508 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 12 02:57:58.213818 augenrules[1822]: No rules Mar 12 02:57:58.215004 systemd[1]: audit-rules.service: Deactivated successfully. Mar 12 02:57:58.217016 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 12 02:57:58.759789 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 12 02:57:58.765634 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). Mar 12 02:58:00.205859 ldconfig[1424]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 12 02:58:00.216099 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 12 02:58:00.222099 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 12 02:58:00.234218 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 12 02:58:00.239139 systemd[1]: Reached target sysinit.target - System Initialization. Mar 12 02:58:00.243717 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 12 02:58:00.248752 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 12 02:58:00.254323 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 12 02:58:00.258884 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 12 02:58:00.263942 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 12 02:58:00.269043 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 12 02:58:00.269072 systemd[1]: Reached target paths.target - Path Units. Mar 12 02:58:00.272879 systemd[1]: Reached target timers.target - Timer Units. Mar 12 02:58:00.292363 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 12 02:58:00.298028 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 12 02:58:00.303319 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 12 02:58:00.308628 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 12 02:58:00.313651 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 12 02:58:00.320197 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 12 02:58:00.324655 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 12 02:58:00.330126 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 12 02:58:00.334987 systemd[1]: Reached target sockets.target - Socket Units. Mar 12 02:58:00.339162 systemd[1]: Reached target basic.target - Basic System. Mar 12 02:58:00.343140 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 12 02:58:00.343163 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 12 02:58:00.345424 systemd[1]: Starting chronyd.service - NTP client/server... Mar 12 02:58:00.360055 systemd[1]: Starting containerd.service - containerd container runtime... Mar 12 02:58:00.365181 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Mar 12 02:58:00.372093 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 12 02:58:00.379152 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 12 02:58:00.388116 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 12 02:58:00.395017 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 12 02:58:00.400072 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). Mar 12 02:58:00.403094 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Mar 12 02:58:00.408210 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Mar 12 02:58:00.409102 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 12 02:58:00.413766 jq[1842]: false Mar 12 02:58:00.415357 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 12 02:58:00.421090 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 12 02:58:00.428092 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 12 02:58:00.435945 KVP[1844]: KVP starting; pid is:1844 Mar 12 02:58:00.438255 chronyd[1834]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Mar 12 02:58:00.439096 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 12 02:58:00.446094 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 12 02:58:00.450467 KVP[1844]: KVP LIC Version: 3.1 Mar 12 02:58:00.452989 kernel: hv_utils: KVP IC version 4.0 Mar 12 02:58:00.453693 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 12 02:58:00.454759 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 12 02:58:00.455794 systemd[1]: Starting update-engine.service - Update Engine... Mar 12 02:58:00.459367 extend-filesystems[1843]: Found /dev/sda6 Mar 12 02:58:00.465704 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 12 02:58:00.466726 chronyd[1834]: Timezone right/UTC failed leap second check, ignoring Mar 12 02:58:00.466870 chronyd[1834]: Loaded seccomp filter (level 2) Mar 12 02:58:00.476007 extend-filesystems[1843]: Found /dev/sda9 Mar 12 02:58:00.487588 extend-filesystems[1843]: Checking size of /dev/sda9 Mar 12 02:58:00.478073 systemd[1]: Started chronyd.service - NTP client/server. Mar 12 02:58:00.491155 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 12 02:58:00.499081 jq[1862]: true Mar 12 02:58:00.500767 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 12 02:58:00.504208 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 12 02:58:00.506296 systemd[1]: motdgen.service: Deactivated successfully. Mar 12 02:58:00.506452 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 12 02:58:00.512705 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 12 02:58:00.519118 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 12 02:58:00.521170 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 12 02:58:00.537271 (ntainerd)[1877]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 12 02:58:00.541481 jq[1876]: true Mar 12 02:58:00.549462 systemd-logind[1855]: New seat seat0. Mar 12 02:58:00.551777 systemd-logind[1855]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 12 02:58:00.551921 systemd[1]: Started systemd-logind.service - User Login Management. Mar 12 02:58:00.557277 update_engine[1857]: I20260312 02:58:00.555622 1857 main.cc:92] Flatcar Update Engine starting Mar 12 02:58:00.638277 extend-filesystems[1843]: Old size kept for /dev/sda9 Mar 12 02:58:00.639734 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 12 02:58:00.641023 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 12 02:58:01.366552 sshd_keygen[1868]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 12 02:58:01.378117 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 12 02:58:01.386278 (kubelet)[1978]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Mar 12 02:58:01.388380 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 12 02:58:01.394118 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 12 02:58:01.401666 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Mar 12 02:58:01.412414 dbus-daemon[1837]: [system] SELinux support is enabled Mar 12 02:58:01.412564 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 12 02:58:01.421945 systemd[1]: issuegen.service: Deactivated successfully. Mar 12 02:58:01.424168 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 12 02:58:01.430207 update_engine[1857]: I20260312 02:58:01.429999 1857 update_check_scheduler.cc:74] Next update check in 6m40s Mar 12 02:58:01.433383 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). Mar 12 02:58:01.434027 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 12 02:58:01.439524 dbus-daemon[1837]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 12 02:58:01.447223 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 12 02:58:01.452416 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). Mar 12 02:58:01.452528 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 12 02:58:01.460413 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Mar 12 02:58:01.481362 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 12 02:58:01.487544 systemd[1]: Started update-engine.service - Update Engine. Mar 12 02:58:01.494224 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 12 02:58:01.500874 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Mar 12 02:58:01.508078 systemd[1]: Reached target getty.target - Login Prompts. Mar 12 02:58:01.513515 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 12 02:58:01.617508 bash[1906]: Updated "/home/core/.ssh/authorized_keys" Mar 12 02:58:01.618286 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 12 02:58:01.624444 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 12 02:58:01.730874 kubelet[1978]: E0312 02:58:01.730828 1978 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" Mar 12 02:58:01.733127 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 12 02:58:01.733243 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 12 02:58:01.733617 systemd[1]: kubelet.service: Consumed 489ms CPU time, 244.8M memory peak. Mar 12 02:58:02.028525 coreos-metadata[1836]: Mar 12 02:58:02.028 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 12 02:58:02.032082 coreos-metadata[1836]: Mar 12 02:58:02.032 INFO Fetch successful Mar 12 02:58:02.032082 coreos-metadata[1836]: Mar 12 02:58:02.032 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Mar 12 02:58:02.036761 coreos-metadata[1836]: Mar 12 02:58:02.036 INFO Fetch successful Mar 12 02:58:02.036761 coreos-metadata[1836]: Mar 12 02:58:02.036 INFO Fetching http://168.63.129.16/machine/6dde699a-fcb7-4e39-a98c-a706d9dcdc85/73c9a8ac%2Dc1cf%2D44f8%2Db372%2Df42087346b29.%5Fci%2D4459.2.4%2Dn%2D97f68a91ee?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Mar 12 02:58:02.038607 coreos-metadata[1836]: Mar 12 02:58:02.038 INFO Fetch successful Mar 12 02:58:02.038607 coreos-metadata[1836]: Mar 12 02:58:02.038 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Mar 12 02:58:02.046799 coreos-metadata[1836]: Mar 12 02:58:02.046 INFO Fetch successful Mar 12 02:58:02.063434 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Mar 12 02:58:02.068527 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 12 02:58:03.284074 locksmithd[2003]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 12 02:58:03.884985 containerd[1877]: time="2026-03-12T02:58:03Z" 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 Mar 12 02:58:03.885874 containerd[1877]: time="2026-03-12T02:58:03.885842208Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Mar 12 02:58:03.891391 containerd[1877]: time="2026-03-12T02:58:03.891362096Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.8µs" Mar 12 02:58:03.891391 containerd[1877]: time="2026-03-12T02:58:03.891390648Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 12 02:58:03.891470 containerd[1877]: time="2026-03-12T02:58:03.891405592Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 12 02:58:03.891547 containerd[1877]: time="2026-03-12T02:58:03.891525192Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 12 02:58:03.891547 containerd[1877]: time="2026-03-12T02:58:03.891544896Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 12 02:58:03.891572 containerd[1877]: time="2026-03-12T02:58:03.891565592Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 12 02:58:03.891623 containerd[1877]: time="2026-03-12T02:58:03.891608888Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 12 02:58:03.891636 containerd[1877]: time="2026-03-12T02:58:03.891620792Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 12 02:58:03.892069 containerd[1877]: time="2026-03-12T02:58:03.891894320Z" 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 Mar 12 02:58:03.892069 containerd[1877]: time="2026-03-12T02:58:03.891910448Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 12 02:58:03.892069 containerd[1877]: time="2026-03-12T02:58:03.891919000Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 12 02:58:03.892069 containerd[1877]: time="2026-03-12T02:58:03.891923808Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 12 02:58:03.892069 containerd[1877]: time="2026-03-12T02:58:03.892001576Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 12 02:58:03.892175 containerd[1877]: time="2026-03-12T02:58:03.892151424Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 12 02:58:03.892175 containerd[1877]: time="2026-03-12T02:58:03.892170440Z" 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 Mar 12 02:58:03.892210 containerd[1877]: time="2026-03-12T02:58:03.892176640Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 12 02:58:03.892210 containerd[1877]: time="2026-03-12T02:58:03.892199592Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 12 02:58:03.892346 containerd[1877]: time="2026-03-12T02:58:03.892330616Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 12 02:58:03.892390 containerd[1877]: time="2026-03-12T02:58:03.892377336Z" level=info msg="metadata content store policy set" policy=shared Mar 12 02:58:04.170791 containerd[1877]: time="2026-03-12T02:58:04.170675336Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 12 02:58:04.170791 containerd[1877]: time="2026-03-12T02:58:04.170758376Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 12 02:58:04.170791 containerd[1877]: time="2026-03-12T02:58:04.170769248Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 12 02:58:04.170791 containerd[1877]: time="2026-03-12T02:58:04.170777328Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 12 02:58:04.170791 containerd[1877]: time="2026-03-12T02:58:04.170785832Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 12 02:58:04.170791 containerd[1877]: time="2026-03-12T02:58:04.170793728Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 12 02:58:04.170979 containerd[1877]: time="2026-03-12T02:58:04.170803880Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 12 02:58:04.170979 containerd[1877]: time="2026-03-12T02:58:04.170812536Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 12 02:58:04.170979 containerd[1877]: time="2026-03-12T02:58:04.170830760Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 12 02:58:04.170979 containerd[1877]: time="2026-03-12T02:58:04.170837576Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 12 02:58:04.170979 containerd[1877]: time="2026-03-12T02:58:04.170843520Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 12 02:58:04.170979 containerd[1877]: time="2026-03-12T02:58:04.170852208Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 12 02:58:04.171049 containerd[1877]: time="2026-03-12T02:58:04.171023200Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 12 02:58:04.171049 containerd[1877]: time="2026-03-12T02:58:04.171039744Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 12 02:58:04.171070 containerd[1877]: time="2026-03-12T02:58:04.171049192Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 12 02:58:04.171070 containerd[1877]: time="2026-03-12T02:58:04.171062600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 12 02:58:04.171093 containerd[1877]: time="2026-03-12T02:58:04.171072040Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 12 02:58:04.171093 containerd[1877]: time="2026-03-12T02:58:04.171079024Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 12 02:58:04.171093 containerd[1877]: time="2026-03-12T02:58:04.171085872Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 12 02:58:04.171093 containerd[1877]: time="2026-03-12T02:58:04.171092088Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 12 02:58:04.171171 containerd[1877]: time="2026-03-12T02:58:04.171100176Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 12 02:58:04.171171 containerd[1877]: time="2026-03-12T02:58:04.171107312Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 12 02:58:04.171171 containerd[1877]: time="2026-03-12T02:58:04.171113880Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 12 02:58:04.171171 containerd[1877]: time="2026-03-12T02:58:04.171157336Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 12 02:58:04.171171 containerd[1877]: time="2026-03-12T02:58:04.171168368Z" level=info msg="Start snapshots syncer" Mar 12 02:58:04.171232 containerd[1877]: time="2026-03-12T02:58:04.171185752Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 12 02:58:04.171420 containerd[1877]: time="2026-03-12T02:58:04.171375376Z" 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\"}" Mar 12 02:58:04.171420 containerd[1877]: time="2026-03-12T02:58:04.171416536Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 12 02:58:04.171552 containerd[1877]: time="2026-03-12T02:58:04.171449800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 12 02:58:04.171552 containerd[1877]: time="2026-03-12T02:58:04.171540392Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 12 02:58:04.171588 containerd[1877]: time="2026-03-12T02:58:04.171554120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 12 02:58:04.171588 containerd[1877]: time="2026-03-12T02:58:04.171562240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 12 02:58:04.171588 containerd[1877]: time="2026-03-12T02:58:04.171569056Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 12 02:58:04.171588 containerd[1877]: time="2026-03-12T02:58:04.171577208Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 12 02:58:04.171588 containerd[1877]: time="2026-03-12T02:58:04.171583632Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 12 02:58:04.171642 containerd[1877]: time="2026-03-12T02:58:04.171590008Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 12 02:58:04.171642 containerd[1877]: time="2026-03-12T02:58:04.171607264Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 12 02:58:04.171642 containerd[1877]: time="2026-03-12T02:58:04.171615144Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 12 02:58:04.171642 containerd[1877]: time="2026-03-12T02:58:04.171621696Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 12 02:58:04.171642 containerd[1877]: time="2026-03-12T02:58:04.171646448Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171658560Z" 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 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171664768Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171670720Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171675240Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171680544Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171687104Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171699640Z" level=info msg="runtime interface created" Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171703496Z" level=info msg="created NRI interface" Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171708576Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171715736Z" level=info msg="Connect containerd service" Mar 12 02:58:04.171787 containerd[1877]: time="2026-03-12T02:58:04.171729072Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 12 02:58:04.172337 containerd[1877]: time="2026-03-12T02:58:04.172311064Z" 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" Mar 12 02:58:06.455909 containerd[1877]: time="2026-03-12T02:58:06.455866768Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.455924008Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.455947152Z" level=info msg="Start subscribing containerd event" Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.455994776Z" level=info msg="Start recovering state" Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456060968Z" level=info msg="Start event monitor" Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456070480Z" level=info msg="Start cni network conf syncer for default" Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456077184Z" level=info msg="Start streaming server" Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456082760Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456087680Z" level=info msg="runtime interface starting up..." Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456092400Z" level=info msg="starting plugins..." Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456103784Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 12 02:58:06.456243 containerd[1877]: time="2026-03-12T02:58:06.456195576Z" level=info msg="containerd successfully booted in 2.571684s" Mar 12 02:58:06.456479 systemd[1]: Started containerd.service - containerd container runtime. Mar 12 02:58:06.462773 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 12 02:58:06.472020 systemd[1]: Startup finished in 1.709s (kernel) + 18.684s (initrd) + 29.081s (userspace) = 49.475s. Mar 12 02:58:08.308291 login[1997]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Mar 12 02:58:08.309270 login[2002]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Mar 12 02:58:08.314178 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 12 02:58:08.314897 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 12 02:58:08.320763 systemd-logind[1855]: New session 1 of user core. Mar 12 02:58:08.378949 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 12 02:58:08.380949 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 12 02:58:08.395844 (systemd)[2042]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 12 02:58:08.397619 systemd-logind[1855]: New session c1 of user core. Mar 12 02:58:08.701458 systemd[2042]: Queued start job for default target default.target. Mar 12 02:58:08.708667 systemd[2042]: Created slice app.slice - User Application Slice. Mar 12 02:58:08.708689 systemd[2042]: Reached target paths.target - Paths. Mar 12 02:58:08.708797 systemd[2042]: Reached target timers.target - Timers. Mar 12 02:58:08.709771 systemd[2042]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 12 02:58:08.716586 systemd[2042]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 12 02:58:08.716626 systemd[2042]: Reached target sockets.target - Sockets. Mar 12 02:58:08.716653 systemd[2042]: Reached target basic.target - Basic System. Mar 12 02:58:08.716673 systemd[2042]: Reached target default.target - Main User Target. Mar 12 02:58:08.716691 systemd[2042]: Startup finished in 314ms. Mar 12 02:58:08.716775 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 12 02:58:08.718623 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 12 02:58:09.607666 login[1997]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Mar 12 02:58:09.611840 systemd-logind[1855]: New session 2 of user core. Mar 12 02:58:09.617083 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 12 02:58:11.053730 waagent[1994]: 2026-03-12T02:58:11.053650Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Mar 12 02:58:11.058986 waagent[1994]: 2026-03-12T02:58:11.058850Z INFO Daemon Daemon OS: flatcar 4459.2.4 Mar 12 02:58:11.062375 waagent[1994]: 2026-03-12T02:58:11.062341Z INFO Daemon Daemon Python: 3.11.13 Mar 12 02:58:11.065692 waagent[1994]: 2026-03-12T02:58:11.065658Z INFO Daemon Daemon Run daemon Mar 12 02:58:11.070230 waagent[1994]: 2026-03-12T02:58:11.070196Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.4' Mar 12 02:58:11.076908 waagent[1994]: 2026-03-12T02:58:11.076880Z INFO Daemon Daemon Using waagent for provisioning Mar 12 02:58:11.080970 waagent[1994]: 2026-03-12T02:58:11.080919Z INFO Daemon Daemon Activate resource disk Mar 12 02:58:11.084430 waagent[1994]: 2026-03-12T02:58:11.084396Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 12 02:58:11.092692 waagent[1994]: 2026-03-12T02:58:11.092659Z INFO Daemon Daemon Found device: None Mar 12 02:58:11.096127 waagent[1994]: 2026-03-12T02:58:11.096098Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Mar 12 02:58:11.102312 waagent[1994]: 2026-03-12T02:58:11.102286Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Mar 12 02:58:11.110943 waagent[1994]: 2026-03-12T02:58:11.110908Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 12 02:58:11.115085 waagent[1994]: 2026-03-12T02:58:11.115059Z INFO Daemon Daemon Running default provisioning handler Mar 12 02:58:11.123579 waagent[1994]: 2026-03-12T02:58:11.123525Z 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. Mar 12 02:58:11.133547 waagent[1994]: 2026-03-12T02:58:11.133508Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 12 02:58:11.140834 waagent[1994]: 2026-03-12T02:58:11.140806Z INFO Daemon Daemon cloud-init is enabled: False Mar 12 02:58:11.144465 waagent[1994]: 2026-03-12T02:58:11.144439Z INFO Daemon Daemon Copying ovf-env.xml Mar 12 02:58:11.381310 waagent[1994]: 2026-03-12T02:58:11.381192Z INFO Daemon Daemon Successfully mounted dvd Mar 12 02:58:11.591925 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 12 02:58:11.594228 waagent[1994]: 2026-03-12T02:58:11.594168Z INFO Daemon Daemon Detect protocol endpoint Mar 12 02:58:11.598015 waagent[1994]: 2026-03-12T02:58:11.597926Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 12 02:58:11.602265 waagent[1994]: 2026-03-12T02:58:11.602235Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 12 02:58:11.607060 waagent[1994]: 2026-03-12T02:58:11.607034Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 12 02:58:11.610917 waagent[1994]: 2026-03-12T02:58:11.610888Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 12 02:58:11.614653 waagent[1994]: 2026-03-12T02:58:11.614629Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 12 02:58:11.754672 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Mar 12 02:58:11.756006 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 12 02:58:11.930757 waagent[1994]: 2026-03-12T02:58:11.930709Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 12 02:58:11.935956 waagent[1994]: 2026-03-12T02:58:11.935936Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 12 02:58:11.939928 waagent[1994]: 2026-03-12T02:58:11.939903Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 12 02:58:12.558997 waagent[1994]: 2026-03-12T02:58:12.558177Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 12 02:58:12.563406 waagent[1994]: 2026-03-12T02:58:12.563011Z INFO Daemon Daemon Forcing an update of the goal state. Mar 12 02:58:12.570441 waagent[1994]: 2026-03-12T02:58:12.570406Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 12 02:58:12.606379 waagent[1994]: 2026-03-12T02:58:12.606347Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.179 Mar 12 02:58:12.611056 waagent[1994]: 2026-03-12T02:58:12.611022Z INFO Daemon Mar 12 02:58:12.613187 waagent[1994]: 2026-03-12T02:58:12.613158Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 8cefc424-ef07-4256-a5ad-73b1956e5fba eTag: 5356565446028078561 source: Fabric] Mar 12 02:58:12.621505 waagent[1994]: 2026-03-12T02:58:12.621470Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Mar 12 02:58:12.626428 waagent[1994]: 2026-03-12T02:58:12.626396Z INFO Daemon Mar 12 02:58:12.628533 waagent[1994]: 2026-03-12T02:58:12.628506Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Mar 12 02:58:12.637706 waagent[1994]: 2026-03-12T02:58:12.637675Z INFO Daemon Daemon Downloading artifacts profile blob Mar 12 02:58:12.750050 waagent[1994]: 2026-03-12T02:58:12.749984Z INFO Daemon Downloaded certificate {'thumbprint': '1A9E97C3B1B6BAFE603B6122462AB1907D5B21A7', 'hasPrivateKey': True} Mar 12 02:58:12.757488 waagent[1994]: 2026-03-12T02:58:12.757443Z INFO Daemon Fetch goal state completed Mar 12 02:58:12.767387 waagent[1994]: 2026-03-12T02:58:12.767357Z INFO Daemon Daemon Starting provisioning Mar 12 02:58:12.771431 waagent[1994]: 2026-03-12T02:58:12.771398Z INFO Daemon Daemon Handle ovf-env.xml. Mar 12 02:58:12.774903 waagent[1994]: 2026-03-12T02:58:12.774876Z INFO Daemon Daemon Set hostname [ci-4459.2.4-n-97f68a91ee] Mar 12 02:58:14.014907 waagent[1994]: 2026-03-12T02:58:14.014839Z INFO Daemon Daemon Publish hostname [ci-4459.2.4-n-97f68a91ee] Mar 12 02:58:14.019653 waagent[1994]: 2026-03-12T02:58:14.019612Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 12 02:58:14.024153 waagent[1994]: 2026-03-12T02:58:14.024119Z INFO Daemon Daemon Primary interface is [eth0] Mar 12 02:58:14.033903 systemd-networkd[1486]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 12 02:58:14.033908 systemd-networkd[1486]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 12 02:58:14.033939 systemd-networkd[1486]: eth0: DHCP lease lost Mar 12 02:58:14.036974 waagent[1994]: 2026-03-12T02:58:14.034720Z INFO Daemon Daemon Create user account if not exists Mar 12 02:58:14.038691 waagent[1994]: 2026-03-12T02:58:14.038657Z INFO Daemon Daemon User core already exists, skip useradd Mar 12 02:58:14.042759 waagent[1994]: 2026-03-12T02:58:14.042733Z INFO Daemon Daemon Configure sudoer Mar 12 02:58:14.066999 systemd-networkd[1486]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 12 02:58:15.154341 waagent[1994]: 2026-03-12T02:58:15.154247Z INFO Daemon Daemon Configure sshd Mar 12 02:58:16.862184 waagent[1994]: 2026-03-12T02:58:16.861225Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Mar 12 02:58:16.870649 waagent[1994]: 2026-03-12T02:58:16.870603Z INFO Daemon Daemon Deploy ssh public key. Mar 12 02:58:16.879129 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 12 02:58:16.884147 (kubelet)[2099]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Mar 12 02:58:16.911243 kubelet[2099]: E0312 02:58:16.911210 2099 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" Mar 12 02:58:16.913595 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 12 02:58:16.913687 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 12 02:58:16.914216 systemd[1]: kubelet.service: Consumed 108ms CPU time, 105M memory peak. Mar 12 02:58:17.301692 waagent[1994]: 2026-03-12T02:58:17.301650Z INFO Daemon Daemon Provisioning complete Mar 12 02:58:17.314528 waagent[1994]: 2026-03-12T02:58:17.314493Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 12 02:58:17.319471 waagent[1994]: 2026-03-12T02:58:17.319438Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 12 02:58:17.326925 waagent[1994]: 2026-03-12T02:58:17.326894Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Mar 12 02:58:17.423645 waagent[2108]: 2026-03-12T02:58:17.423587Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Mar 12 02:58:17.425000 waagent[2108]: 2026-03-12T02:58:17.424031Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.4 Mar 12 02:58:17.425000 waagent[2108]: 2026-03-12T02:58:17.424088Z INFO ExtHandler ExtHandler Python: 3.11.13 Mar 12 02:58:17.425000 waagent[2108]: 2026-03-12T02:58:17.424123Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Mar 12 02:58:17.460849 waagent[2108]: 2026-03-12T02:58:17.460799Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.4; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Mar 12 02:58:17.460985 waagent[2108]: 2026-03-12T02:58:17.460945Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 12 02:58:17.461030 waagent[2108]: 2026-03-12T02:58:17.461011Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 12 02:58:17.466648 waagent[2108]: 2026-03-12T02:58:17.466603Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 12 02:58:17.474337 waagent[2108]: 2026-03-12T02:58:17.474309Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.179 Mar 12 02:58:17.474680 waagent[2108]: 2026-03-12T02:58:17.474650Z INFO ExtHandler Mar 12 02:58:17.474733 waagent[2108]: 2026-03-12T02:58:17.474715Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b8f76e02-75ea-41fa-8dce-9bc531594e57 eTag: 5356565446028078561 source: Fabric] Mar 12 02:58:17.474945 waagent[2108]: 2026-03-12T02:58:17.474921Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 12 02:58:17.475356 waagent[2108]: 2026-03-12T02:58:17.475327Z INFO ExtHandler Mar 12 02:58:17.475396 waagent[2108]: 2026-03-12T02:58:17.475380Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 12 02:58:17.480532 waagent[2108]: 2026-03-12T02:58:17.480507Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 12 02:58:17.530216 waagent[2108]: 2026-03-12T02:58:17.530155Z INFO ExtHandler Downloaded certificate {'thumbprint': '1A9E97C3B1B6BAFE603B6122462AB1907D5B21A7', 'hasPrivateKey': True} Mar 12 02:58:17.530569 waagent[2108]: 2026-03-12T02:58:17.530534Z INFO ExtHandler Fetch goal state completed Mar 12 02:58:17.541686 waagent[2108]: 2026-03-12T02:58:17.541641Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.4 27 Jan 2026 (Library: OpenSSL 3.4.4 27 Jan 2026) Mar 12 02:58:17.544832 waagent[2108]: 2026-03-12T02:58:17.544790Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2108 Mar 12 02:58:17.544934 waagent[2108]: 2026-03-12T02:58:17.544907Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Mar 12 02:58:17.545200 waagent[2108]: 2026-03-12T02:58:17.545172Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Mar 12 02:58:17.546271 waagent[2108]: 2026-03-12T02:58:17.546237Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.4', '', 'Flatcar Container Linux by Kinvolk'] Mar 12 02:58:17.546590 waagent[2108]: 2026-03-12T02:58:17.546560Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.4', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Mar 12 02:58:17.546692 waagent[2108]: 2026-03-12T02:58:17.546671Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Mar 12 02:58:17.547123 waagent[2108]: 2026-03-12T02:58:17.547094Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 12 02:58:17.595501 waagent[2108]: 2026-03-12T02:58:17.595440Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 12 02:58:17.595592 waagent[2108]: 2026-03-12T02:58:17.595565Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 12 02:58:17.599932 waagent[2108]: 2026-03-12T02:58:17.599894Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 12 02:58:17.611874 systemd[1]: Reload requested from client PID 2123 ('systemctl') (unit waagent.service)... Mar 12 02:58:17.611887 systemd[1]: Reloading... Mar 12 02:58:17.685064 zram_generator::config[2165]: No configuration found. Mar 12 02:58:17.830259 systemd[1]: Reloading finished in 218 ms. Mar 12 02:58:17.855853 waagent[2108]: 2026-03-12T02:58:17.855744Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Mar 12 02:58:17.855924 waagent[2108]: 2026-03-12T02:58:17.855884Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Mar 12 02:58:18.118047 waagent[2108]: 2026-03-12T02:58:18.117531Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 12 02:58:18.118047 waagent[2108]: 2026-03-12T02:58:18.117826Z 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] Mar 12 02:58:18.118690 waagent[2108]: 2026-03-12T02:58:18.118651Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 12 02:58:18.118791 waagent[2108]: 2026-03-12T02:58:18.118755Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 12 02:58:18.119125 waagent[2108]: 2026-03-12T02:58:18.119089Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 12 02:58:18.119176 waagent[2108]: 2026-03-12T02:58:18.119148Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 12 02:58:18.119361 waagent[2108]: 2026-03-12T02:58:18.119332Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 12 02:58:18.119638 waagent[2108]: 2026-03-12T02:58:18.119603Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 12 02:58:18.119743 waagent[2108]: 2026-03-12T02:58:18.119702Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 12 02:58:18.120044 waagent[2108]: 2026-03-12T02:58:18.120005Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 12 02:58:18.120096 waagent[2108]: 2026-03-12T02:58:18.120084Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 12 02:58:18.120220 waagent[2108]: 2026-03-12T02:58:18.120164Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 12 02:58:18.120415 waagent[2108]: 2026-03-12T02:58:18.120388Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 12 02:58:18.120548 waagent[2108]: 2026-03-12T02:58:18.120491Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 12 02:58:18.120548 waagent[2108]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 12 02:58:18.120548 waagent[2108]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Mar 12 02:58:18.120548 waagent[2108]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 12 02:58:18.120548 waagent[2108]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 12 02:58:18.120548 waagent[2108]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 12 02:58:18.120548 waagent[2108]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 12 02:58:18.120660 waagent[2108]: 2026-03-12T02:58:18.120581Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 12 02:58:18.120695 waagent[2108]: 2026-03-12T02:58:18.120669Z INFO EnvHandler ExtHandler Configure routes Mar 12 02:58:18.120736 waagent[2108]: 2026-03-12T02:58:18.120716Z INFO EnvHandler ExtHandler Gateway:None Mar 12 02:58:18.120760 waagent[2108]: 2026-03-12T02:58:18.120743Z INFO EnvHandler ExtHandler Routes:None Mar 12 02:58:18.126989 waagent[2108]: 2026-03-12T02:58:18.126503Z INFO ExtHandler ExtHandler Mar 12 02:58:18.126989 waagent[2108]: 2026-03-12T02:58:18.126562Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 1f23fec5-d0d9-4b17-a3a4-20c934e85cd2 correlation cba8bd93-ccd2-42db-bb36-eb4a980085ed created: 2026-03-12T02:56:45.496330Z] Mar 12 02:58:18.126989 waagent[2108]: 2026-03-12T02:58:18.126794Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 12 02:58:18.127355 waagent[2108]: 2026-03-12T02:58:18.127325Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Mar 12 02:58:18.208358 waagent[2108]: 2026-03-12T02:58:18.208325Z 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 Mar 12 02:58:18.208358 waagent[2108]: Try `iptables -h' or 'iptables --help' for more information.) Mar 12 02:58:18.208731 waagent[2108]: 2026-03-12T02:58:18.208705Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: AD9E3CD1-E932-4CBD-BCF1-4E0A4AEB0D4A;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Mar 12 02:58:18.243123 waagent[2108]: 2026-03-12T02:58:18.243085Z INFO MonitorHandler ExtHandler Network interfaces: Mar 12 02:58:18.243123 waagent[2108]: Executing ['ip', '-a', '-o', 'link']: Mar 12 02:58:18.243123 waagent[2108]: 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 Mar 12 02:58:18.243123 waagent[2108]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6d:ff:73 brd ff:ff:ff:ff:ff:ff Mar 12 02:58:18.243123 waagent[2108]: 3: enP48142s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6d:ff:73 brd ff:ff:ff:ff:ff:ff\ altname enP48142p0s2 Mar 12 02:58:18.243123 waagent[2108]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 12 02:58:18.243123 waagent[2108]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 12 02:58:18.243123 waagent[2108]: 2: eth0 inet 10.200.20.17/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 12 02:58:18.243123 waagent[2108]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 12 02:58:18.243123 waagent[2108]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Mar 12 02:58:18.243123 waagent[2108]: 2: eth0 inet6 fe80::20d:3aff:fe6d:ff73/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 12 02:58:18.293828 waagent[2108]: 2026-03-12T02:58:18.293792Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Mar 12 02:58:18.293828 waagent[2108]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 12 02:58:18.293828 waagent[2108]: pkts bytes target prot opt in out source destination Mar 12 02:58:18.293828 waagent[2108]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 12 02:58:18.293828 waagent[2108]: pkts bytes target prot opt in out source destination Mar 12 02:58:18.293828 waagent[2108]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 12 02:58:18.293828 waagent[2108]: pkts bytes target prot opt in out source destination Mar 12 02:58:18.293828 waagent[2108]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 12 02:58:18.293828 waagent[2108]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 12 02:58:18.293828 waagent[2108]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 12 02:58:18.296224 waagent[2108]: 2026-03-12T02:58:18.296193Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 12 02:58:18.296224 waagent[2108]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 12 02:58:18.296224 waagent[2108]: pkts bytes target prot opt in out source destination Mar 12 02:58:18.296224 waagent[2108]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 12 02:58:18.296224 waagent[2108]: pkts bytes target prot opt in out source destination Mar 12 02:58:18.296224 waagent[2108]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 12 02:58:18.296224 waagent[2108]: pkts bytes target prot opt in out source destination Mar 12 02:58:18.296224 waagent[2108]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 12 02:58:18.296224 waagent[2108]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 12 02:58:18.296224 waagent[2108]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 12 02:58:18.296570 waagent[2108]: 2026-03-12T02:58:18.296548Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 12 02:58:24.272821 chronyd[1834]: Selected source PHC0 Mar 12 02:58:27.004836 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Mar 12 02:58:27.006423 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 12 02:58:27.469284 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 12 02:58:27.472360 (kubelet)[2260]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Mar 12 02:58:27.497607 kubelet[2260]: E0312 02:58:27.497568 2260 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" Mar 12 02:58:27.499359 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 12 02:58:27.499459 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 12 02:58:27.499706 systemd[1]: kubelet.service: Consumed 102ms CPU time, 104.9M memory peak. Mar 12 02:58:31.648076 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 12 02:58:31.650139 systemd[1]: Started sshd@0-10.200.20.17:22-10.200.16.10:58084.service - OpenSSH per-connection server daemon (10.200.16.10:58084). Mar 12 02:58:32.206884 sshd[2267]: Accepted publickey for core from 10.200.16.10 port 58084 ssh2: RSA SHA256:Z7iH1P3S73ZdxQIwiDYFg2VFhFwvaatKOiDPh/QZsqE Mar 12 02:58:32.207593 sshd-session[2267]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 12 02:58:32.211054 systemd-logind[1855]: New session 3 of user core. Mar 12 02:58:32.219080 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 12 02:58:32.524653 systemd[1]: Started sshd@1-10.200.20.17:22-10.200.16.10:58090.service - OpenSSH per-connection server daemon (10.200.16.10:58090). Mar 12 02:58:32.943081 sshd[2273]: Accepted publickey for core from 10.200.16.10 port 58090 ssh2: RSA SHA256:Z7iH1P3S73ZdxQIwiDYFg2VFhFwvaatKOiDPh/QZsqE Mar 12 02:58:32.944089 sshd-session[2273]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 12 02:58:32.947475 systemd-logind[1855]: New session 4 of user core. Mar 12 02:58:32.955078 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 12 02:58:33.176762 sshd[2276]: Connection closed by 10.200.16.10 port 58090 Mar 12 02:58:33.177557 sshd-session[2273]: pam_unix(sshd:session): session closed for user core Mar 12 02:58:33.181362 systemd[1]: sshd@1-10.200.20.17:22-10.200.16.10:58090.service: Deactivated successfully. Mar 12 02:58:33.183383 systemd[1]: session-4.scope: Deactivated successfully. Mar 12 02:58:33.184409 systemd-logind[1855]: Session 4 logged out. Waiting for processes to exit. Mar 12 02:58:33.186239 systemd-logind[1855]: Removed session 4. Mar 12 02:58:33.266160 systemd[1]: Started sshd@2-10.200.20.17:22-10.200.16.10:58094.service - OpenSSH per-connection server daemon (10.200.16.10:58094). Mar 12 02:58:33.683257 sshd[2282]: Accepted publickey for core from 10.200.16.10 port 58094 ssh2: RSA SHA256:Z7iH1P3S73ZdxQIwiDYFg2VFhFwvaatKOiDPh/QZsqE Mar 12 02:58:33.684215 sshd-session[2282]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 12 02:58:33.687688 systemd-logind[1855]: New session 5 of user core. Mar 12 02:58:33.695072 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 12 02:58:33.913387 sshd[2285]: Connection closed by 10.200.16.10 port 58094 Mar 12 02:58:33.913942 sshd-session[2282]: pam_unix(sshd:session): session closed for user core Mar 12 02:58:33.917419 systemd[1]: sshd@2-10.200.20.17:22-10.200.16.10:58094.service: Deactivated successfully. Mar 12 02:58:33.918667 systemd[1]: session-5.scope: Deactivated successfully. Mar 12 02:58:33.919252 systemd-logind[1855]: Session 5 logged out. Waiting for processes to exit. Mar 12 02:58:33.920214 systemd-logind[1855]: Removed session 5. Mar 12 02:58:34.006177 systemd[1]: Started sshd@3-10.200.20.17:22-10.200.16.10:58104.service - OpenSSH per-connection server daemon (10.200.16.10:58104). Mar 12 02:58:34.424084 sshd[2291]: Accepted publickey for core from 10.200.16.10 port 58104 ssh2: RSA SHA256:Z7iH1P3S73ZdxQIwiDYFg2VFhFwvaatKOiDPh/QZsqE Mar 12 02:58:34.426022 sshd-session[2291]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 12 02:58:34.429738 systemd-logind[1855]: New session 6 of user core. Mar 12 02:58:34.436063 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 12 02:58:34.657488 sshd[2294]: Connection closed by 10.200.16.10 port 58104 Mar 12 02:58:34.658255 sshd-session[2291]: pam_unix(sshd:session): session closed for user core Mar 12 02:58:34.662162 systemd-logind[1855]: Session 6 logged out. Waiting for processes to exit. Mar 12 02:58:34.662437 systemd[1]: sshd@3-10.200.20.17:22-10.200.16.10:58104.service: Deactivated successfully. Mar 12 02:58:34.663646 systemd[1]: session-6.scope: Deactivated successfully. Mar 12 02:58:34.664914 systemd-logind[1855]: Removed session 6. Mar 12 02:58:34.753348 systemd[1]: Started sshd@4-10.200.20.17:22-10.200.16.10:58106.service - OpenSSH per-connection server daemon (10.200.16.10:58106). Mar 12 02:58:35.179019 sshd[2300]: Accepted publickey for core from 10.200.16.10 port 58106 ssh2: RSA SHA256:Z7iH1P3S73ZdxQIwiDYFg2VFhFwvaatKOiDPh/QZsqE Mar 12 02:58:35.180017 sshd-session[2300]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 12 02:58:35.183705 systemd-logind[1855]: New session 7 of user core. Mar 12 02:58:35.189077 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 12 02:58:35.424554 sudo[2304]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Mar 12 02:58:35.424770 sudo[2304]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 12 02:58:35.794666 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 12 02:58:35.794817 systemd[1]: kubelet.service: Consumed 102ms CPU time, 104.9M memory peak. Mar 12 02:58:35.802163 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 12 02:58:35.814461 systemd[1]: Reload requested from client PID 2340 ('systemctl') (unit session-7.scope)... Mar 12 02:58:35.814802 systemd[1]: Reloading... Mar 12 02:58:35.889117 zram_generator::config[2390]: No configuration found. Mar 12 02:58:36.042442 systemd[1]: Reloading finished in 227 ms. Mar 12 02:58:36.133862 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Mar 12 02:58:36.134114 systemd[1]: kubelet.service: Failed with result 'signal'. Mar 12 02:58:36.134416 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 12 02:58:36.134542 systemd[1]: kubelet.service: Consumed 57ms CPU time, 80.3M memory peak. Mar 12 02:58:36.136518 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 12 02:58:37.589502 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Mar 12 02:58:37.988780 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 12 02:58:37.991627 (kubelet)[2451]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Mar 12 02:58:38.015976 kubelet[2451]: 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. Mar 12 02:58:38.357359 kubelet[2451]: I0312 02:58:38.357312 2451 server.go:525] "Kubelet version" kubeletVersion="v1.35.1" Mar 12 02:58:38.357359 kubelet[2451]: I0312 02:58:38.357349 2451 server.go:527] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Mar 12 02:58:38.357359 kubelet[2451]: I0312 02:58:38.357370 2451 watchdog_linux.go:95] "Systemd watchdog is not enabled" Mar 12 02:58:38.357359 kubelet[2451]: I0312 02:58:38.357374 2451 watchdog_linux.go:138] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Mar 12 02:58:38.357550 kubelet[2451]: I0312 02:58:38.357537 2451 server.go:951] "Client rotation is on, will bootstrap in background" Mar 12 02:58:38.359675 kubelet[2451]: I0312 02:58:38.359656 2451 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Mar 12 02:58:38.362675 kubelet[2451]: I0312 02:58:38.362663 2451 server.go:1418] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Mar 12 02:58:38.365700 kubelet[2451]: I0312 02:58:38.365682 2451 server.go:775] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Mar 12 02:58:38.366590 kubelet[2451]: I0312 02:58:38.366564 2451 container_manager_linux.go:272] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Mar 12 02:58:38.366784 kubelet[2451]: I0312 02:58:38.366668 2451 container_manager_linux.go:277] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.20.17","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Mar 12 02:58:38.366897 kubelet[2451]: I0312 02:58:38.366886 2451 topology_manager.go:143] "Creating topology manager with none policy" Mar 12 02:58:38.366942 kubelet[2451]: I0312 02:58:38.366935 2451 container_manager_linux.go:308] "Creating device plugin manager" Mar 12 02:58:38.367078 kubelet[2451]: I0312 02:58:38.367067 2451 container_manager_linux.go:317] "Creating Dynamic Resource Allocation (DRA) manager" Mar 12 02:58:38.516422 kubelet[2451]: I0312 02:58:38.516387 2451 state_mem.go:41] "Initialized" logger="CPUManager state memory" Mar 12 02:58:38.516779 kubelet[2451]: I0312 02:58:38.516758 2451 kubelet.go:482] "Attempting to sync node with API server" Mar 12 02:58:38.516779 kubelet[2451]: I0312 02:58:38.516782 2451 kubelet.go:383] "Adding static pod path" path="/etc/kubernetes/manifests" Mar 12 02:58:38.516868 kubelet[2451]: I0312 02:58:38.516797 2451 kubelet.go:394] "Adding apiserver pod source" Mar 12 02:58:38.516868 kubelet[2451]: I0312 02:58:38.516806 2451 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Mar 12 02:58:38.518258 kubelet[2451]: E0312 02:58:38.517259 2451 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:38.518258 kubelet[2451]: E0312 02:58:38.518024 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:38.519397 kubelet[2451]: I0312 02:58:38.519379 2451 kuberuntime_manager.go:294] "Container runtime initialized" containerRuntime="containerd" version="v2.0.7" apiVersion="v1" Mar 12 02:58:38.520223 kubelet[2451]: I0312 02:58:38.520210 2451 kubelet.go:943] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Mar 12 02:58:38.520306 kubelet[2451]: I0312 02:58:38.520299 2451 kubelet.go:970] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Mar 12 02:58:38.520392 kubelet[2451]: W0312 02:58:38.520384 2451 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Mar 12 02:58:38.522948 kubelet[2451]: I0312 02:58:38.522934 2451 server.go:1257] "Started kubelet" Mar 12 02:58:38.525369 kubelet[2451]: I0312 02:58:38.525315 2451 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer" Mar 12 02:58:38.526074 kubelet[2451]: I0312 02:58:38.526012 2451 server.go:182] "Starting to listen" address="0.0.0.0" port=10250 Mar 12 02:58:38.528199 kubelet[2451]: I0312 02:58:38.528136 2451 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Mar 12 02:58:38.528258 kubelet[2451]: I0312 02:58:38.528200 2451 server_v1.go:49] "podresources" method="list" useActivePods=true Mar 12 02:58:38.528537 kubelet[2451]: I0312 02:58:38.528518 2451 server.go:254] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Mar 12 02:58:38.529441 kubelet[2451]: E0312 02:58:38.529423 2451 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"10.200.20.17\" not found" Mar 12 02:58:38.530421 kubelet[2451]: I0312 02:58:38.530373 2451 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Mar 12 02:58:38.532974 kubelet[2451]: I0312 02:58:38.531838 2451 volume_manager.go:311] "Starting Kubelet Volume Manager" Mar 12 02:58:38.532974 kubelet[2451]: I0312 02:58:38.532100 2451 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Mar 12 02:58:38.532974 kubelet[2451]: I0312 02:58:38.532133 2451 reconciler.go:29] "Reconciler: start to sync state" Mar 12 02:58:38.535373 kubelet[2451]: I0312 02:58:38.535356 2451 server.go:317] "Adding debug handlers to kubelet server" Mar 12 02:58:38.536161 kubelet[2451]: I0312 02:58:38.536148 2451 factory.go:223] Registration of the systemd container factory successfully Mar 12 02:58:38.536334 kubelet[2451]: I0312 02:58:38.536319 2451 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 Mar 12 02:58:38.537410 kubelet[2451]: E0312 02:58:38.537191 2451 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.200.20.17\" not found" node="10.200.20.17" Mar 12 02:58:38.537579 kubelet[2451]: I0312 02:58:38.537383 2451 factory.go:223] Registration of the containerd container factory successfully Mar 12 02:58:38.537820 kubelet[2451]: E0312 02:58:38.537552 2451 kubelet.go:1656] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Mar 12 02:58:38.542864 kubelet[2451]: I0312 02:58:38.542853 2451 cpu_manager.go:225] "Starting" policy="none" Mar 12 02:58:38.543039 kubelet[2451]: I0312 02:58:38.542952 2451 cpu_manager.go:226] "Reconciling" reconcilePeriod="10s" Mar 12 02:58:38.543118 kubelet[2451]: I0312 02:58:38.543110 2451 state_mem.go:41] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory" Mar 12 02:58:38.549833 kubelet[2451]: I0312 02:58:38.549818 2451 policy_none.go:50] "Start" Mar 12 02:58:38.549908 kubelet[2451]: I0312 02:58:38.549900 2451 memory_manager.go:187] "Starting memorymanager" policy="None" Mar 12 02:58:38.549992 kubelet[2451]: I0312 02:58:38.549984 2451 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Mar 12 02:58:38.555110 kubelet[2451]: I0312 02:58:38.555097 2451 policy_none.go:44] "Start" Mar 12 02:58:38.559005 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Mar 12 02:58:38.570458 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Mar 12 02:58:38.571006 kubelet[2451]: I0312 02:58:38.570977 2451 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Mar 12 02:58:38.573012 kubelet[2451]: I0312 02:58:38.572982 2451 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Mar 12 02:58:38.573012 kubelet[2451]: I0312 02:58:38.573001 2451 status_manager.go:249] "Starting to sync pod status with apiserver" Mar 12 02:58:38.573086 kubelet[2451]: I0312 02:58:38.573019 2451 kubelet.go:2501] "Starting kubelet main sync loop" Mar 12 02:58:38.573086 kubelet[2451]: E0312 02:58:38.573057 2451 kubelet.go:2525] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Mar 12 02:58:38.576021 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Mar 12 02:58:38.590780 kubelet[2451]: E0312 02:58:38.590757 2451 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Mar 12 02:58:38.592474 kubelet[2451]: I0312 02:58:38.591429 2451 eviction_manager.go:194] "Eviction manager: starting control loop" Mar 12 02:58:38.592474 kubelet[2451]: I0312 02:58:38.591445 2451 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Mar 12 02:58:38.592474 kubelet[2451]: I0312 02:58:38.591639 2451 plugin_manager.go:121] "Starting Kubelet Plugin Manager" Mar 12 02:58:38.592950 kubelet[2451]: E0312 02:58:38.592916 2451 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Mar 12 02:58:38.592950 kubelet[2451]: E0312 02:58:38.592949 2451 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.20.17\" not found" Mar 12 02:58:38.693049 kubelet[2451]: I0312 02:58:38.692231 2451 kubelet_node_status.go:74] "Attempting to register node" node="10.200.20.17" Mar 12 02:58:38.697435 kubelet[2451]: I0312 02:58:38.697410 2451 kubelet_node_status.go:77] "Successfully registered node" node="10.200.20.17" Mar 12 02:58:38.804292 kubelet[2451]: I0312 02:58:38.804264 2451 kuberuntime_manager.go:2062] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Mar 12 02:58:38.804735 containerd[1877]: time="2026-03-12T02:58:38.804704814Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Mar 12 02:58:38.805360 kubelet[2451]: I0312 02:58:38.805152 2451 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Mar 12 02:58:39.000913 sudo[2304]: pam_unix(sudo:session): session closed for user root Mar 12 02:58:39.078761 sshd[2303]: Connection closed by 10.200.16.10 port 58106 Mar 12 02:58:39.079332 sshd-session[2300]: pam_unix(sshd:session): session closed for user core Mar 12 02:58:39.082751 systemd-logind[1855]: Session 7 logged out. Waiting for processes to exit. Mar 12 02:58:39.083232 systemd[1]: sshd@4-10.200.20.17:22-10.200.16.10:58106.service: Deactivated successfully. Mar 12 02:58:39.086291 systemd[1]: session-7.scope: Deactivated successfully. Mar 12 02:58:39.086559 systemd[1]: session-7.scope: Consumed 285ms CPU time, 69.6M memory peak. Mar 12 02:58:39.088454 systemd-logind[1855]: Removed session 7. Mar 12 02:58:39.359205 kubelet[2451]: I0312 02:58:39.359169 2451 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Mar 12 02:58:39.359569 kubelet[2451]: I0312 02:58:39.359379 2451 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Mar 12 02:58:39.359569 kubelet[2451]: I0312 02:58:39.359411 2451 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Mar 12 02:58:39.359671 kubelet[2451]: I0312 02:58:39.359637 2451 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Mar 12 02:58:39.518838 kubelet[2451]: E0312 02:58:39.518806 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:39.518838 kubelet[2451]: I0312 02:58:39.518809 2451 apiserver.go:52] "Watching apiserver" Mar 12 02:58:39.534196 kubelet[2451]: I0312 02:58:39.532364 2451 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Mar 12 02:58:39.535327 systemd[1]: Created slice kubepods-burstable-podf62e647f_cd8f_4383_bcb9_fa1b1ba20499.slice - libcontainer container kubepods-burstable-podf62e647f_cd8f_4383_bcb9_fa1b1ba20499.slice. Mar 12 02:58:39.535479 kubelet[2451]: I0312 02:58:39.535455 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a67af15a-af60-4dbe-bfdc-5b979a53dd46-xtables-lock\") pod \"kube-proxy-m5tbt\" (UID: \"a67af15a-af60-4dbe-bfdc-5b979a53dd46\") " pod="kube-system/kube-proxy-m5tbt" Mar 12 02:58:39.535479 kubelet[2451]: I0312 02:58:39.535479 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f62e647f-cd8f-4383-bcb9-fa1b1ba20499-cni-plugin\") pod \"kube-flannel-ds-9r2wx\" (UID: \"f62e647f-cd8f-4383-bcb9-fa1b1ba20499\") " pod="kube-flannel/kube-flannel-ds-9r2wx" Mar 12 02:58:39.535536 kubelet[2451]: I0312 02:58:39.535490 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f62e647f-cd8f-4383-bcb9-fa1b1ba20499-flannel-cfg\") pod \"kube-flannel-ds-9r2wx\" (UID: \"f62e647f-cd8f-4383-bcb9-fa1b1ba20499\") " pod="kube-flannel/kube-flannel-ds-9r2wx" Mar 12 02:58:39.535536 kubelet[2451]: I0312 02:58:39.535500 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8jqck\" (UniqueName: \"kubernetes.io/projected/f62e647f-cd8f-4383-bcb9-fa1b1ba20499-kube-api-access-8jqck\") pod \"kube-flannel-ds-9r2wx\" (UID: \"f62e647f-cd8f-4383-bcb9-fa1b1ba20499\") " pod="kube-flannel/kube-flannel-ds-9r2wx" Mar 12 02:58:39.535536 kubelet[2451]: I0312 02:58:39.535511 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/a67af15a-af60-4dbe-bfdc-5b979a53dd46-lib-modules\") pod \"kube-proxy-m5tbt\" (UID: \"a67af15a-af60-4dbe-bfdc-5b979a53dd46\") " pod="kube-system/kube-proxy-m5tbt" Mar 12 02:58:39.535536 kubelet[2451]: I0312 02:58:39.535520 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-n6np2\" (UniqueName: \"kubernetes.io/projected/a67af15a-af60-4dbe-bfdc-5b979a53dd46-kube-api-access-n6np2\") pod \"kube-proxy-m5tbt\" (UID: \"a67af15a-af60-4dbe-bfdc-5b979a53dd46\") " pod="kube-system/kube-proxy-m5tbt" Mar 12 02:58:39.535536 kubelet[2451]: I0312 02:58:39.535528 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f62e647f-cd8f-4383-bcb9-fa1b1ba20499-run\") pod \"kube-flannel-ds-9r2wx\" (UID: \"f62e647f-cd8f-4383-bcb9-fa1b1ba20499\") " pod="kube-flannel/kube-flannel-ds-9r2wx" Mar 12 02:58:39.535609 kubelet[2451]: I0312 02:58:39.535536 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f62e647f-cd8f-4383-bcb9-fa1b1ba20499-cni\") pod \"kube-flannel-ds-9r2wx\" (UID: \"f62e647f-cd8f-4383-bcb9-fa1b1ba20499\") " pod="kube-flannel/kube-flannel-ds-9r2wx" Mar 12 02:58:39.535609 kubelet[2451]: I0312 02:58:39.535545 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f62e647f-cd8f-4383-bcb9-fa1b1ba20499-xtables-lock\") pod \"kube-flannel-ds-9r2wx\" (UID: \"f62e647f-cd8f-4383-bcb9-fa1b1ba20499\") " pod="kube-flannel/kube-flannel-ds-9r2wx" Mar 12 02:58:39.535609 kubelet[2451]: I0312 02:58:39.535554 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/a67af15a-af60-4dbe-bfdc-5b979a53dd46-kube-proxy\") pod \"kube-proxy-m5tbt\" (UID: \"a67af15a-af60-4dbe-bfdc-5b979a53dd46\") " pod="kube-system/kube-proxy-m5tbt" Mar 12 02:58:39.549111 systemd[1]: Created slice kubepods-besteffort-poda67af15a_af60_4dbe_bfdc_5b979a53dd46.slice - libcontainer container kubepods-besteffort-poda67af15a_af60_4dbe_bfdc_5b979a53dd46.slice. Mar 12 02:58:39.861257 containerd[1877]: time="2026-03-12T02:58:39.861212402Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9r2wx,Uid:f62e647f-cd8f-4383-bcb9-fa1b1ba20499,Namespace:kube-flannel,Attempt:0,}" Mar 12 02:58:39.864449 containerd[1877]: time="2026-03-12T02:58:39.864411358Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-m5tbt,Uid:a67af15a-af60-4dbe-bfdc-5b979a53dd46,Namespace:kube-system,Attempt:0,}" Mar 12 02:58:40.519494 kubelet[2451]: E0312 02:58:40.519454 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:40.539796 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2609519336.mount: Deactivated successfully. Mar 12 02:58:40.561951 containerd[1877]: time="2026-03-12T02:58:40.561495709Z" 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\"}" Mar 12 02:58:40.568955 containerd[1877]: time="2026-03-12T02:58:40.568924856Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Mar 12 02:58:40.574453 containerd[1877]: time="2026-03-12T02:58:40.574316640Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Mar 12 02:58:40.576578 containerd[1877]: time="2026-03-12T02:58:40.576544840Z" 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\"}" Mar 12 02:58:40.580838 containerd[1877]: time="2026-03-12T02:58:40.580809340Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Mar 12 02:58:40.583372 containerd[1877]: time="2026-03-12T02:58:40.583177251Z" 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\"}" Mar 12 02:58:40.583624 containerd[1877]: time="2026-03-12T02:58:40.583600010Z" 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 707.330774ms" Mar 12 02:58:40.584575 containerd[1877]: time="2026-03-12T02:58:40.584551840Z" 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 712.953754ms" Mar 12 02:58:40.623621 containerd[1877]: time="2026-03-12T02:58:40.623582829Z" level=info msg="connecting to shim 2e7c69aacac5ac775c68bdd5f380da332acf707b11792b20355d42d081665d53" address="unix:///run/containerd/s/8008ad6d973ffe6d142bedbe9ca77476119b90058bbfcf6714acc20176277753" namespace=k8s.io protocol=ttrpc version=3 Mar 12 02:58:40.625274 containerd[1877]: time="2026-03-12T02:58:40.625246775Z" level=info msg="connecting to shim 2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10" address="unix:///run/containerd/s/b859e5682261fae4f919a86820637a42532c8b1dc877785b1506e94985a394a4" namespace=k8s.io protocol=ttrpc version=3 Mar 12 02:58:40.647107 systemd[1]: Started cri-containerd-2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10.scope - libcontainer container 2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10. Mar 12 02:58:40.648618 systemd[1]: Started cri-containerd-2e7c69aacac5ac775c68bdd5f380da332acf707b11792b20355d42d081665d53.scope - libcontainer container 2e7c69aacac5ac775c68bdd5f380da332acf707b11792b20355d42d081665d53. Mar 12 02:58:40.677086 containerd[1877]: time="2026-03-12T02:58:40.677055946Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-m5tbt,Uid:a67af15a-af60-4dbe-bfdc-5b979a53dd46,Namespace:kube-system,Attempt:0,} returns sandbox id \"2e7c69aacac5ac775c68bdd5f380da332acf707b11792b20355d42d081665d53\"" Mar 12 02:58:40.681587 containerd[1877]: time="2026-03-12T02:58:40.681397321Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.2\"" Mar 12 02:58:40.687368 containerd[1877]: time="2026-03-12T02:58:40.687197735Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9r2wx,Uid:f62e647f-cd8f-4383-bcb9-fa1b1ba20499,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10\"" Mar 12 02:58:41.520042 kubelet[2451]: E0312 02:58:41.520000 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:41.626253 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1606085382.mount: Deactivated successfully. Mar 12 02:58:41.821792 containerd[1877]: time="2026-03-12T02:58:41.821674173Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.35.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:41.824681 containerd[1877]: time="2026-03-12T02:58:41.824650167Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.35.2: active requests=0, bytes read=22329583" Mar 12 02:58:41.827135 containerd[1877]: time="2026-03-12T02:58:41.827107530Z" level=info msg="ImageCreate event name:\"sha256:df7dcaf93e84e5dfbe96b2f86588b38a8959748d9c84b2e0532e2b5ae1bc5884\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:41.830767 containerd[1877]: time="2026-03-12T02:58:41.830736228Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:015265214cc874b593a7adccdcfe4ac15d2b8e9ae89881bdcd5bcb99d42e1862\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:41.831158 containerd[1877]: time="2026-03-12T02:58:41.831023251Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.35.2\" with image id \"sha256:df7dcaf93e84e5dfbe96b2f86588b38a8959748d9c84b2e0532e2b5ae1bc5884\", repo tag \"registry.k8s.io/kube-proxy:v1.35.2\", repo digest \"registry.k8s.io/kube-proxy@sha256:015265214cc874b593a7adccdcfe4ac15d2b8e9ae89881bdcd5bcb99d42e1862\", size \"22328602\" in 1.149283191s" Mar 12 02:58:41.831158 containerd[1877]: time="2026-03-12T02:58:41.831054393Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.2\" returns image reference \"sha256:df7dcaf93e84e5dfbe96b2f86588b38a8959748d9c84b2e0532e2b5ae1bc5884\"" Mar 12 02:58:41.832658 containerd[1877]: time="2026-03-12T02:58:41.832371321Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Mar 12 02:58:41.838647 containerd[1877]: time="2026-03-12T02:58:41.838623900Z" level=info msg="CreateContainer within sandbox \"2e7c69aacac5ac775c68bdd5f380da332acf707b11792b20355d42d081665d53\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Mar 12 02:58:41.855595 containerd[1877]: time="2026-03-12T02:58:41.855568387Z" level=info msg="Container f61ba766545096cf745879f4e56cb5d86ba13015ecc11ecc041fe33c1d79aafc: CDI devices from CRI Config.CDIDevices: []" Mar 12 02:58:41.873001 containerd[1877]: time="2026-03-12T02:58:41.872948783Z" level=info msg="CreateContainer within sandbox \"2e7c69aacac5ac775c68bdd5f380da332acf707b11792b20355d42d081665d53\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"f61ba766545096cf745879f4e56cb5d86ba13015ecc11ecc041fe33c1d79aafc\"" Mar 12 02:58:41.873972 containerd[1877]: time="2026-03-12T02:58:41.873902893Z" level=info msg="StartContainer for \"f61ba766545096cf745879f4e56cb5d86ba13015ecc11ecc041fe33c1d79aafc\"" Mar 12 02:58:41.875060 containerd[1877]: time="2026-03-12T02:58:41.875040144Z" level=info msg="connecting to shim f61ba766545096cf745879f4e56cb5d86ba13015ecc11ecc041fe33c1d79aafc" address="unix:///run/containerd/s/8008ad6d973ffe6d142bedbe9ca77476119b90058bbfcf6714acc20176277753" protocol=ttrpc version=3 Mar 12 02:58:41.895074 systemd[1]: Started cri-containerd-f61ba766545096cf745879f4e56cb5d86ba13015ecc11ecc041fe33c1d79aafc.scope - libcontainer container f61ba766545096cf745879f4e56cb5d86ba13015ecc11ecc041fe33c1d79aafc. Mar 12 02:58:41.970468 containerd[1877]: time="2026-03-12T02:58:41.970399852Z" level=info msg="StartContainer for \"f61ba766545096cf745879f4e56cb5d86ba13015ecc11ecc041fe33c1d79aafc\" returns successfully" Mar 12 02:58:42.520722 kubelet[2451]: E0312 02:58:42.520670 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:42.596436 kubelet[2451]: I0312 02:58:42.596370 2451 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-system/kube-proxy-m5tbt" podStartSLOduration=3.444316532 podStartE2EDuration="4.59634774s" podCreationTimestamp="2026-03-12 02:58:38 +0000 UTC" firstStartedPulling="2026-03-12 02:58:40.680029252 +0000 UTC m=+2.685954157" lastFinishedPulling="2026-03-12 02:58:41.83206046 +0000 UTC m=+3.837985365" observedRunningTime="2026-03-12 02:58:42.595980283 +0000 UTC m=+4.601905220" watchObservedRunningTime="2026-03-12 02:58:42.59634774 +0000 UTC m=+4.602272645" Mar 12 02:58:42.977793 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1459809010.mount: Deactivated successfully. Mar 12 02:58:43.045658 containerd[1877]: time="2026-03-12T02:58:43.045054920Z" 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\"}" Mar 12 02:58:43.050141 containerd[1877]: time="2026-03-12T02:58:43.050115259Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Mar 12 02:58:43.053276 containerd[1877]: time="2026-03-12T02:58:43.053251948Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:43.057299 containerd[1877]: time="2026-03-12T02:58:43.057273287Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:43.057768 containerd[1877]: time="2026-03-12T02:58:43.057740994Z" 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.225349299s" Mar 12 02:58:43.057817 containerd[1877]: time="2026-03-12T02:58:43.057769024Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Mar 12 02:58:43.065059 containerd[1877]: time="2026-03-12T02:58:43.064702106Z" level=info msg="CreateContainer within sandbox \"2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Mar 12 02:58:43.082494 containerd[1877]: time="2026-03-12T02:58:43.082470407Z" level=info msg="Container 3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2: CDI devices from CRI Config.CDIDevices: []" Mar 12 02:58:43.096231 containerd[1877]: time="2026-03-12T02:58:43.096198330Z" level=info msg="CreateContainer within sandbox \"2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2\"" Mar 12 02:58:43.096744 containerd[1877]: time="2026-03-12T02:58:43.096723602Z" level=info msg="StartContainer for \"3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2\"" Mar 12 02:58:43.097576 containerd[1877]: time="2026-03-12T02:58:43.097518562Z" level=info msg="connecting to shim 3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2" address="unix:///run/containerd/s/b859e5682261fae4f919a86820637a42532c8b1dc877785b1506e94985a394a4" protocol=ttrpc version=3 Mar 12 02:58:43.110076 systemd[1]: Started cri-containerd-3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2.scope - libcontainer container 3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2. Mar 12 02:58:43.128455 systemd[1]: cri-containerd-3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2.scope: Deactivated successfully. Mar 12 02:58:43.132665 containerd[1877]: time="2026-03-12T02:58:43.132590536Z" level=info msg="received container exit event container_id:\"3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2\" id:\"3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2\" pid:2784 exited_at:{seconds:1773284323 nanos:131301511}" Mar 12 02:58:43.133155 containerd[1877]: time="2026-03-12T02:58:43.132887462Z" level=info msg="StartContainer for \"3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2\" returns successfully" Mar 12 02:58:43.521001 kubelet[2451]: E0312 02:58:43.520943 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:43.923067 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3cb7eac5b0bc717bbc2ddce5bdab2f4d67aa31780cbbe626dbbb3d686e15a7f2-rootfs.mount: Deactivated successfully. Mar 12 02:58:44.521931 kubelet[2451]: E0312 02:58:44.521901 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:44.591217 containerd[1877]: time="2026-03-12T02:58:44.591181715Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Mar 12 02:58:45.522864 kubelet[2451]: E0312 02:58:45.522828 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:46.273160 containerd[1877]: time="2026-03-12T02:58:46.273116952Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:46.275948 containerd[1877]: time="2026-03-12T02:58:46.275916126Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Mar 12 02:58:46.278254 containerd[1877]: time="2026-03-12T02:58:46.278217018Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:46.283987 containerd[1877]: time="2026-03-12T02:58:46.283393710Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:46.284112 containerd[1877]: time="2026-03-12T02:58:46.284084220Z" 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 1.692863755s" Mar 12 02:58:46.284171 containerd[1877]: time="2026-03-12T02:58:46.284160959Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Mar 12 02:58:46.290986 containerd[1877]: time="2026-03-12T02:58:46.290945882Z" level=info msg="CreateContainer within sandbox \"2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Mar 12 02:58:46.310042 containerd[1877]: time="2026-03-12T02:58:46.310006136Z" level=info msg="Container 6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3: CDI devices from CRI Config.CDIDevices: []" Mar 12 02:58:46.323543 containerd[1877]: time="2026-03-12T02:58:46.323507073Z" level=info msg="CreateContainer within sandbox \"2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3\"" Mar 12 02:58:46.324284 containerd[1877]: time="2026-03-12T02:58:46.324266275Z" level=info msg="StartContainer for \"6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3\"" Mar 12 02:58:46.325169 containerd[1877]: time="2026-03-12T02:58:46.325135158Z" level=info msg="connecting to shim 6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3" address="unix:///run/containerd/s/b859e5682261fae4f919a86820637a42532c8b1dc877785b1506e94985a394a4" protocol=ttrpc version=3 Mar 12 02:58:46.344092 systemd[1]: Started cri-containerd-6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3.scope - libcontainer container 6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3. Mar 12 02:58:46.363447 systemd[1]: cri-containerd-6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3.scope: Deactivated successfully. Mar 12 02:58:46.367651 containerd[1877]: time="2026-03-12T02:58:46.367618721Z" level=info msg="received container exit event container_id:\"6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3\" id:\"6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3\" pid:2857 exited_at:{seconds:1773284326 nanos:363704711}" Mar 12 02:58:46.368204 containerd[1877]: time="2026-03-12T02:58:46.368183518Z" level=info msg="StartContainer for \"6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3\" returns successfully" Mar 12 02:58:46.382450 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6c392c1b4fe8f716ef22f83d559136a8bfdcaabfae519ac3272b5539881779f3-rootfs.mount: Deactivated successfully. Mar 12 02:58:46.439359 kubelet[2451]: I0312 02:58:46.439333 2451 kubelet_node_status.go:427] "Fast updating node status as it just became ready" Mar 12 02:58:46.523978 kubelet[2451]: E0312 02:58:46.523855 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:46.999097 update_engine[1857]: I20260312 02:58:46.998509 1857 update_attempter.cc:509] Updating boot flags... Mar 12 02:58:47.524265 kubelet[2451]: E0312 02:58:47.524222 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:47.608008 containerd[1877]: time="2026-03-12T02:58:47.607269587Z" level=info msg="CreateContainer within sandbox \"2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Mar 12 02:58:47.627909 containerd[1877]: time="2026-03-12T02:58:47.627820039Z" level=info msg="Container 6498675f90fb38d8cda4ada4a3a9fd9a008467e546cff2360e9d63dbaa0a865b: CDI devices from CRI Config.CDIDevices: []" Mar 12 02:58:47.629755 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1406656992.mount: Deactivated successfully. Mar 12 02:58:47.641874 containerd[1877]: time="2026-03-12T02:58:47.641785028Z" level=info msg="CreateContainer within sandbox \"2abf398652ebeb155b4c43dcb694abbbb2ca8b26b56529376fc6353d56e0fe10\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"6498675f90fb38d8cda4ada4a3a9fd9a008467e546cff2360e9d63dbaa0a865b\"" Mar 12 02:58:47.642450 containerd[1877]: time="2026-03-12T02:58:47.642429316Z" level=info msg="StartContainer for \"6498675f90fb38d8cda4ada4a3a9fd9a008467e546cff2360e9d63dbaa0a865b\"" Mar 12 02:58:47.643360 containerd[1877]: time="2026-03-12T02:58:47.643158568Z" level=info msg="connecting to shim 6498675f90fb38d8cda4ada4a3a9fd9a008467e546cff2360e9d63dbaa0a865b" address="unix:///run/containerd/s/b859e5682261fae4f919a86820637a42532c8b1dc877785b1506e94985a394a4" protocol=ttrpc version=3 Mar 12 02:58:47.660084 systemd[1]: Started cri-containerd-6498675f90fb38d8cda4ada4a3a9fd9a008467e546cff2360e9d63dbaa0a865b.scope - libcontainer container 6498675f90fb38d8cda4ada4a3a9fd9a008467e546cff2360e9d63dbaa0a865b. Mar 12 02:58:47.683170 containerd[1877]: time="2026-03-12T02:58:47.683132491Z" level=info msg="StartContainer for \"6498675f90fb38d8cda4ada4a3a9fd9a008467e546cff2360e9d63dbaa0a865b\" returns successfully" Mar 12 02:58:48.524757 kubelet[2451]: E0312 02:58:48.524709 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:48.780249 systemd-networkd[1486]: flannel.1: Link UP Mar 12 02:58:48.780256 systemd-networkd[1486]: flannel.1: Gained carrier Mar 12 02:58:49.524848 kubelet[2451]: E0312 02:58:49.524801 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:50.341243 systemd-networkd[1486]: flannel.1: Gained IPv6LL Mar 12 02:58:50.525797 kubelet[2451]: E0312 02:58:50.525756 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:51.526603 kubelet[2451]: E0312 02:58:51.526558 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:52.219752 kubelet[2451]: I0312 02:58:52.219693 2451 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-9r2wx" podStartSLOduration=7.307143777 podStartE2EDuration="14.219678784s" podCreationTimestamp="2026-03-12 02:58:38 +0000 UTC" firstStartedPulling="2026-03-12 02:58:40.688474586 +0000 UTC m=+2.694399491" lastFinishedPulling="2026-03-12 02:58:47.601009585 +0000 UTC m=+9.606934498" observedRunningTime="2026-03-12 02:58:48.618529007 +0000 UTC m=+10.624453920" watchObservedRunningTime="2026-03-12 02:58:52.219678784 +0000 UTC m=+14.225603689" Mar 12 02:58:52.232254 systemd[1]: Created slice kubepods-besteffort-pod783c58f6_871d_4846_9158_c3d39cfd3786.slice - libcontainer container kubepods-besteffort-pod783c58f6_871d_4846_9158_c3d39cfd3786.slice. Mar 12 02:58:52.405077 kubelet[2451]: I0312 02:58:52.405014 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h2lnr\" (UniqueName: \"kubernetes.io/projected/783c58f6-871d-4846-9158-c3d39cfd3786-kube-api-access-h2lnr\") pod \"nginx-deployment-6cc69d4fc7-tmtbm\" (UID: \"783c58f6-871d-4846-9158-c3d39cfd3786\") " pod="default/nginx-deployment-6cc69d4fc7-tmtbm" Mar 12 02:58:52.527584 kubelet[2451]: E0312 02:58:52.527552 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:52.543617 containerd[1877]: time="2026-03-12T02:58:52.543584951Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-tmtbm,Uid:783c58f6-871d-4846-9158-c3d39cfd3786,Namespace:default,Attempt:0,}" Mar 12 02:58:52.578094 systemd-networkd[1486]: cni0: Link UP Mar 12 02:58:52.578100 systemd-networkd[1486]: cni0: Gained carrier Mar 12 02:58:52.581496 systemd-networkd[1486]: cni0: Lost carrier Mar 12 02:58:52.601873 systemd-networkd[1486]: vethabd0ef3d: Link UP Mar 12 02:58:52.611166 kernel: cni0: port 1(vethabd0ef3d) entered blocking state Mar 12 02:58:52.611254 kernel: cni0: port 1(vethabd0ef3d) entered disabled state Mar 12 02:58:52.614954 kernel: vethabd0ef3d: entered allmulticast mode Mar 12 02:58:52.617978 kernel: vethabd0ef3d: entered promiscuous mode Mar 12 02:58:52.627020 kernel: cni0: port 1(vethabd0ef3d) entered blocking state Mar 12 02:58:52.627067 kernel: cni0: port 1(vethabd0ef3d) entered forwarding state Mar 12 02:58:52.626985 systemd-networkd[1486]: vethabd0ef3d: Gained carrier Mar 12 02:58:52.627607 systemd-networkd[1486]: cni0: Gained carrier Mar 12 02:58:52.629080 containerd[1877]: 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"} Mar 12 02:58:52.629080 containerd[1877]: delegateAdd: netconf sent to delegate plugin: Mar 12 02:58:52.674130 containerd[1877]: {"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="2026-03-12T02:58:52.674095355Z" level=info msg="connecting to shim da6e7f88a11236091e220f681e20f7592f644684f990105b4dec02e9a68eb2d8" address="unix:///run/containerd/s/10316d8534fc24d2579fa4f8ad0f2e1cc03c8c49970a430920fd5d1dca42d643" namespace=k8s.io protocol=ttrpc version=3 Mar 12 02:58:52.698104 systemd[1]: Started cri-containerd-da6e7f88a11236091e220f681e20f7592f644684f990105b4dec02e9a68eb2d8.scope - libcontainer container da6e7f88a11236091e220f681e20f7592f644684f990105b4dec02e9a68eb2d8. Mar 12 02:58:52.725547 containerd[1877]: time="2026-03-12T02:58:52.725511291Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-tmtbm,Uid:783c58f6-871d-4846-9158-c3d39cfd3786,Namespace:default,Attempt:0,} returns sandbox id \"da6e7f88a11236091e220f681e20f7592f644684f990105b4dec02e9a68eb2d8\"" Mar 12 02:58:52.726784 containerd[1877]: time="2026-03-12T02:58:52.726727640Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 12 02:58:53.528348 kubelet[2451]: E0312 02:58:53.528297 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:54.437080 systemd-networkd[1486]: vethabd0ef3d: Gained IPv6LL Mar 12 02:58:54.437338 systemd-networkd[1486]: cni0: Gained IPv6LL Mar 12 02:58:54.529003 kubelet[2451]: E0312 02:58:54.528929 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:54.878749 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2647880976.mount: Deactivated successfully. Mar 12 02:58:55.455504 containerd[1877]: time="2026-03-12T02:58:55.455455822Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:55.459778 containerd[1877]: time="2026-03-12T02:58:55.459747062Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=62467203" Mar 12 02:58:55.462554 containerd[1877]: time="2026-03-12T02:58:55.462515076Z" level=info msg="ImageCreate event name:\"sha256:6c31f5402a15481cf5cb9122f8fdb85d93d9ddb061235673ef14380e2b898c2f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:55.467066 containerd[1877]: time="2026-03-12T02:58:55.467022839Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:94d84a302e569aca6fb7eed139af2d59a3cba208311ad18b69a7d799472c2b22\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:58:55.467726 containerd[1877]: time="2026-03-12T02:58:55.467338612Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:6c31f5402a15481cf5cb9122f8fdb85d93d9ddb061235673ef14380e2b898c2f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:94d84a302e569aca6fb7eed139af2d59a3cba208311ad18b69a7d799472c2b22\", size \"62467081\" in 2.740584517s" Mar 12 02:58:55.467726 containerd[1877]: time="2026-03-12T02:58:55.467364722Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:6c31f5402a15481cf5cb9122f8fdb85d93d9ddb061235673ef14380e2b898c2f\"" Mar 12 02:58:55.475538 containerd[1877]: time="2026-03-12T02:58:55.475518285Z" level=info msg="CreateContainer within sandbox \"da6e7f88a11236091e220f681e20f7592f644684f990105b4dec02e9a68eb2d8\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Mar 12 02:58:55.494057 containerd[1877]: time="2026-03-12T02:58:55.494030603Z" level=info msg="Container a3205ece47ca0d9fe0c2a05d1e75e0bd184020e5026670043524c0118438eeb8: CDI devices from CRI Config.CDIDevices: []" Mar 12 02:58:55.505969 containerd[1877]: time="2026-03-12T02:58:55.505929240Z" level=info msg="CreateContainer within sandbox \"da6e7f88a11236091e220f681e20f7592f644684f990105b4dec02e9a68eb2d8\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"a3205ece47ca0d9fe0c2a05d1e75e0bd184020e5026670043524c0118438eeb8\"" Mar 12 02:58:55.506581 containerd[1877]: time="2026-03-12T02:58:55.506562985Z" level=info msg="StartContainer for \"a3205ece47ca0d9fe0c2a05d1e75e0bd184020e5026670043524c0118438eeb8\"" Mar 12 02:58:55.507393 containerd[1877]: time="2026-03-12T02:58:55.507340713Z" level=info msg="connecting to shim a3205ece47ca0d9fe0c2a05d1e75e0bd184020e5026670043524c0118438eeb8" address="unix:///run/containerd/s/10316d8534fc24d2579fa4f8ad0f2e1cc03c8c49970a430920fd5d1dca42d643" protocol=ttrpc version=3 Mar 12 02:58:55.522076 systemd[1]: Started cri-containerd-a3205ece47ca0d9fe0c2a05d1e75e0bd184020e5026670043524c0118438eeb8.scope - libcontainer container a3205ece47ca0d9fe0c2a05d1e75e0bd184020e5026670043524c0118438eeb8. Mar 12 02:58:55.529373 kubelet[2451]: E0312 02:58:55.529352 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:55.544424 containerd[1877]: time="2026-03-12T02:58:55.544343840Z" level=info msg="StartContainer for \"a3205ece47ca0d9fe0c2a05d1e75e0bd184020e5026670043524c0118438eeb8\" returns successfully" Mar 12 02:58:55.628836 kubelet[2451]: I0312 02:58:55.628758 2451 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nginx-deployment-6cc69d4fc7-tmtbm" podStartSLOduration=0.886778548 podStartE2EDuration="3.628747108s" podCreationTimestamp="2026-03-12 02:58:52 +0000 UTC" firstStartedPulling="2026-03-12 02:58:52.726479192 +0000 UTC m=+14.732404105" lastFinishedPulling="2026-03-12 02:58:55.46844776 +0000 UTC m=+17.474372665" observedRunningTime="2026-03-12 02:58:55.628696224 +0000 UTC m=+17.634621137" watchObservedRunningTime="2026-03-12 02:58:55.628747108 +0000 UTC m=+17.634672013" Mar 12 02:58:56.530883 kubelet[2451]: E0312 02:58:56.530843 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:57.531237 kubelet[2451]: E0312 02:58:57.531193 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:58.517459 kubelet[2451]: E0312 02:58:58.517416 2451 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:58.531757 kubelet[2451]: E0312 02:58:58.531728 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:58:59.532227 kubelet[2451]: E0312 02:58:59.532176 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:00.532322 kubelet[2451]: E0312 02:59:00.532279 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:00.909213 systemd[1]: Created slice kubepods-besteffort-podb4b682ee_bcef_472a_a974_47f6ca0b61ff.slice - libcontainer container kubepods-besteffort-podb4b682ee_bcef_472a_a974_47f6ca0b61ff.slice. Mar 12 02:59:01.062242 kubelet[2451]: I0312 02:59:01.062195 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dfvv9\" (UniqueName: \"kubernetes.io/projected/b4b682ee-bcef-472a-a974-47f6ca0b61ff-kube-api-access-dfvv9\") pod \"nfs-server-provisioner-0\" (UID: \"b4b682ee-bcef-472a-a974-47f6ca0b61ff\") " pod="default/nfs-server-provisioner-0" Mar 12 02:59:01.062242 kubelet[2451]: I0312 02:59:01.062240 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/b4b682ee-bcef-472a-a974-47f6ca0b61ff-data\") pod \"nfs-server-provisioner-0\" (UID: \"b4b682ee-bcef-472a-a974-47f6ca0b61ff\") " pod="default/nfs-server-provisioner-0" Mar 12 02:59:01.216298 containerd[1877]: time="2026-03-12T02:59:01.216141647Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b4b682ee-bcef-472a-a974-47f6ca0b61ff,Namespace:default,Attempt:0,}" Mar 12 02:59:01.241902 systemd-networkd[1486]: vethc02a6ac2: Link UP Mar 12 02:59:01.252004 kernel: cni0: port 2(vethc02a6ac2) entered blocking state Mar 12 02:59:01.252076 kernel: cni0: port 2(vethc02a6ac2) entered disabled state Mar 12 02:59:01.254984 kernel: vethc02a6ac2: entered allmulticast mode Mar 12 02:59:01.257809 kernel: vethc02a6ac2: entered promiscuous mode Mar 12 02:59:01.268086 kernel: cni0: port 2(vethc02a6ac2) entered blocking state Mar 12 02:59:01.268197 kernel: cni0: port 2(vethc02a6ac2) entered forwarding state Mar 12 02:59:01.268285 systemd-networkd[1486]: vethc02a6ac2: Gained carrier Mar 12 02:59:01.270124 containerd[1877]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000084950), "name":"cbr0", "type":"bridge"} Mar 12 02:59:01.270124 containerd[1877]: delegateAdd: netconf sent to delegate plugin: Mar 12 02:59:01.312390 containerd[1877]: {"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="2026-03-12T02:59:01.312352546Z" level=info msg="connecting to shim 239f4278fb3d0136f2ea15a1f5bd5f147839e64f1409a1ff472cfaff94137512" address="unix:///run/containerd/s/15558b5fcc3b98ab59c5ee4a8d7028eae18d4718a339d247628f497a710e1aac" namespace=k8s.io protocol=ttrpc version=3 Mar 12 02:59:01.329089 systemd[1]: Started cri-containerd-239f4278fb3d0136f2ea15a1f5bd5f147839e64f1409a1ff472cfaff94137512.scope - libcontainer container 239f4278fb3d0136f2ea15a1f5bd5f147839e64f1409a1ff472cfaff94137512. Mar 12 02:59:01.357652 containerd[1877]: time="2026-03-12T02:59:01.357615738Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b4b682ee-bcef-472a-a974-47f6ca0b61ff,Namespace:default,Attempt:0,} returns sandbox id \"239f4278fb3d0136f2ea15a1f5bd5f147839e64f1409a1ff472cfaff94137512\"" Mar 12 02:59:01.359407 containerd[1877]: time="2026-03-12T02:59:01.359378359Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Mar 12 02:59:01.532684 kubelet[2451]: E0312 02:59:01.532635 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:02.373094 systemd-networkd[1486]: vethc02a6ac2: Gained IPv6LL Mar 12 02:59:02.532899 kubelet[2451]: E0312 02:59:02.532855 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:03.279331 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount696495367.mount: Deactivated successfully. Mar 12 02:59:03.533824 kubelet[2451]: E0312 02:59:03.533619 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:04.534910 kubelet[2451]: E0312 02:59:04.534870 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:04.718986 containerd[1877]: time="2026-03-12T02:59:04.718912894Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:59:04.722891 containerd[1877]: time="2026-03-12T02:59:04.722858910Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Mar 12 02:59:04.726381 containerd[1877]: time="2026-03-12T02:59:04.726345673Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:59:04.730457 containerd[1877]: time="2026-03-12T02:59:04.730420544Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:59:04.731571 containerd[1877]: time="2026-03-12T02:59:04.731470648Z" 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.372063516s" Mar 12 02:59:04.731571 containerd[1877]: time="2026-03-12T02:59:04.731498551Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Mar 12 02:59:04.737978 containerd[1877]: time="2026-03-12T02:59:04.737849187Z" level=info msg="CreateContainer within sandbox \"239f4278fb3d0136f2ea15a1f5bd5f147839e64f1409a1ff472cfaff94137512\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Mar 12 02:59:04.754409 containerd[1877]: time="2026-03-12T02:59:04.753793007Z" level=info msg="Container ecda28e657bdbc7f65c2fb4ce3363bd0ada80298ade5b6f1b7cfe7954e7d3cba: CDI devices from CRI Config.CDIDevices: []" Mar 12 02:59:04.757921 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2173615349.mount: Deactivated successfully. Mar 12 02:59:04.769080 containerd[1877]: time="2026-03-12T02:59:04.769047660Z" level=info msg="CreateContainer within sandbox \"239f4278fb3d0136f2ea15a1f5bd5f147839e64f1409a1ff472cfaff94137512\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ecda28e657bdbc7f65c2fb4ce3363bd0ada80298ade5b6f1b7cfe7954e7d3cba\"" Mar 12 02:59:04.769710 containerd[1877]: time="2026-03-12T02:59:04.769684501Z" level=info msg="StartContainer for \"ecda28e657bdbc7f65c2fb4ce3363bd0ada80298ade5b6f1b7cfe7954e7d3cba\"" Mar 12 02:59:04.770422 containerd[1877]: time="2026-03-12T02:59:04.770399434Z" level=info msg="connecting to shim ecda28e657bdbc7f65c2fb4ce3363bd0ada80298ade5b6f1b7cfe7954e7d3cba" address="unix:///run/containerd/s/15558b5fcc3b98ab59c5ee4a8d7028eae18d4718a339d247628f497a710e1aac" protocol=ttrpc version=3 Mar 12 02:59:04.789094 systemd[1]: Started cri-containerd-ecda28e657bdbc7f65c2fb4ce3363bd0ada80298ade5b6f1b7cfe7954e7d3cba.scope - libcontainer container ecda28e657bdbc7f65c2fb4ce3363bd0ada80298ade5b6f1b7cfe7954e7d3cba. Mar 12 02:59:04.817253 containerd[1877]: time="2026-03-12T02:59:04.817215234Z" level=info msg="StartContainer for \"ecda28e657bdbc7f65c2fb4ce3363bd0ada80298ade5b6f1b7cfe7954e7d3cba\" returns successfully" Mar 12 02:59:05.535857 kubelet[2451]: E0312 02:59:05.535807 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:06.536181 kubelet[2451]: E0312 02:59:06.536127 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:07.536689 kubelet[2451]: E0312 02:59:07.536625 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:08.536888 kubelet[2451]: E0312 02:59:08.536743 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:09.537975 kubelet[2451]: E0312 02:59:09.537899 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:10.031475 kubelet[2451]: I0312 02:59:10.031423 2451 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.658103535 podStartE2EDuration="10.031409365s" podCreationTimestamp="2026-03-12 02:59:00 +0000 UTC" firstStartedPulling="2026-03-12 02:59:01.359026158 +0000 UTC m=+23.364951063" lastFinishedPulling="2026-03-12 02:59:04.732331988 +0000 UTC m=+26.738256893" observedRunningTime="2026-03-12 02:59:05.64810814 +0000 UTC m=+27.654033053" watchObservedRunningTime="2026-03-12 02:59:10.031409365 +0000 UTC m=+32.037334278" Mar 12 02:59:10.040553 systemd[1]: Created slice kubepods-besteffort-pod396c11aa_8ec6_4c38_89bc_3a35797c5c1a.slice - libcontainer container kubepods-besteffort-pod396c11aa_8ec6_4c38_89bc_3a35797c5c1a.slice. Mar 12 02:59:10.207853 kubelet[2451]: I0312 02:59:10.207805 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-eaa11132-356d-466c-9947-89b27cce9d48\" (UniqueName: \"kubernetes.io/nfs/396c11aa-8ec6-4c38-89bc-3a35797c5c1a-pvc-eaa11132-356d-466c-9947-89b27cce9d48\") pod \"test-pod-1\" (UID: \"396c11aa-8ec6-4c38-89bc-3a35797c5c1a\") " pod="default/test-pod-1" Mar 12 02:59:10.207853 kubelet[2451]: I0312 02:59:10.207848 2451 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fscgr\" (UniqueName: \"kubernetes.io/projected/396c11aa-8ec6-4c38-89bc-3a35797c5c1a-kube-api-access-fscgr\") pod \"test-pod-1\" (UID: \"396c11aa-8ec6-4c38-89bc-3a35797c5c1a\") " pod="default/test-pod-1" Mar 12 02:59:10.391985 kernel: netfs: FS-Cache loaded Mar 12 02:59:10.448405 kernel: RPC: Registered named UNIX socket transport module. Mar 12 02:59:10.448516 kernel: RPC: Registered udp transport module. Mar 12 02:59:10.448533 kernel: RPC: Registered tcp transport module. Mar 12 02:59:10.454210 kernel: RPC: Registered tcp-with-tls transport module. Mar 12 02:59:10.454251 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 12 02:59:10.538027 kubelet[2451]: E0312 02:59:10.537980 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:10.661324 kernel: NFS: Registering the id_resolver key type Mar 12 02:59:10.661471 kernel: Key type id_resolver registered Mar 12 02:59:10.661487 kernel: Key type id_legacy registered Mar 12 02:59:10.781674 nfsidmap[3591]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.4-n-97f68a91ee' Mar 12 02:59:10.795281 nfsidmap[3592]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.4-n-97f68a91ee' Mar 12 02:59:10.802178 nfsrahead[3594]: setting /var/lib/kubelet/pods/396c11aa-8ec6-4c38-89bc-3a35797c5c1a/volumes/kubernetes.io~nfs/pvc-eaa11132-356d-466c-9947-89b27cce9d48 readahead to 128 Mar 12 02:59:10.947181 containerd[1877]: time="2026-03-12T02:59:10.947135440Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:396c11aa-8ec6-4c38-89bc-3a35797c5c1a,Namespace:default,Attempt:0,}" Mar 12 02:59:10.965653 systemd-networkd[1486]: vethaf098e15: Link UP Mar 12 02:59:10.974809 kernel: cni0: port 3(vethaf098e15) entered blocking state Mar 12 02:59:10.974897 kernel: cni0: port 3(vethaf098e15) entered disabled state Mar 12 02:59:10.978979 kernel: vethaf098e15: entered allmulticast mode Mar 12 02:59:10.983152 kernel: vethaf098e15: entered promiscuous mode Mar 12 02:59:10.994148 kernel: cni0: port 3(vethaf098e15) entered blocking state Mar 12 02:59:10.994215 kernel: cni0: port 3(vethaf098e15) entered forwarding state Mar 12 02:59:10.994424 systemd-networkd[1486]: vethaf098e15: Gained carrier Mar 12 02:59:10.996154 containerd[1877]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000084950), "name":"cbr0", "type":"bridge"} Mar 12 02:59:10.996154 containerd[1877]: delegateAdd: netconf sent to delegate plugin: Mar 12 02:59:11.030250 containerd[1877]: {"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="2026-03-12T02:59:11.030218731Z" level=info msg="connecting to shim 963d942879d7e2e44d0265b0a3c288383c92a0494cff5ae9dfc87ff87477de94" address="unix:///run/containerd/s/13d787e29bb315433da95c229e7a8fabc362f15a664564ef31dc8bf22a6308b4" namespace=k8s.io protocol=ttrpc version=3 Mar 12 02:59:11.052105 systemd[1]: Started cri-containerd-963d942879d7e2e44d0265b0a3c288383c92a0494cff5ae9dfc87ff87477de94.scope - libcontainer container 963d942879d7e2e44d0265b0a3c288383c92a0494cff5ae9dfc87ff87477de94. Mar 12 02:59:11.080799 containerd[1877]: time="2026-03-12T02:59:11.080756401Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:396c11aa-8ec6-4c38-89bc-3a35797c5c1a,Namespace:default,Attempt:0,} returns sandbox id \"963d942879d7e2e44d0265b0a3c288383c92a0494cff5ae9dfc87ff87477de94\"" Mar 12 02:59:11.082069 containerd[1877]: time="2026-03-12T02:59:11.081917370Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 12 02:59:11.538993 kubelet[2451]: E0312 02:59:11.538937 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:12.310130 containerd[1877]: time="2026-03-12T02:59:12.310076454Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 12 02:59:12.313456 containerd[1877]: time="2026-03-12T02:59:12.313179324Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Mar 12 02:59:12.314758 containerd[1877]: time="2026-03-12T02:59:12.314652284Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:6c31f5402a15481cf5cb9122f8fdb85d93d9ddb061235673ef14380e2b898c2f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:94d84a302e569aca6fb7eed139af2d59a3cba208311ad18b69a7d799472c2b22\", size \"62467081\" in 1.232700236s" Mar 12 02:59:12.314758 containerd[1877]: time="2026-03-12T02:59:12.314678403Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:6c31f5402a15481cf5cb9122f8fdb85d93d9ddb061235673ef14380e2b898c2f\"" Mar 12 02:59:12.322583 containerd[1877]: time="2026-03-12T02:59:12.322211969Z" level=info msg="CreateContainer within sandbox \"963d942879d7e2e44d0265b0a3c288383c92a0494cff5ae9dfc87ff87477de94\" for container &ContainerMetadata{Name:test,Attempt:0,}" Mar 12 02:59:12.342994 containerd[1877]: time="2026-03-12T02:59:12.342493313Z" level=info msg="Container f1c23f6a67333e387e8cbdc26f95689af1fdb01c97ff8b5a2aa3cb0cd23fc7b9: CDI devices from CRI Config.CDIDevices: []" Mar 12 02:59:12.358302 containerd[1877]: time="2026-03-12T02:59:12.358264647Z" level=info msg="CreateContainer within sandbox \"963d942879d7e2e44d0265b0a3c288383c92a0494cff5ae9dfc87ff87477de94\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"f1c23f6a67333e387e8cbdc26f95689af1fdb01c97ff8b5a2aa3cb0cd23fc7b9\"" Mar 12 02:59:12.358928 containerd[1877]: time="2026-03-12T02:59:12.358878903Z" level=info msg="StartContainer for \"f1c23f6a67333e387e8cbdc26f95689af1fdb01c97ff8b5a2aa3cb0cd23fc7b9\"" Mar 12 02:59:12.359661 containerd[1877]: time="2026-03-12T02:59:12.359631591Z" level=info msg="connecting to shim f1c23f6a67333e387e8cbdc26f95689af1fdb01c97ff8b5a2aa3cb0cd23fc7b9" address="unix:///run/containerd/s/13d787e29bb315433da95c229e7a8fabc362f15a664564ef31dc8bf22a6308b4" protocol=ttrpc version=3 Mar 12 02:59:12.374080 systemd[1]: Started cri-containerd-f1c23f6a67333e387e8cbdc26f95689af1fdb01c97ff8b5a2aa3cb0cd23fc7b9.scope - libcontainer container f1c23f6a67333e387e8cbdc26f95689af1fdb01c97ff8b5a2aa3cb0cd23fc7b9. Mar 12 02:59:12.397849 containerd[1877]: time="2026-03-12T02:59:12.397812732Z" level=info msg="StartContainer for \"f1c23f6a67333e387e8cbdc26f95689af1fdb01c97ff8b5a2aa3cb0cd23fc7b9\" returns successfully" Mar 12 02:59:12.539157 kubelet[2451]: E0312 02:59:12.539022 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:12.677097 systemd-networkd[1486]: vethaf098e15: Gained IPv6LL Mar 12 02:59:13.539794 kubelet[2451]: E0312 02:59:13.539739 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:14.540267 kubelet[2451]: E0312 02:59:14.540200 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:15.542890 kubelet[2451]: E0312 02:59:15.542833 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 12 02:59:16.544632 kubelet[2451]: E0312 02:59:16.544571 2451 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"