Nov 6 23:17:01.206052 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 6 23:17:01.206075 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT Thu Nov 6 21:59:06 -00 2025 Nov 6 23:17:01.206083 kernel: KASLR enabled Nov 6 23:17:01.206089 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Nov 6 23:17:01.206096 kernel: printk: bootconsole [pl11] enabled Nov 6 23:17:01.206101 kernel: efi: EFI v2.7 by EDK II Nov 6 23:17:01.206108 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f20e698 RNG=0x3fd5f998 MEMRESERVE=0x3e477598 Nov 6 23:17:01.206114 kernel: random: crng init done Nov 6 23:17:01.206120 kernel: secureboot: Secure boot disabled Nov 6 23:17:01.206126 kernel: ACPI: Early table checksum verification disabled Nov 6 23:17:01.206132 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) Nov 6 23:17:01.206138 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206144 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206151 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) Nov 6 23:17:01.206159 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206165 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206171 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206179 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206185 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206191 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206197 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Nov 6 23:17:01.206203 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:17:01.206210 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Nov 6 23:17:01.206216 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] Nov 6 23:17:01.206222 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] Nov 6 23:17:01.206228 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] Nov 6 23:17:01.206234 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] Nov 6 23:17:01.206240 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] Nov 6 23:17:01.206248 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] Nov 6 23:17:01.206254 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] Nov 6 23:17:01.206260 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] Nov 6 23:17:01.206266 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] Nov 6 23:17:01.206272 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] Nov 6 23:17:01.206279 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] Nov 6 23:17:01.206285 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] Nov 6 23:17:01.206291 kernel: NUMA: NODE_DATA [mem 0x1bf7ef800-0x1bf7f4fff] Nov 6 23:17:01.208338 kernel: Zone ranges: Nov 6 23:17:01.208351 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Nov 6 23:17:01.208357 kernel: DMA32 empty Nov 6 23:17:01.208364 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Nov 6 23:17:01.208378 kernel: Movable zone start for each node Nov 6 23:17:01.208384 kernel: Early memory node ranges Nov 6 23:17:01.208391 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Nov 6 23:17:01.208398 kernel: node 0: [mem 0x0000000000824000-0x000000003e45ffff] Nov 6 23:17:01.208404 kernel: node 0: [mem 0x000000003e460000-0x000000003e46ffff] Nov 6 23:17:01.208412 kernel: node 0: [mem 0x000000003e470000-0x000000003e54ffff] Nov 6 23:17:01.208419 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] Nov 6 23:17:01.208426 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] Nov 6 23:17:01.208432 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] Nov 6 23:17:01.208439 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] Nov 6 23:17:01.208445 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Nov 6 23:17:01.208452 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Nov 6 23:17:01.208459 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Nov 6 23:17:01.208465 kernel: psci: probing for conduit method from ACPI. Nov 6 23:17:01.208472 kernel: psci: PSCIv1.1 detected in firmware. Nov 6 23:17:01.208478 kernel: psci: Using standard PSCI v0.2 function IDs Nov 6 23:17:01.208485 kernel: psci: MIGRATE_INFO_TYPE not supported. Nov 6 23:17:01.208493 kernel: psci: SMC Calling Convention v1.4 Nov 6 23:17:01.208499 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Nov 6 23:17:01.208506 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Nov 6 23:17:01.208512 kernel: percpu: Embedded 31 pages/cpu s86120 r8192 d32664 u126976 Nov 6 23:17:01.208519 kernel: pcpu-alloc: s86120 r8192 d32664 u126976 alloc=31*4096 Nov 6 23:17:01.208526 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 6 23:17:01.208533 kernel: Detected PIPT I-cache on CPU0 Nov 6 23:17:01.208539 kernel: CPU features: detected: GIC system register CPU interface Nov 6 23:17:01.208546 kernel: CPU features: detected: Hardware dirty bit management Nov 6 23:17:01.208552 kernel: CPU features: detected: Spectre-BHB Nov 6 23:17:01.208559 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 6 23:17:01.208568 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 6 23:17:01.208575 kernel: CPU features: detected: ARM erratum 1418040 Nov 6 23:17:01.208582 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Nov 6 23:17:01.208589 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 6 23:17:01.208595 kernel: alternatives: applying boot alternatives Nov 6 23:17:01.208603 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=463065366e5b9a391e66d180eedbf8fe1b0462c2e722921ef25580943d9b67c6 Nov 6 23:17:01.208610 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 6 23:17:01.208617 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 6 23:17:01.208623 kernel: Fallback order for Node 0: 0 Nov 6 23:17:01.208630 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Nov 6 23:17:01.208637 kernel: Policy zone: Normal Nov 6 23:17:01.208645 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 6 23:17:01.208651 kernel: software IO TLB: area num 2. Nov 6 23:17:01.208658 kernel: software IO TLB: mapped [mem 0x0000000036530000-0x000000003a530000] (64MB) Nov 6 23:17:01.208665 kernel: Memory: 3983528K/4194160K available (10368K kernel code, 2180K rwdata, 8104K rodata, 38400K init, 897K bss, 210632K reserved, 0K cma-reserved) Nov 6 23:17:01.208672 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 6 23:17:01.208678 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 6 23:17:01.208686 kernel: rcu: RCU event tracing is enabled. Nov 6 23:17:01.208693 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 6 23:17:01.208700 kernel: Trampoline variant of Tasks RCU enabled. Nov 6 23:17:01.208706 kernel: Tracing variant of Tasks RCU enabled. Nov 6 23:17:01.208713 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 6 23:17:01.208721 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 6 23:17:01.208728 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 6 23:17:01.208734 kernel: GICv3: 960 SPIs implemented Nov 6 23:17:01.208741 kernel: GICv3: 0 Extended SPIs implemented Nov 6 23:17:01.208747 kernel: Root IRQ handler: gic_handle_irq Nov 6 23:17:01.208754 kernel: GICv3: GICv3 features: 16 PPIs, RSS Nov 6 23:17:01.208761 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Nov 6 23:17:01.208767 kernel: ITS: No ITS available, not enabling LPIs Nov 6 23:17:01.208774 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 6 23:17:01.208781 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 6 23:17:01.208788 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 6 23:17:01.208794 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 6 23:17:01.208803 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 6 23:17:01.208809 kernel: Console: colour dummy device 80x25 Nov 6 23:17:01.208816 kernel: printk: console [tty1] enabled Nov 6 23:17:01.208823 kernel: ACPI: Core revision 20230628 Nov 6 23:17:01.208830 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 6 23:17:01.208837 kernel: pid_max: default: 32768 minimum: 301 Nov 6 23:17:01.208844 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 6 23:17:01.208851 kernel: landlock: Up and running. Nov 6 23:17:01.208858 kernel: SELinux: Initializing. Nov 6 23:17:01.208866 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 6 23:17:01.208873 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 6 23:17:01.208879 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 6 23:17:01.208886 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 6 23:17:01.208893 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0x100000e, misc 0x31e1 Nov 6 23:17:01.208900 kernel: Hyper-V: Host Build 10.0.26100.1382-1-0 Nov 6 23:17:01.208907 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 6 23:17:01.208920 kernel: rcu: Hierarchical SRCU implementation. Nov 6 23:17:01.208927 kernel: rcu: Max phase no-delay instances is 400. Nov 6 23:17:01.208934 kernel: Remapping and enabling EFI services. Nov 6 23:17:01.208942 kernel: smp: Bringing up secondary CPUs ... Nov 6 23:17:01.208949 kernel: Detected PIPT I-cache on CPU1 Nov 6 23:17:01.208958 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Nov 6 23:17:01.208965 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 6 23:17:01.208972 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 6 23:17:01.208979 kernel: smp: Brought up 1 node, 2 CPUs Nov 6 23:17:01.208986 kernel: SMP: Total of 2 processors activated. Nov 6 23:17:01.208995 kernel: CPU features: detected: 32-bit EL0 Support Nov 6 23:17:01.209002 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Nov 6 23:17:01.209009 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 6 23:17:01.209016 kernel: CPU features: detected: CRC32 instructions Nov 6 23:17:01.209023 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 6 23:17:01.209031 kernel: CPU features: detected: LSE atomic instructions Nov 6 23:17:01.209038 kernel: CPU features: detected: Privileged Access Never Nov 6 23:17:01.209045 kernel: CPU: All CPU(s) started at EL1 Nov 6 23:17:01.209052 kernel: alternatives: applying system-wide alternatives Nov 6 23:17:01.209061 kernel: devtmpfs: initialized Nov 6 23:17:01.209068 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 6 23:17:01.209075 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 6 23:17:01.209083 kernel: pinctrl core: initialized pinctrl subsystem Nov 6 23:17:01.209090 kernel: SMBIOS 3.1.0 present. Nov 6 23:17:01.209097 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 Nov 6 23:17:01.209104 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 6 23:17:01.209111 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 6 23:17:01.209119 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 6 23:17:01.209127 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 6 23:17:01.209134 kernel: audit: initializing netlink subsys (disabled) Nov 6 23:17:01.209142 kernel: audit: type=2000 audit(0.047:1): state=initialized audit_enabled=0 res=1 Nov 6 23:17:01.209149 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 6 23:17:01.209156 kernel: cpuidle: using governor menu Nov 6 23:17:01.209163 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 6 23:17:01.209170 kernel: ASID allocator initialised with 32768 entries Nov 6 23:17:01.209178 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 6 23:17:01.209188 kernel: Serial: AMBA PL011 UART driver Nov 6 23:17:01.209197 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 6 23:17:01.209204 kernel: Modules: 0 pages in range for non-PLT usage Nov 6 23:17:01.209211 kernel: Modules: 509248 pages in range for PLT usage Nov 6 23:17:01.209218 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 6 23:17:01.209225 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 6 23:17:01.209232 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 6 23:17:01.209240 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 6 23:17:01.209247 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 6 23:17:01.209254 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 6 23:17:01.209262 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 6 23:17:01.209270 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 6 23:17:01.209277 kernel: ACPI: Added _OSI(Module Device) Nov 6 23:17:01.209284 kernel: ACPI: Added _OSI(Processor Device) Nov 6 23:17:01.209291 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 6 23:17:01.209326 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 6 23:17:01.209334 kernel: ACPI: Interpreter enabled Nov 6 23:17:01.209341 kernel: ACPI: Using GIC for interrupt routing Nov 6 23:17:01.209348 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Nov 6 23:17:01.209357 kernel: printk: console [ttyAMA0] enabled Nov 6 23:17:01.209364 kernel: printk: bootconsole [pl11] disabled Nov 6 23:17:01.209371 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Nov 6 23:17:01.209378 kernel: iommu: Default domain type: Translated Nov 6 23:17:01.209386 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 6 23:17:01.209393 kernel: efivars: Registered efivars operations Nov 6 23:17:01.209400 kernel: vgaarb: loaded Nov 6 23:17:01.209407 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 6 23:17:01.209414 kernel: VFS: Disk quotas dquot_6.6.0 Nov 6 23:17:01.209423 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 6 23:17:01.209430 kernel: pnp: PnP ACPI init Nov 6 23:17:01.209437 kernel: pnp: PnP ACPI: found 0 devices Nov 6 23:17:01.209444 kernel: NET: Registered PF_INET protocol family Nov 6 23:17:01.209452 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 6 23:17:01.209459 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 6 23:17:01.209467 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 6 23:17:01.209474 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 6 23:17:01.209482 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 6 23:17:01.209491 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 6 23:17:01.209498 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 6 23:17:01.209506 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 6 23:17:01.209513 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 6 23:17:01.209520 kernel: PCI: CLS 0 bytes, default 64 Nov 6 23:17:01.209527 kernel: kvm [1]: HYP mode not available Nov 6 23:17:01.209534 kernel: Initialise system trusted keyrings Nov 6 23:17:01.209541 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 6 23:17:01.209548 kernel: Key type asymmetric registered Nov 6 23:17:01.209557 kernel: Asymmetric key parser 'x509' registered Nov 6 23:17:01.209564 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 6 23:17:01.209571 kernel: io scheduler mq-deadline registered Nov 6 23:17:01.209578 kernel: io scheduler kyber registered Nov 6 23:17:01.209585 kernel: io scheduler bfq registered Nov 6 23:17:01.209592 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 6 23:17:01.209599 kernel: thunder_xcv, ver 1.0 Nov 6 23:17:01.209606 kernel: thunder_bgx, ver 1.0 Nov 6 23:17:01.209613 kernel: nicpf, ver 1.0 Nov 6 23:17:01.209622 kernel: nicvf, ver 1.0 Nov 6 23:17:01.209760 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 6 23:17:01.209829 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-06T23:17:00 UTC (1762471020) Nov 6 23:17:01.209839 kernel: efifb: probing for efifb Nov 6 23:17:01.209846 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 6 23:17:01.209854 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 6 23:17:01.209861 kernel: efifb: scrolling: redraw Nov 6 23:17:01.209868 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 6 23:17:01.209878 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 23:17:01.209886 kernel: fb0: EFI VGA frame buffer device Nov 6 23:17:01.209893 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Nov 6 23:17:01.209900 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 6 23:17:01.209907 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 6 counters available Nov 6 23:17:01.209914 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 6 23:17:01.209921 kernel: watchdog: Hard watchdog permanently disabled Nov 6 23:17:01.209928 kernel: NET: Registered PF_INET6 protocol family Nov 6 23:17:01.209935 kernel: Segment Routing with IPv6 Nov 6 23:17:01.209944 kernel: In-situ OAM (IOAM) with IPv6 Nov 6 23:17:01.209951 kernel: NET: Registered PF_PACKET protocol family Nov 6 23:17:01.209958 kernel: Key type dns_resolver registered Nov 6 23:17:01.209965 kernel: registered taskstats version 1 Nov 6 23:17:01.209972 kernel: Loading compiled-in X.509 certificates Nov 6 23:17:01.209979 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: e53d3b094875ce4245a8b2684246260baeee1996' Nov 6 23:17:01.209986 kernel: Key type .fscrypt registered Nov 6 23:17:01.209993 kernel: Key type fscrypt-provisioning registered Nov 6 23:17:01.210000 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 6 23:17:01.210009 kernel: ima: Allocated hash algorithm: sha1 Nov 6 23:17:01.210016 kernel: ima: No architecture policies found Nov 6 23:17:01.210023 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 6 23:17:01.210030 kernel: clk: Disabling unused clocks Nov 6 23:17:01.210037 kernel: Freeing unused kernel memory: 38400K Nov 6 23:17:01.210044 kernel: Run /init as init process Nov 6 23:17:01.210052 kernel: with arguments: Nov 6 23:17:01.210059 kernel: /init Nov 6 23:17:01.210065 kernel: with environment: Nov 6 23:17:01.210074 kernel: HOME=/ Nov 6 23:17:01.210081 kernel: TERM=linux Nov 6 23:17:01.210089 systemd[1]: Successfully made /usr/ read-only. Nov 6 23:17:01.210099 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 23:17:01.210107 systemd[1]: Detected virtualization microsoft. Nov 6 23:17:01.210115 systemd[1]: Detected architecture arm64. Nov 6 23:17:01.210122 systemd[1]: Running in initrd. Nov 6 23:17:01.210130 systemd[1]: No hostname configured, using default hostname. Nov 6 23:17:01.210139 systemd[1]: Hostname set to . Nov 6 23:17:01.210146 systemd[1]: Initializing machine ID from random generator. Nov 6 23:17:01.210154 systemd[1]: Queued start job for default target initrd.target. Nov 6 23:17:01.210162 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:17:01.210170 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:17:01.210178 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 6 23:17:01.210186 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 23:17:01.210193 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 6 23:17:01.210204 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 6 23:17:01.210212 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 6 23:17:01.210220 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 6 23:17:01.210228 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:17:01.210236 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:17:01.210244 systemd[1]: Reached target paths.target - Path Units. Nov 6 23:17:01.210253 systemd[1]: Reached target slices.target - Slice Units. Nov 6 23:17:01.210260 systemd[1]: Reached target swap.target - Swaps. Nov 6 23:17:01.210268 systemd[1]: Reached target timers.target - Timer Units. Nov 6 23:17:01.210276 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 23:17:01.210283 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 23:17:01.210291 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 6 23:17:01.212863 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 6 23:17:01.212873 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:17:01.212881 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 23:17:01.212893 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:17:01.212901 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 23:17:01.212909 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 6 23:17:01.212917 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 23:17:01.212925 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 6 23:17:01.212932 systemd[1]: Starting systemd-fsck-usr.service... Nov 6 23:17:01.212940 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 23:17:01.212947 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 23:17:01.212982 systemd-journald[218]: Collecting audit messages is disabled. Nov 6 23:17:01.213004 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:17:01.213013 systemd-journald[218]: Journal started Nov 6 23:17:01.213033 systemd-journald[218]: Runtime Journal (/run/log/journal/cd60a8a3715048ad99b2580607774c14) is 8M, max 78.5M, 70.5M free. Nov 6 23:17:01.213664 systemd-modules-load[220]: Inserted module 'overlay' Nov 6 23:17:01.230967 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 23:17:01.232321 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 6 23:17:01.254775 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 6 23:17:01.254799 kernel: Bridge firewalling registered Nov 6 23:17:01.250111 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:17:01.254038 systemd-modules-load[220]: Inserted module 'br_netfilter' Nov 6 23:17:01.260531 systemd[1]: Finished systemd-fsck-usr.service. Nov 6 23:17:01.269390 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 23:17:01.278140 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:17:01.298531 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:17:01.310144 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 23:17:01.315462 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 23:17:01.341017 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 23:17:01.356366 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:17:01.370315 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:17:01.375534 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 23:17:01.385273 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:17:01.406512 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 6 23:17:01.417755 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 23:17:01.429001 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 23:17:01.442358 dracut-cmdline[252]: dracut-dracut-053 Nov 6 23:17:01.442358 dracut-cmdline[252]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=463065366e5b9a391e66d180eedbf8fe1b0462c2e722921ef25580943d9b67c6 Nov 6 23:17:01.452644 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:17:01.511258 systemd-resolved[259]: Positive Trust Anchors: Nov 6 23:17:01.511274 systemd-resolved[259]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 23:17:01.511647 systemd-resolved[259]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 6 23:17:01.513995 systemd-resolved[259]: Defaulting to hostname 'linux'. Nov 6 23:17:01.515421 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 23:17:01.528271 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:17:01.585326 kernel: SCSI subsystem initialized Nov 6 23:17:01.593317 kernel: Loading iSCSI transport class v2.0-870. Nov 6 23:17:01.605317 kernel: iscsi: registered transport (tcp) Nov 6 23:17:01.620506 kernel: iscsi: registered transport (qla4xxx) Nov 6 23:17:01.620548 kernel: QLogic iSCSI HBA Driver Nov 6 23:17:01.659835 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 6 23:17:01.671785 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 6 23:17:01.699824 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 6 23:17:01.699855 kernel: device-mapper: uevent: version 1.0.3 Nov 6 23:17:01.705226 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 6 23:17:01.754319 kernel: raid6: neonx8 gen() 15815 MB/s Nov 6 23:17:01.773304 kernel: raid6: neonx4 gen() 15861 MB/s Nov 6 23:17:01.792306 kernel: raid6: neonx2 gen() 13227 MB/s Nov 6 23:17:01.812303 kernel: raid6: neonx1 gen() 10546 MB/s Nov 6 23:17:01.831302 kernel: raid6: int64x8 gen() 6802 MB/s Nov 6 23:17:01.850306 kernel: raid6: int64x4 gen() 7359 MB/s Nov 6 23:17:01.870301 kernel: raid6: int64x2 gen() 6124 MB/s Nov 6 23:17:01.892166 kernel: raid6: int64x1 gen() 5071 MB/s Nov 6 23:17:01.892178 kernel: raid6: using algorithm neonx4 gen() 15861 MB/s Nov 6 23:17:01.915222 kernel: raid6: .... xor() 12302 MB/s, rmw enabled Nov 6 23:17:01.915239 kernel: raid6: using neon recovery algorithm Nov 6 23:17:01.922305 kernel: xor: measuring software checksum speed Nov 6 23:17:01.926301 kernel: 8regs : 20536 MB/sec Nov 6 23:17:01.926313 kernel: 32regs : 21090 MB/sec Nov 6 23:17:01.931635 kernel: arm64_neon : 28041 MB/sec Nov 6 23:17:01.934979 kernel: xor: using function: arm64_neon (28041 MB/sec) Nov 6 23:17:01.984510 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 6 23:17:01.993736 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 6 23:17:02.009480 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:17:02.030402 systemd-udevd[441]: Using default interface naming scheme 'v255'. Nov 6 23:17:02.034843 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:17:02.050411 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 6 23:17:02.082018 dracut-pre-trigger[453]: rd.md=0: removing MD RAID activation Nov 6 23:17:02.114477 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 23:17:02.125538 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 23:17:02.166522 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:17:02.184690 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 6 23:17:02.216912 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 6 23:17:02.227690 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 23:17:02.236659 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:17:02.244837 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 23:17:02.264504 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 6 23:17:02.280668 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 23:17:02.280797 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:17:02.292987 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:17:02.302367 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:17:02.302602 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:17:02.334630 kernel: hv_vmbus: Vmbus version:5.3 Nov 6 23:17:02.319766 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:17:02.335603 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:17:02.358049 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 6 23:17:02.386937 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 6 23:17:02.386963 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 6 23:17:02.386974 kernel: hv_vmbus: registering driver hid_hyperv Nov 6 23:17:02.386983 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 6 23:17:02.377455 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:17:02.401478 kernel: hv_vmbus: registering driver hv_storvsc Nov 6 23:17:02.405552 kernel: PTP clock support registered Nov 6 23:17:02.406315 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Nov 6 23:17:02.416555 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:17:02.451176 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Nov 6 23:17:02.451198 kernel: hv_utils: Registering HyperV Utility Driver Nov 6 23:17:02.451214 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 6 23:17:02.451427 kernel: hv_vmbus: registering driver hv_netvsc Nov 6 23:17:02.451439 kernel: hv_vmbus: registering driver hv_utils Nov 6 23:17:02.457592 kernel: scsi host0: storvsc_host_t Nov 6 23:17:02.457806 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 6 23:17:02.457927 kernel: scsi host1: storvsc_host_t Nov 6 23:17:02.458031 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Nov 6 23:17:02.467308 kernel: hv_utils: Heartbeat IC version 3.0 Nov 6 23:17:02.472330 kernel: hv_utils: Shutdown IC version 3.2 Nov 6 23:17:02.472351 kernel: hv_utils: TimeSync IC version 4.0 Nov 6 23:17:02.845638 systemd-resolved[259]: Clock change detected. Flushing caches. Nov 6 23:17:02.870519 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 6 23:17:02.870724 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 6 23:17:02.871801 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 6 23:17:02.888854 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 6 23:17:02.889088 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 6 23:17:02.893879 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 6 23:17:02.893181 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:17:02.910348 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 6 23:17:02.910589 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 6 23:17:02.911891 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#100 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 6 23:17:02.921825 kernel: hv_netvsc 000d3af9-a171-000d-3af9-a171000d3af9 eth0: VF slot 1 added Nov 6 23:17:02.927789 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:17:02.933846 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 6 23:17:02.947967 kernel: hv_vmbus: registering driver hv_pci Nov 6 23:17:02.948043 kernel: hv_pci 48b310f2-c813-4c24-a78e-3c3c9e7fd5b0: PCI VMBus probing: Using version 0x10004 Nov 6 23:17:02.961885 kernel: hv_pci 48b310f2-c813-4c24-a78e-3c3c9e7fd5b0: PCI host bridge to bus c813:00 Nov 6 23:17:02.962075 kernel: pci_bus c813:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Nov 6 23:17:02.962197 kernel: pci_bus c813:00: No busn resource found for root bus, will use [bus 00-ff] Nov 6 23:17:02.973235 kernel: pci c813:00:02.0: [15b3:1018] type 00 class 0x020000 Nov 6 23:17:02.985625 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#95 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 6 23:17:02.985844 kernel: pci c813:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Nov 6 23:17:02.991830 kernel: pci c813:00:02.0: enabling Extended Tags Nov 6 23:17:03.007839 kernel: pci c813:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at c813:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Nov 6 23:17:03.017737 kernel: pci_bus c813:00: busn_res: [bus 00-ff] end is updated to 00 Nov 6 23:17:03.017927 kernel: pci c813:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Nov 6 23:17:03.066651 kernel: mlx5_core c813:00:02.0: enabling device (0000 -> 0002) Nov 6 23:17:03.072785 kernel: mlx5_core c813:00:02.0: firmware version: 16.30.5006 Nov 6 23:17:03.265889 kernel: hv_netvsc 000d3af9-a171-000d-3af9-a171000d3af9 eth0: VF registering: eth1 Nov 6 23:17:03.266093 kernel: mlx5_core c813:00:02.0 eth1: joined to eth0 Nov 6 23:17:03.271459 kernel: mlx5_core c813:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Nov 6 23:17:03.280798 kernel: mlx5_core c813:00:02.0 enP51219s1: renamed from eth1 Nov 6 23:17:03.617898 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 6 23:17:03.656000 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (485) Nov 6 23:17:03.676132 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 6 23:17:03.688638 kernel: BTRFS: device fsid 8ac35527-52fd-4925-acbb-f12804e07c02 devid 1 transid 36 /dev/sda3 scanned by (udev-worker) (490) Nov 6 23:17:03.698845 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 23:17:03.711810 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Nov 6 23:17:03.717474 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 6 23:17:03.742902 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 6 23:17:03.767857 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:17:04.785548 disk-uuid[606]: The operation has completed successfully. Nov 6 23:17:04.789766 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:17:04.862207 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 6 23:17:04.862323 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 6 23:17:04.900956 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 6 23:17:04.912068 sh[692]: Success Nov 6 23:17:04.947653 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 6 23:17:05.449840 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 6 23:17:05.455702 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 6 23:17:05.468941 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 6 23:17:05.501784 kernel: BTRFS info (device dm-0): first mount of filesystem 8ac35527-52fd-4925-acbb-f12804e07c02 Nov 6 23:17:05.501843 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:17:05.501854 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 6 23:17:05.511277 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 6 23:17:05.514863 kernel: BTRFS info (device dm-0): using free space tree Nov 6 23:17:05.940729 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 6 23:17:05.944861 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 6 23:17:05.961996 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 6 23:17:05.973974 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 6 23:17:06.008747 kernel: BTRFS info (device sda6): first mount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:17:06.008817 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:17:06.012619 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:17:06.082006 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:17:06.087681 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 23:17:06.096681 kernel: BTRFS info (device sda6): last unmount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:17:06.109763 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 23:17:06.115657 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 6 23:17:06.140969 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 6 23:17:06.147907 systemd-networkd[871]: lo: Link UP Nov 6 23:17:06.147911 systemd-networkd[871]: lo: Gained carrier Nov 6 23:17:06.149533 systemd-networkd[871]: Enumeration completed Nov 6 23:17:06.150047 systemd-networkd[871]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:17:06.150050 systemd-networkd[871]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:17:06.152987 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 23:17:06.159150 systemd[1]: Reached target network.target - Network. Nov 6 23:17:06.212785 kernel: mlx5_core c813:00:02.0 enP51219s1: Link up Nov 6 23:17:06.251792 kernel: hv_netvsc 000d3af9-a171-000d-3af9-a171000d3af9 eth0: Data path switched to VF: enP51219s1 Nov 6 23:17:06.252061 systemd-networkd[871]: enP51219s1: Link UP Nov 6 23:17:06.252139 systemd-networkd[871]: eth0: Link UP Nov 6 23:17:06.252229 systemd-networkd[871]: eth0: Gained carrier Nov 6 23:17:06.252237 systemd-networkd[871]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:17:06.270994 systemd-networkd[871]: enP51219s1: Gained carrier Nov 6 23:17:06.287818 systemd-networkd[871]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 6 23:17:07.247871 ignition[874]: Ignition 2.20.0 Nov 6 23:17:07.247886 ignition[874]: Stage: fetch-offline Nov 6 23:17:07.251944 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 23:17:07.247924 ignition[874]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:17:07.247933 ignition[874]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:17:07.248032 ignition[874]: parsed url from cmdline: "" Nov 6 23:17:07.248035 ignition[874]: no config URL provided Nov 6 23:17:07.248040 ignition[874]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 23:17:07.278946 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 6 23:17:07.248047 ignition[874]: no config at "/usr/lib/ignition/user.ign" Nov 6 23:17:07.248053 ignition[874]: failed to fetch config: resource requires networking Nov 6 23:17:07.248596 ignition[874]: Ignition finished successfully Nov 6 23:17:07.298302 ignition[884]: Ignition 2.20.0 Nov 6 23:17:07.298308 ignition[884]: Stage: fetch Nov 6 23:17:07.298474 ignition[884]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:17:07.298483 ignition[884]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:17:07.298569 ignition[884]: parsed url from cmdline: "" Nov 6 23:17:07.298572 ignition[884]: no config URL provided Nov 6 23:17:07.298576 ignition[884]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 23:17:07.298583 ignition[884]: no config at "/usr/lib/ignition/user.ign" Nov 6 23:17:07.298610 ignition[884]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 6 23:17:07.379942 ignition[884]: GET result: OK Nov 6 23:17:07.379989 ignition[884]: config has been read from IMDS userdata Nov 6 23:17:07.380007 ignition[884]: parsing config with SHA512: 65e64642857e9291f423bbb626555cdfe9e6f1bf2fd983a0aa98c8b97254126695d699305d64bf63db9a7f2ebd8d0cecfe7830d71a54df22bb73ebfe573c3474 Nov 6 23:17:07.383126 unknown[884]: fetched base config from "system" Nov 6 23:17:07.383368 ignition[884]: fetch: fetch complete Nov 6 23:17:07.383133 unknown[884]: fetched base config from "system" Nov 6 23:17:07.383373 ignition[884]: fetch: fetch passed Nov 6 23:17:07.383138 unknown[884]: fetched user config from "azure" Nov 6 23:17:07.383417 ignition[884]: Ignition finished successfully Nov 6 23:17:07.387790 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 6 23:17:07.403039 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 6 23:17:07.429067 ignition[890]: Ignition 2.20.0 Nov 6 23:17:07.429077 ignition[890]: Stage: kargs Nov 6 23:17:07.433282 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 6 23:17:07.429257 ignition[890]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:17:07.429266 ignition[890]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:17:07.429948 ignition[890]: kargs: kargs passed Nov 6 23:17:07.450938 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 6 23:17:07.429994 ignition[890]: Ignition finished successfully Nov 6 23:17:07.478090 ignition[896]: Ignition 2.20.0 Nov 6 23:17:07.478099 ignition[896]: Stage: disks Nov 6 23:17:07.481828 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 6 23:17:07.478273 ignition[896]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:17:07.486815 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 6 23:17:07.478282 ignition[896]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:17:07.493755 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 6 23:17:07.479102 ignition[896]: disks: disks passed Nov 6 23:17:07.503594 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 23:17:07.479155 ignition[896]: Ignition finished successfully Nov 6 23:17:07.511722 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 23:17:07.520957 systemd[1]: Reached target basic.target - Basic System. Nov 6 23:17:07.543016 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 6 23:17:07.610860 systemd-fsck[905]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Nov 6 23:17:07.616049 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 6 23:17:07.633005 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 6 23:17:07.688927 kernel: EXT4-fs (sda9): mounted filesystem 93ef6c07-4a07-4e6a-86ce-df7a94c95ac7 r/w with ordered data mode. Quota mode: none. Nov 6 23:17:07.689695 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 6 23:17:07.693979 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 6 23:17:07.735855 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 23:17:07.754796 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (916) Nov 6 23:17:07.765360 kernel: BTRFS info (device sda6): first mount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:17:07.765381 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:17:07.769402 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:17:07.771926 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 6 23:17:07.779647 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:17:07.784605 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 6 23:17:07.789763 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 6 23:17:07.789808 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 23:17:07.812311 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 23:17:07.819859 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 6 23:17:07.837016 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 6 23:17:07.841962 systemd-networkd[871]: eth0: Gained IPv6LL Nov 6 23:17:08.467271 coreos-metadata[933]: Nov 06 23:17:08.467 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 23:17:08.474261 coreos-metadata[933]: Nov 06 23:17:08.473 INFO Fetch successful Nov 6 23:17:08.474261 coreos-metadata[933]: Nov 06 23:17:08.473 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 6 23:17:08.488133 coreos-metadata[933]: Nov 06 23:17:08.487 INFO Fetch successful Nov 6 23:17:08.503407 coreos-metadata[933]: Nov 06 23:17:08.503 INFO wrote hostname ci-4230.2.4-n-0cdd2482db to /sysroot/etc/hostname Nov 6 23:17:08.511552 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 23:17:08.926679 initrd-setup-root[948]: cut: /sysroot/etc/passwd: No such file or directory Nov 6 23:17:08.994015 initrd-setup-root[955]: cut: /sysroot/etc/group: No such file or directory Nov 6 23:17:09.013331 initrd-setup-root[962]: cut: /sysroot/etc/shadow: No such file or directory Nov 6 23:17:09.021428 initrd-setup-root[969]: cut: /sysroot/etc/gshadow: No such file or directory Nov 6 23:17:10.207492 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 6 23:17:10.226035 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 6 23:17:10.234993 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 6 23:17:10.253055 kernel: BTRFS info (device sda6): last unmount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:17:10.249839 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 6 23:17:10.271835 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 6 23:17:10.284504 ignition[1038]: INFO : Ignition 2.20.0 Nov 6 23:17:10.289272 ignition[1038]: INFO : Stage: mount Nov 6 23:17:10.289272 ignition[1038]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:17:10.289272 ignition[1038]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:17:10.289272 ignition[1038]: INFO : mount: mount passed Nov 6 23:17:10.289272 ignition[1038]: INFO : Ignition finished successfully Nov 6 23:17:10.294639 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 6 23:17:10.319029 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 6 23:17:10.336933 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 23:17:10.355786 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1049) Nov 6 23:17:10.367172 kernel: BTRFS info (device sda6): first mount of filesystem 9553d21b-1d44-4f16-bc6d-739b0555444a Nov 6 23:17:10.367213 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 6 23:17:10.370711 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:17:10.377788 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:17:10.379353 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 23:17:10.403615 ignition[1066]: INFO : Ignition 2.20.0 Nov 6 23:17:10.403615 ignition[1066]: INFO : Stage: files Nov 6 23:17:10.410534 ignition[1066]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:17:10.410534 ignition[1066]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:17:10.410534 ignition[1066]: DEBUG : files: compiled without relabeling support, skipping Nov 6 23:17:10.442237 ignition[1066]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 6 23:17:10.442237 ignition[1066]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 6 23:17:10.561198 ignition[1066]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 6 23:17:10.567494 ignition[1066]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 6 23:17:10.567494 ignition[1066]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 6 23:17:10.561585 unknown[1066]: wrote ssh authorized keys file for user: core Nov 6 23:17:10.596368 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 6 23:17:10.604219 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 6 23:17:10.613454 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 23:17:10.621609 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 23:17:10.621609 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 6 23:17:10.621609 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 6 23:17:10.621609 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 6 23:17:10.621609 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Nov 6 23:17:11.181301 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 6 23:17:11.535563 ignition[1066]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 6 23:17:11.545059 ignition[1066]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 6 23:17:11.545059 ignition[1066]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 6 23:17:11.545059 ignition[1066]: INFO : files: files passed Nov 6 23:17:11.545059 ignition[1066]: INFO : Ignition finished successfully Nov 6 23:17:11.546689 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 6 23:17:11.574981 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 6 23:17:11.583000 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 6 23:17:11.602631 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 6 23:17:11.602723 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 6 23:17:11.619887 initrd-setup-root-after-ignition[1093]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:17:11.619887 initrd-setup-root-after-ignition[1093]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:17:11.615582 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 23:17:11.649438 initrd-setup-root-after-ignition[1097]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:17:11.626193 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 6 23:17:11.650010 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 6 23:17:11.686940 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 6 23:17:11.687062 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 6 23:17:11.697222 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 6 23:17:11.706818 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 6 23:17:11.715570 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 6 23:17:11.730030 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 6 23:17:11.748411 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 23:17:11.762070 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 6 23:17:11.777898 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:17:11.783326 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:17:11.793256 systemd[1]: Stopped target timers.target - Timer Units. Nov 6 23:17:11.802321 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 6 23:17:11.802451 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 23:17:11.815448 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 6 23:17:11.820168 systemd[1]: Stopped target basic.target - Basic System. Nov 6 23:17:11.829130 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 6 23:17:11.838430 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 23:17:11.847306 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 6 23:17:11.856704 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 6 23:17:11.866293 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 23:17:11.876382 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 6 23:17:11.885341 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 6 23:17:11.895173 systemd[1]: Stopped target swap.target - Swaps. Nov 6 23:17:11.902980 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 6 23:17:11.903109 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 6 23:17:11.915409 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:17:11.920544 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:17:11.929965 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 6 23:17:11.930038 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:17:11.940007 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 6 23:17:11.940122 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 6 23:17:11.954224 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 6 23:17:11.954406 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 23:17:11.964223 systemd[1]: ignition-files.service: Deactivated successfully. Nov 6 23:17:11.964317 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 6 23:17:11.974592 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 6 23:17:11.974685 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 23:17:12.000000 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 6 23:17:12.042758 ignition[1118]: INFO : Ignition 2.20.0 Nov 6 23:17:12.042758 ignition[1118]: INFO : Stage: umount Nov 6 23:17:12.042758 ignition[1118]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:17:12.042758 ignition[1118]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:17:12.042758 ignition[1118]: INFO : umount: umount passed Nov 6 23:17:12.042758 ignition[1118]: INFO : Ignition finished successfully Nov 6 23:17:12.012930 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 6 23:17:12.013116 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:17:12.037079 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 6 23:17:12.048298 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 6 23:17:12.048484 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:17:12.058178 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 6 23:17:12.058295 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 23:17:12.080510 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 6 23:17:12.080648 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 6 23:17:12.088817 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 6 23:17:12.088927 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 6 23:17:12.100389 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 6 23:17:12.101752 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 6 23:17:12.101828 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 6 23:17:12.108036 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 6 23:17:12.108090 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 6 23:17:12.112802 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 6 23:17:12.112855 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 6 23:17:12.122753 systemd[1]: Stopped target network.target - Network. Nov 6 23:17:12.131958 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 6 23:17:12.132025 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 23:17:12.141562 systemd[1]: Stopped target paths.target - Path Units. Nov 6 23:17:12.145671 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 6 23:17:12.150796 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:17:12.156333 systemd[1]: Stopped target slices.target - Slice Units. Nov 6 23:17:12.164858 systemd[1]: Stopped target sockets.target - Socket Units. Nov 6 23:17:12.173834 systemd[1]: iscsid.socket: Deactivated successfully. Nov 6 23:17:12.173886 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 23:17:12.182489 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 6 23:17:12.182521 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 23:17:12.191437 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 6 23:17:12.191498 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 6 23:17:12.200127 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 6 23:17:12.200174 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 6 23:17:12.210443 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 6 23:17:12.219042 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 6 23:17:12.244369 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 6 23:17:12.244494 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 6 23:17:12.251370 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 6 23:17:12.251486 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 6 23:17:12.265187 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 6 23:17:12.265409 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 6 23:17:12.265664 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 6 23:17:12.279949 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 6 23:17:12.461967 kernel: hv_netvsc 000d3af9-a171-000d-3af9-a171000d3af9 eth0: Data path switched from VF: enP51219s1 Nov 6 23:17:12.281287 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 6 23:17:12.281356 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:17:12.288547 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 6 23:17:12.288621 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 6 23:17:12.309981 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 6 23:17:12.318871 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 6 23:17:12.318976 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 23:17:12.328430 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 6 23:17:12.328478 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:17:12.341210 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 6 23:17:12.341276 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 6 23:17:12.346376 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 6 23:17:12.346422 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:17:12.360276 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:17:12.370865 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 6 23:17:12.370943 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:17:12.397899 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 6 23:17:12.398045 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:17:12.407729 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 6 23:17:12.407872 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 6 23:17:12.416611 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 6 23:17:12.416651 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:17:12.426040 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 6 23:17:12.426096 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 6 23:17:12.447264 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 6 23:17:12.447326 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 6 23:17:12.462057 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 23:17:12.462139 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:17:12.495949 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 6 23:17:12.508849 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 6 23:17:12.508918 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:17:12.524357 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:17:12.524407 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:17:12.534804 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Nov 6 23:17:12.534869 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:17:12.694554 systemd-journald[218]: Received SIGTERM from PID 1 (systemd). Nov 6 23:17:12.535229 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 6 23:17:12.535339 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 6 23:17:12.543655 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 6 23:17:12.543746 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 6 23:17:12.554380 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 6 23:17:12.578056 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 6 23:17:12.606400 systemd[1]: Switching root. Nov 6 23:17:12.724803 systemd-journald[218]: Journal stopped Nov 6 23:17:23.986148 kernel: SELinux: policy capability network_peer_controls=1 Nov 6 23:17:23.986182 kernel: SELinux: policy capability open_perms=1 Nov 6 23:17:23.986192 kernel: SELinux: policy capability extended_socket_class=1 Nov 6 23:17:23.986200 kernel: SELinux: policy capability always_check_network=0 Nov 6 23:17:23.986211 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 6 23:17:23.986219 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 6 23:17:23.986228 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 6 23:17:23.986236 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 6 23:17:23.986243 kernel: audit: type=1403 audit(1762471033.943:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 6 23:17:23.986253 systemd[1]: Successfully loaded SELinux policy in 208.186ms. Nov 6 23:17:23.986264 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.658ms. Nov 6 23:17:23.986276 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 23:17:23.986285 systemd[1]: Detected virtualization microsoft. Nov 6 23:17:23.986293 systemd[1]: Detected architecture arm64. Nov 6 23:17:23.986302 systemd[1]: Detected first boot. Nov 6 23:17:23.986313 systemd[1]: Hostname set to . Nov 6 23:17:23.986322 systemd[1]: Initializing machine ID from random generator. Nov 6 23:17:23.986330 zram_generator::config[1162]: No configuration found. Nov 6 23:17:23.986339 kernel: NET: Registered PF_VSOCK protocol family Nov 6 23:17:23.986347 systemd[1]: Populated /etc with preset unit settings. Nov 6 23:17:23.986357 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 6 23:17:23.986365 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 6 23:17:23.986376 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 6 23:17:23.986385 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 6 23:17:23.986393 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 6 23:17:23.986403 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 6 23:17:23.986411 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 6 23:17:23.986420 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 6 23:17:23.986429 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 6 23:17:23.986440 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 6 23:17:23.986449 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 6 23:17:23.986458 systemd[1]: Created slice user.slice - User and Session Slice. Nov 6 23:17:23.986468 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:17:23.986477 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:17:23.986486 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 6 23:17:23.986494 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 6 23:17:23.986503 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 6 23:17:23.986514 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 23:17:23.986523 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 6 23:17:23.986532 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:17:23.986543 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 6 23:17:23.986552 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 6 23:17:23.986562 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 6 23:17:23.986571 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 6 23:17:23.986580 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:17:23.986590 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 23:17:23.986599 systemd[1]: Reached target slices.target - Slice Units. Nov 6 23:17:23.986608 systemd[1]: Reached target swap.target - Swaps. Nov 6 23:17:23.986617 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 6 23:17:23.986626 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 6 23:17:23.986635 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 6 23:17:23.986646 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:17:23.986656 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 23:17:23.986666 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:17:23.986675 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 6 23:17:23.986685 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 6 23:17:23.986694 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 6 23:17:23.986703 systemd[1]: Mounting media.mount - External Media Directory... Nov 6 23:17:23.986713 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 6 23:17:23.986723 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 6 23:17:23.986732 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 6 23:17:23.986741 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 6 23:17:23.986751 systemd[1]: Reached target machines.target - Containers. Nov 6 23:17:23.986760 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 6 23:17:23.986779 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:17:23.986791 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 23:17:23.986802 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 6 23:17:23.986811 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:17:23.986820 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 23:17:23.986830 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:17:23.986839 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 6 23:17:23.986848 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:17:23.986857 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 6 23:17:23.986867 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 6 23:17:23.986878 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 6 23:17:23.986888 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 6 23:17:23.986897 systemd[1]: Stopped systemd-fsck-usr.service. Nov 6 23:17:23.986906 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 23:17:23.986916 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 23:17:23.986924 kernel: loop: module loaded Nov 6 23:17:23.986933 kernel: fuse: init (API version 7.39) Nov 6 23:17:23.986941 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 23:17:23.986951 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 6 23:17:23.986961 kernel: ACPI: bus type drm_connector registered Nov 6 23:17:23.986970 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 6 23:17:23.986979 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 6 23:17:23.986988 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 23:17:23.986998 systemd[1]: verity-setup.service: Deactivated successfully. Nov 6 23:17:23.987036 systemd-journald[1266]: Collecting audit messages is disabled. Nov 6 23:17:23.987059 systemd[1]: Stopped verity-setup.service. Nov 6 23:17:23.987069 systemd-journald[1266]: Journal started Nov 6 23:17:23.987088 systemd-journald[1266]: Runtime Journal (/run/log/journal/d6df412aa4af4d378aa2c42ce606d507) is 8M, max 78.5M, 70.5M free. Nov 6 23:17:23.038837 systemd[1]: Queued start job for default target multi-user.target. Nov 6 23:17:23.045613 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 6 23:17:23.045988 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 6 23:17:23.046307 systemd[1]: systemd-journald.service: Consumed 2.434s CPU time. Nov 6 23:17:23.998933 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 23:17:23.999641 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 6 23:17:24.004682 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 6 23:17:24.009903 systemd[1]: Mounted media.mount - External Media Directory. Nov 6 23:17:24.014303 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 6 23:17:24.019649 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 6 23:17:24.024844 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 6 23:17:24.029668 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 6 23:17:24.036381 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:17:24.042395 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 6 23:17:24.042559 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 6 23:17:24.048304 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:17:24.048457 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:17:24.053904 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 23:17:24.054052 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 23:17:24.059291 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:17:24.059434 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:17:24.065750 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 6 23:17:24.065921 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 6 23:17:24.071623 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:17:24.071784 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:17:24.076994 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 23:17:24.082642 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 23:17:24.088505 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 6 23:17:24.094837 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 6 23:17:24.110380 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:17:24.118339 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 6 23:17:24.127851 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 6 23:17:24.133931 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 6 23:17:24.138976 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 6 23:17:24.139008 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 23:17:24.144475 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 6 23:17:24.151117 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 6 23:17:24.157262 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 6 23:17:24.161958 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:17:24.185875 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 6 23:17:24.192961 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 6 23:17:24.198457 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 23:17:24.199370 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 6 23:17:24.204393 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 23:17:24.205944 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 23:17:24.212343 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 6 23:17:24.227686 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 6 23:17:24.235351 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 6 23:17:24.242490 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 6 23:17:24.248592 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 6 23:17:24.254152 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 6 23:17:24.262127 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 6 23:17:24.272579 systemd-journald[1266]: Time spent on flushing to /var/log/journal/d6df412aa4af4d378aa2c42ce606d507 is 18.371ms for 890 entries. Nov 6 23:17:24.272579 systemd-journald[1266]: System Journal (/var/log/journal/d6df412aa4af4d378aa2c42ce606d507) is 8M, max 2.6G, 2.6G free. Nov 6 23:17:24.327168 systemd-journald[1266]: Received client request to flush runtime journal. Nov 6 23:17:24.327241 kernel: loop0: detected capacity change from 0 to 113512 Nov 6 23:17:24.274834 udevadm[1305]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 6 23:17:24.275636 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 6 23:17:24.291884 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 6 23:17:24.328142 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 6 23:17:24.796197 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:17:27.487803 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 6 23:17:27.488457 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 6 23:17:27.949791 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 6 23:17:27.992386 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 6 23:17:28.005909 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 23:17:28.019790 kernel: loop1: detected capacity change from 0 to 207008 Nov 6 23:17:29.116179 systemd-tmpfiles[1320]: ACLs are not supported, ignoring. Nov 6 23:17:29.116194 systemd-tmpfiles[1320]: ACLs are not supported, ignoring. Nov 6 23:17:29.119023 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 6 23:17:29.124758 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:17:29.135901 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:17:29.151786 kernel: loop2: detected capacity change from 0 to 28720 Nov 6 23:17:29.165726 systemd-udevd[1325]: Using default interface naming scheme 'v255'. Nov 6 23:17:30.660796 kernel: loop3: detected capacity change from 0 to 123192 Nov 6 23:17:31.291916 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:17:31.306949 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 23:17:31.348375 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 6 23:17:31.417963 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 6 23:17:31.512969 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 6 23:17:31.556834 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#155 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 6 23:17:31.658912 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:17:31.714795 kernel: hv_vmbus: registering driver hv_balloon Nov 6 23:17:31.722029 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 6 23:17:31.722101 kernel: hv_balloon: Memory hot add disabled on ARM64 Nov 6 23:17:31.722129 kernel: mousedev: PS/2 mouse device common for all mice Nov 6 23:17:32.066497 kernel: hv_vmbus: registering driver hyperv_fb Nov 6 23:17:32.066589 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 6 23:17:32.072153 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 6 23:17:32.076184 kernel: Console: switching to colour dummy device 80x25 Nov 6 23:17:32.082545 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 23:17:32.113321 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:17:32.113546 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:17:32.119949 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:17:32.123974 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:17:32.499199 systemd-networkd[1339]: lo: Link UP Nov 6 23:17:32.499207 systemd-networkd[1339]: lo: Gained carrier Nov 6 23:17:32.501241 systemd-networkd[1339]: Enumeration completed Nov 6 23:17:32.501367 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 23:17:32.501514 systemd-networkd[1339]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:17:32.501518 systemd-networkd[1339]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:17:32.509924 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 6 23:17:32.516754 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 6 23:17:32.563795 kernel: mlx5_core c813:00:02.0 enP51219s1: Link up Nov 6 23:17:32.587812 kernel: hv_netvsc 000d3af9-a171-000d-3af9-a171000d3af9 eth0: Data path switched to VF: enP51219s1 Nov 6 23:17:32.588321 systemd-networkd[1339]: enP51219s1: Link UP Nov 6 23:17:32.588410 systemd-networkd[1339]: eth0: Link UP Nov 6 23:17:32.588413 systemd-networkd[1339]: eth0: Gained carrier Nov 6 23:17:32.588426 systemd-networkd[1339]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:17:32.594048 systemd-networkd[1339]: enP51219s1: Gained carrier Nov 6 23:17:32.603801 systemd-networkd[1339]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 6 23:17:32.895981 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 6 23:17:33.034797 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1346) Nov 6 23:17:33.358976 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 23:17:33.368899 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 6 23:17:33.425787 kernel: loop4: detected capacity change from 0 to 113512 Nov 6 23:17:33.697896 systemd-networkd[1339]: eth0: Gained IPv6LL Nov 6 23:17:33.699880 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 6 23:17:33.904530 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 6 23:17:34.082947 kernel: loop5: detected capacity change from 0 to 207008 Nov 6 23:17:34.081814 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 6 23:17:34.097119 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 6 23:17:34.446793 kernel: loop6: detected capacity change from 0 to 28720 Nov 6 23:17:34.457792 kernel: loop7: detected capacity change from 0 to 123192 Nov 6 23:17:34.465793 (sd-merge)[1440]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-azure'. Nov 6 23:17:34.466234 (sd-merge)[1440]: Merged extensions into '/usr'. Nov 6 23:17:34.470361 systemd[1]: Reload requested from client PID 1302 ('systemd-sysext') (unit systemd-sysext.service)... Nov 6 23:17:34.470373 systemd[1]: Reloading... Nov 6 23:17:34.487588 lvm[1451]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 6 23:17:34.540802 zram_generator::config[1479]: No configuration found. Nov 6 23:17:34.661671 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:34.754068 systemd[1]: Reloading finished in 283 ms. Nov 6 23:17:34.772485 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 6 23:17:34.778853 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 6 23:17:34.787280 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:17:34.796869 systemd[1]: Starting ensure-sysext.service... Nov 6 23:17:34.801993 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 6 23:17:34.808958 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 23:17:34.811314 lvm[1539]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 6 23:17:34.825416 systemd[1]: Reload requested from client PID 1538 ('systemctl') (unit ensure-sysext.service)... Nov 6 23:17:34.825433 systemd[1]: Reloading... Nov 6 23:17:34.896827 zram_generator::config[1577]: No configuration found. Nov 6 23:17:34.903564 systemd-tmpfiles[1540]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 6 23:17:34.903794 systemd-tmpfiles[1540]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 6 23:17:34.905288 systemd-tmpfiles[1540]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 6 23:17:34.905494 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. Nov 6 23:17:34.905537 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. Nov 6 23:17:34.939330 systemd-tmpfiles[1540]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 23:17:34.939453 systemd-tmpfiles[1540]: Skipping /boot Nov 6 23:17:34.949873 systemd-tmpfiles[1540]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 23:17:34.949980 systemd-tmpfiles[1540]: Skipping /boot Nov 6 23:17:35.000478 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:35.095980 systemd[1]: Reloading finished in 270 ms. Nov 6 23:17:35.120794 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 6 23:17:35.128034 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:17:35.146031 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 6 23:17:35.183021 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 6 23:17:35.190162 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 6 23:17:35.205007 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 23:17:35.211082 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 6 23:17:35.221558 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:17:35.228039 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:17:35.237007 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:17:35.244425 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:17:35.249286 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:17:35.249390 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 23:17:35.253375 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:17:35.253570 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:17:35.261104 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:17:35.261245 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:17:35.269191 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:17:35.269349 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:17:35.283805 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:17:35.291379 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 6 23:17:35.300031 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:17:35.306588 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:17:35.312849 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 23:17:35.320786 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:17:35.327001 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:17:35.332500 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:17:35.332632 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 23:17:35.332777 systemd[1]: Reached target time-set.target - System Time Set. Nov 6 23:17:35.340928 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:17:35.341103 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:17:35.346660 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 23:17:35.346843 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 23:17:35.352492 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:17:35.352648 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:17:35.358745 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:17:35.359008 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:17:35.369213 systemd[1]: Finished ensure-sysext.service. Nov 6 23:17:35.371991 systemd-resolved[1636]: Positive Trust Anchors: Nov 6 23:17:35.372007 systemd-resolved[1636]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 23:17:35.372050 systemd-resolved[1636]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 6 23:17:35.376090 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 23:17:35.376155 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 23:17:35.462277 systemd-resolved[1636]: Using system hostname 'ci-4230.2.4-n-0cdd2482db'. Nov 6 23:17:35.463784 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 23:17:35.469643 systemd[1]: Reached target network.target - Network. Nov 6 23:17:35.473815 systemd[1]: Reached target network-online.target - Network is Online. Nov 6 23:17:35.479142 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:17:35.479290 augenrules[1675]: No rules Nov 6 23:17:35.484922 systemd[1]: audit-rules.service: Deactivated successfully. Nov 6 23:17:35.487124 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 6 23:17:35.612381 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 6 23:17:37.961362 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 6 23:17:37.968146 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 6 23:17:41.854330 ldconfig[1297]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 6 23:17:41.867991 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 6 23:17:41.877957 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 6 23:17:41.900798 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 6 23:17:41.905978 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 23:17:41.910816 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 6 23:17:41.916242 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 6 23:17:41.921928 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 6 23:17:41.926640 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 6 23:17:41.932104 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 6 23:17:41.937560 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 6 23:17:41.937591 systemd[1]: Reached target paths.target - Path Units. Nov 6 23:17:41.941660 systemd[1]: Reached target timers.target - Timer Units. Nov 6 23:17:41.961318 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 6 23:17:41.967439 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 6 23:17:42.021117 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 6 23:17:42.027007 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 6 23:17:42.032524 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 6 23:17:42.044372 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 6 23:17:42.049177 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 6 23:17:42.055075 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 6 23:17:42.059816 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 23:17:42.063964 systemd[1]: Reached target basic.target - Basic System. Nov 6 23:17:42.068317 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 6 23:17:42.068341 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 6 23:17:42.092846 systemd[1]: Starting chronyd.service - NTP client/server... Nov 6 23:17:42.099678 systemd[1]: Starting containerd.service - containerd container runtime... Nov 6 23:17:42.109952 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 6 23:17:42.120179 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 6 23:17:42.130903 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 6 23:17:42.136745 (chronyd)[1688]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Nov 6 23:17:42.137469 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 6 23:17:42.142035 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 6 23:17:42.142155 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Nov 6 23:17:42.143341 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 6 23:17:42.149113 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 6 23:17:42.150098 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:17:42.155924 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 6 23:17:42.164351 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 6 23:17:42.166651 jq[1695]: false Nov 6 23:17:42.169919 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 6 23:17:42.178032 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 6 23:17:42.181265 KVP[1697]: KVP starting; pid is:1697 Nov 6 23:17:42.189559 KVP[1697]: KVP LIC Version: 3.1 Nov 6 23:17:42.190759 kernel: hv_utils: KVP IC version 4.0 Nov 6 23:17:42.190869 chronyd[1708]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Nov 6 23:17:42.191472 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 6 23:17:42.199749 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 6 23:17:42.201197 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 6 23:17:42.205329 systemd[1]: Starting update-engine.service - Update Engine... Nov 6 23:17:42.216134 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 6 23:17:42.228202 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 6 23:17:42.228424 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 6 23:17:42.233244 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 6 23:17:42.234876 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 6 23:17:42.241913 extend-filesystems[1696]: Found loop4 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found loop5 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found loop6 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found loop7 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda1 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda2 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda3 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found usr Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda4 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda6 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda7 Nov 6 23:17:42.241913 extend-filesystems[1696]: Found sda9 Nov 6 23:17:42.241913 extend-filesystems[1696]: Checking size of /dev/sda9 Nov 6 23:17:42.344065 jq[1712]: true Nov 6 23:17:42.344176 update_engine[1711]: I20251106 23:17:42.320502 1711 main.cc:92] Flatcar Update Engine starting Nov 6 23:17:42.344365 extend-filesystems[1696]: Old size kept for /dev/sda9 Nov 6 23:17:42.344365 extend-filesystems[1696]: Found sr0 Nov 6 23:17:42.264308 chronyd[1708]: Timezone right/UTC failed leap second check, ignoring Nov 6 23:17:42.264407 systemd[1]: motdgen.service: Deactivated successfully. Nov 6 23:17:42.264471 chronyd[1708]: Loaded seccomp filter (level 2) Nov 6 23:17:42.264623 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 6 23:17:42.273079 (ntainerd)[1724]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 6 23:17:42.377961 jq[1723]: true Nov 6 23:17:42.274858 systemd[1]: Started chronyd.service - NTP client/server. Nov 6 23:17:42.302199 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 6 23:17:42.334706 systemd-logind[1706]: New seat seat0. Nov 6 23:17:42.337302 systemd-logind[1706]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 6 23:17:42.337910 systemd[1]: Started systemd-logind.service - User Login Management. Nov 6 23:17:42.354263 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 6 23:17:42.354463 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 6 23:17:42.488235 bash[1752]: Updated "/home/core/.ssh/authorized_keys" Nov 6 23:17:42.488800 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 6 23:17:42.496215 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 6 23:17:42.513355 dbus-daemon[1694]: [system] SELinux support is enabled Nov 6 23:17:42.513534 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 6 23:17:42.519416 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1756) Nov 6 23:17:42.521539 update_engine[1711]: I20251106 23:17:42.521490 1711 update_check_scheduler.cc:74] Next update check in 2m26s Nov 6 23:17:42.524545 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 6 23:17:42.524579 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 6 23:17:42.533126 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 6 23:17:42.533154 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 6 23:17:42.542886 systemd[1]: Started update-engine.service - Update Engine. Nov 6 23:17:42.547198 dbus-daemon[1694]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 6 23:17:42.565706 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 6 23:17:42.607814 coreos-metadata[1690]: Nov 06 23:17:42.607 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 23:17:42.613918 coreos-metadata[1690]: Nov 06 23:17:42.613 INFO Fetch successful Nov 6 23:17:42.613918 coreos-metadata[1690]: Nov 06 23:17:42.613 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 6 23:17:42.619164 coreos-metadata[1690]: Nov 06 23:17:42.618 INFO Fetch successful Nov 6 23:17:42.619164 coreos-metadata[1690]: Nov 06 23:17:42.619 INFO Fetching http://168.63.129.16/machine/9dcfa3bc-94e1-48fc-b4d3-8dfbe7b7eb92/2054da26%2Da13f%2D4824%2Da7d8%2De633b062eb2c.%5Fci%2D4230.2.4%2Dn%2D0cdd2482db?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 6 23:17:42.621686 coreos-metadata[1690]: Nov 06 23:17:42.621 INFO Fetch successful Nov 6 23:17:42.621686 coreos-metadata[1690]: Nov 06 23:17:42.621 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 6 23:17:42.632978 coreos-metadata[1690]: Nov 06 23:17:42.631 INFO Fetch successful Nov 6 23:17:42.677302 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 6 23:17:42.686561 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 6 23:17:42.759943 sshd_keygen[1735]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 6 23:17:42.779999 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 6 23:17:42.791335 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 6 23:17:42.799603 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 6 23:17:42.806215 systemd[1]: issuegen.service: Deactivated successfully. Nov 6 23:17:42.808750 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 6 23:17:42.818604 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 6 23:17:42.829952 locksmithd[1816]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 6 23:17:42.849311 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 6 23:17:42.867856 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 6 23:17:42.882043 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 6 23:17:42.895646 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 6 23:17:42.902880 systemd[1]: Reached target getty.target - Login Prompts. Nov 6 23:17:42.922641 containerd[1724]: time="2025-11-06T23:17:42.922545460Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 6 23:17:42.961367 containerd[1724]: time="2025-11-06T23:17:42.961315220Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:42.964467 containerd[1724]: time="2025-11-06T23:17:42.964423620Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:42.965921 containerd[1724]: time="2025-11-06T23:17:42.965895980Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 6 23:17:42.966022 containerd[1724]: time="2025-11-06T23:17:42.966008500Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 6 23:17:42.966225 containerd[1724]: time="2025-11-06T23:17:42.966207740Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 6 23:17:42.966956 containerd[1724]: time="2025-11-06T23:17:42.966937620Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:42.967104 containerd[1724]: time="2025-11-06T23:17:42.967083980Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:42.967164 containerd[1724]: time="2025-11-06T23:17:42.967151300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:42.968669 containerd[1724]: time="2025-11-06T23:17:42.968644380Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:42.968748 containerd[1724]: time="2025-11-06T23:17:42.968734660Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:42.968832 containerd[1724]: time="2025-11-06T23:17:42.968817860Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:42.969806 containerd[1724]: time="2025-11-06T23:17:42.969782940Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:42.969981 containerd[1724]: time="2025-11-06T23:17:42.969959380Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:42.970719 containerd[1724]: time="2025-11-06T23:17:42.970694500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:17:42.971532 containerd[1724]: time="2025-11-06T23:17:42.971508340Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:17:42.972462 containerd[1724]: time="2025-11-06T23:17:42.971594540Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 6 23:17:42.972462 containerd[1724]: time="2025-11-06T23:17:42.972286300Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 6 23:17:42.972462 containerd[1724]: time="2025-11-06T23:17:42.972334860Z" level=info msg="metadata content store policy set" policy=shared Nov 6 23:17:42.988884 containerd[1724]: time="2025-11-06T23:17:42.988833460Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 6 23:17:42.988983 containerd[1724]: time="2025-11-06T23:17:42.988903580Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 6 23:17:42.988983 containerd[1724]: time="2025-11-06T23:17:42.988920420Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 6 23:17:42.988983 containerd[1724]: time="2025-11-06T23:17:42.988936340Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 6 23:17:42.988983 containerd[1724]: time="2025-11-06T23:17:42.988951020Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 6 23:17:42.989146 containerd[1724]: time="2025-11-06T23:17:42.989123260Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989359420Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989456660Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989472100Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989485740Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989500020Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989513020Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989525460Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989539420Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989562100Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989575580Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989587220Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989598340Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989618740Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.989889 containerd[1724]: time="2025-11-06T23:17:42.989632220Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989644060Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989656900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989668220Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989680620Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989693260Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989705620Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989717140Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989732100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989744060Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989755100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989788020Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989811900Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989832140Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989846060Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990172 containerd[1724]: time="2025-11-06T23:17:42.989856420Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989900700Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989918180Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989928580Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989940140Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989949420Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989962020Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989971340Z" level=info msg="NRI interface is disabled by configuration." Nov 6 23:17:42.990434 containerd[1724]: time="2025-11-06T23:17:42.989982340Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 6 23:17:42.990570 containerd[1724]: time="2025-11-06T23:17:42.990371100Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 6 23:17:42.990570 containerd[1724]: time="2025-11-06T23:17:42.990427500Z" level=info msg="Connect containerd service" Nov 6 23:17:42.990570 containerd[1724]: time="2025-11-06T23:17:42.990469780Z" level=info msg="using legacy CRI server" Nov 6 23:17:42.990570 containerd[1724]: time="2025-11-06T23:17:42.990477660Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 6 23:17:42.990709 containerd[1724]: time="2025-11-06T23:17:42.990614540Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 6 23:17:42.993068 containerd[1724]: time="2025-11-06T23:17:42.993032540Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993340300Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993392900Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993425900Z" level=info msg="Start subscribing containerd event" Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993462300Z" level=info msg="Start recovering state" Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993526900Z" level=info msg="Start event monitor" Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993537620Z" level=info msg="Start snapshots syncer" Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993556060Z" level=info msg="Start cni network conf syncer for default" Nov 6 23:17:42.993556 containerd[1724]: time="2025-11-06T23:17:42.993563500Z" level=info msg="Start streaming server" Nov 6 23:17:42.993712 systemd[1]: Started containerd.service - containerd container runtime. Nov 6 23:17:42.997419 containerd[1724]: time="2025-11-06T23:17:42.994835460Z" level=info msg="containerd successfully booted in 0.078148s" Nov 6 23:17:43.234072 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:43.239922 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 6 23:17:43.247858 systemd[1]: Startup finished in 639ms (kernel) + 12.646s (initrd) + 29.511s (userspace) = 42.797s. Nov 6 23:17:43.558973 (kubelet)[1870]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 6 23:17:43.955165 kubelet[1870]: E1106 23:17:43.955066 1870 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 6 23:17:43.957678 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 6 23:17:43.958060 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 6 23:17:43.958590 systemd[1]: kubelet.service: Consumed 701ms CPU time, 254.8M memory peak. Nov 6 23:17:44.159073 login[1859]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 6 23:17:44.175473 login[1860]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:44.181305 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 6 23:17:44.186001 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 6 23:17:44.193646 systemd-logind[1706]: New session 1 of user core. Nov 6 23:17:44.210799 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 6 23:17:44.217081 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 6 23:17:44.248333 (systemd)[1884]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 6 23:17:44.251010 systemd-logind[1706]: New session c1 of user core. Nov 6 23:17:44.673614 systemd[1884]: Queued start job for default target default.target. Nov 6 23:17:44.679022 systemd[1884]: Created slice app.slice - User Application Slice. Nov 6 23:17:44.679046 systemd[1884]: Reached target paths.target - Paths. Nov 6 23:17:44.679080 systemd[1884]: Reached target timers.target - Timers. Nov 6 23:17:44.682923 systemd[1884]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 6 23:17:44.692990 systemd[1884]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 6 23:17:44.693154 systemd[1884]: Reached target sockets.target - Sockets. Nov 6 23:17:44.693278 systemd[1884]: Reached target basic.target - Basic System. Nov 6 23:17:44.693308 systemd[1884]: Reached target default.target - Main User Target. Nov 6 23:17:44.693333 systemd[1884]: Startup finished in 436ms. Nov 6 23:17:44.693573 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 6 23:17:44.702288 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 6 23:17:45.130288 waagent[1857]: 2025-11-06T23:17:45.130148Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Nov 6 23:17:45.135411 waagent[1857]: 2025-11-06T23:17:45.135353Z INFO Daemon Daemon OS: flatcar 4230.2.4 Nov 6 23:17:45.139291 waagent[1857]: 2025-11-06T23:17:45.139252Z INFO Daemon Daemon Python: 3.11.11 Nov 6 23:17:45.143295 waagent[1857]: 2025-11-06T23:17:45.142959Z INFO Daemon Daemon Run daemon Nov 6 23:17:45.146230 waagent[1857]: 2025-11-06T23:17:45.146188Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.4' Nov 6 23:17:45.153507 waagent[1857]: 2025-11-06T23:17:45.153466Z INFO Daemon Daemon Using waagent for provisioning Nov 6 23:17:45.157779 waagent[1857]: 2025-11-06T23:17:45.157736Z INFO Daemon Daemon Activate resource disk Nov 6 23:17:45.159413 login[1859]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:17:45.161496 waagent[1857]: 2025-11-06T23:17:45.161453Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 6 23:17:45.166655 systemd-logind[1706]: New session 2 of user core. Nov 6 23:17:45.171963 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 6 23:17:45.173425 waagent[1857]: 2025-11-06T23:17:45.172859Z INFO Daemon Daemon Found device: None Nov 6 23:17:45.176585 waagent[1857]: 2025-11-06T23:17:45.176523Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Nov 6 23:17:45.183673 waagent[1857]: 2025-11-06T23:17:45.183614Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Nov 6 23:17:45.194000 waagent[1857]: 2025-11-06T23:17:45.193118Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 23:17:45.199970 waagent[1857]: 2025-11-06T23:17:45.199666Z INFO Daemon Daemon Running default provisioning handler Nov 6 23:17:45.209661 waagent[1857]: 2025-11-06T23:17:45.209575Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 6 23:17:45.220995 waagent[1857]: 2025-11-06T23:17:45.220937Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 6 23:17:45.229605 waagent[1857]: 2025-11-06T23:17:45.229082Z INFO Daemon Daemon cloud-init is enabled: False Nov 6 23:17:45.233278 waagent[1857]: 2025-11-06T23:17:45.233237Z INFO Daemon Daemon Copying ovf-env.xml Nov 6 23:17:45.371355 waagent[1857]: 2025-11-06T23:17:45.368329Z INFO Daemon Daemon Successfully mounted dvd Nov 6 23:17:45.401688 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 6 23:17:45.404847 waagent[1857]: 2025-11-06T23:17:45.404753Z INFO Daemon Daemon Detect protocol endpoint Nov 6 23:17:45.408949 waagent[1857]: 2025-11-06T23:17:45.408899Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 23:17:45.413520 waagent[1857]: 2025-11-06T23:17:45.413485Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 6 23:17:45.418841 waagent[1857]: 2025-11-06T23:17:45.418806Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 6 23:17:45.423125 waagent[1857]: 2025-11-06T23:17:45.423089Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 6 23:17:45.427216 waagent[1857]: 2025-11-06T23:17:45.427182Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 6 23:17:45.480685 waagent[1857]: 2025-11-06T23:17:45.480642Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 6 23:17:45.486272 waagent[1857]: 2025-11-06T23:17:45.486247Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 6 23:17:45.490511 waagent[1857]: 2025-11-06T23:17:45.490478Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 6 23:17:45.743856 waagent[1857]: 2025-11-06T23:17:45.743180Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 6 23:17:45.748856 waagent[1857]: 2025-11-06T23:17:45.748800Z INFO Daemon Daemon Forcing an update of the goal state. Nov 6 23:17:45.756808 waagent[1857]: 2025-11-06T23:17:45.756753Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 23:17:45.796462 waagent[1857]: 2025-11-06T23:17:45.796419Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 6 23:17:45.801280 waagent[1857]: 2025-11-06T23:17:45.801238Z INFO Daemon Nov 6 23:17:45.803591 waagent[1857]: 2025-11-06T23:17:45.803549Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 2a5b78f3-9e9f-4a94-8aae-b7e48b0faee0 eTag: 2674749388901666727 source: Fabric] Nov 6 23:17:45.813020 waagent[1857]: 2025-11-06T23:17:45.812977Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 6 23:17:45.818662 waagent[1857]: 2025-11-06T23:17:45.818621Z INFO Daemon Nov 6 23:17:45.820915 waagent[1857]: 2025-11-06T23:17:45.820877Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 6 23:17:45.830673 waagent[1857]: 2025-11-06T23:17:45.830641Z INFO Daemon Daemon Downloading artifacts profile blob Nov 6 23:17:45.904207 waagent[1857]: 2025-11-06T23:17:45.904120Z INFO Daemon Downloaded certificate {'thumbprint': '3D901176587AE1BA55837C6155B36FE07FA51633', 'hasPrivateKey': True} Nov 6 23:17:45.912674 waagent[1857]: 2025-11-06T23:17:45.912627Z INFO Daemon Fetch goal state completed Nov 6 23:17:45.923186 waagent[1857]: 2025-11-06T23:17:45.923151Z INFO Daemon Daemon Starting provisioning Nov 6 23:17:45.927312 waagent[1857]: 2025-11-06T23:17:45.927269Z INFO Daemon Daemon Handle ovf-env.xml. Nov 6 23:17:45.931039 waagent[1857]: 2025-11-06T23:17:45.931000Z INFO Daemon Daemon Set hostname [ci-4230.2.4-n-0cdd2482db] Nov 6 23:17:45.967789 waagent[1857]: 2025-11-06T23:17:45.965583Z INFO Daemon Daemon Publish hostname [ci-4230.2.4-n-0cdd2482db] Nov 6 23:17:45.970920 waagent[1857]: 2025-11-06T23:17:45.970872Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 6 23:17:45.975997 waagent[1857]: 2025-11-06T23:17:45.975959Z INFO Daemon Daemon Primary interface is [eth0] Nov 6 23:17:45.987319 systemd-networkd[1339]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:17:45.987895 systemd-networkd[1339]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:17:45.987942 systemd-networkd[1339]: eth0: DHCP lease lost Nov 6 23:17:45.988502 waagent[1857]: 2025-11-06T23:17:45.988421Z INFO Daemon Daemon Create user account if not exists Nov 6 23:17:45.993055 waagent[1857]: 2025-11-06T23:17:45.993009Z INFO Daemon Daemon User core already exists, skip useradd Nov 6 23:17:45.997943 waagent[1857]: 2025-11-06T23:17:45.997867Z INFO Daemon Daemon Configure sudoer Nov 6 23:17:46.001886 waagent[1857]: 2025-11-06T23:17:46.001833Z INFO Daemon Daemon Configure sshd Nov 6 23:17:46.005507 waagent[1857]: 2025-11-06T23:17:46.005455Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 6 23:17:46.015587 waagent[1857]: 2025-11-06T23:17:46.015541Z INFO Daemon Daemon Deploy ssh public key. Nov 6 23:17:46.040825 systemd-networkd[1339]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 6 23:17:47.185308 waagent[1857]: 2025-11-06T23:17:47.185244Z INFO Daemon Daemon Provisioning complete Nov 6 23:17:47.200399 waagent[1857]: 2025-11-06T23:17:47.200340Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 6 23:17:47.205642 waagent[1857]: 2025-11-06T23:17:47.205588Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 6 23:17:47.213721 waagent[1857]: 2025-11-06T23:17:47.213674Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Nov 6 23:17:47.377545 waagent[1933]: 2025-11-06T23:17:47.377400Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Nov 6 23:17:47.379033 waagent[1933]: 2025-11-06T23:17:47.378306Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.4 Nov 6 23:17:47.379033 waagent[1933]: 2025-11-06T23:17:47.378491Z INFO ExtHandler ExtHandler Python: 3.11.11 Nov 6 23:17:47.447577 waagent[1933]: 2025-11-06T23:17:47.447371Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.4; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Nov 6 23:17:47.447741 waagent[1933]: 2025-11-06T23:17:47.447706Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:17:47.447854 waagent[1933]: 2025-11-06T23:17:47.447825Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:17:47.457225 waagent[1933]: 2025-11-06T23:17:47.457166Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 23:17:47.463028 waagent[1933]: 2025-11-06T23:17:47.462987Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 6 23:17:47.463517 waagent[1933]: 2025-11-06T23:17:47.463477Z INFO ExtHandler Nov 6 23:17:47.463583 waagent[1933]: 2025-11-06T23:17:47.463556Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: c6497e34-7841-4ec9-bdee-2a9655fefd78 eTag: 2674749388901666727 source: Fabric] Nov 6 23:17:47.463890 waagent[1933]: 2025-11-06T23:17:47.463852Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 6 23:17:47.464451 waagent[1933]: 2025-11-06T23:17:47.464409Z INFO ExtHandler Nov 6 23:17:47.464508 waagent[1933]: 2025-11-06T23:17:47.464483Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 6 23:17:47.468715 waagent[1933]: 2025-11-06T23:17:47.468685Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 6 23:17:47.559749 waagent[1933]: 2025-11-06T23:17:47.559639Z INFO ExtHandler Downloaded certificate {'thumbprint': '3D901176587AE1BA55837C6155B36FE07FA51633', 'hasPrivateKey': True} Nov 6 23:17:47.561345 waagent[1933]: 2025-11-06T23:17:47.560341Z INFO ExtHandler Fetch goal state completed Nov 6 23:17:47.574000 waagent[1933]: 2025-11-06T23:17:47.573927Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1933 Nov 6 23:17:47.574795 waagent[1933]: 2025-11-06T23:17:47.574285Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 6 23:17:47.576174 waagent[1933]: 2025-11-06T23:17:47.576127Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.4', '', 'Flatcar Container Linux by Kinvolk'] Nov 6 23:17:47.576594 waagent[1933]: 2025-11-06T23:17:47.576552Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 6 23:17:48.127742 waagent[1933]: 2025-11-06T23:17:48.127332Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 6 23:17:48.127742 waagent[1933]: 2025-11-06T23:17:48.127533Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 6 23:17:48.133767 waagent[1933]: 2025-11-06T23:17:48.133736Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 6 23:17:48.140206 systemd[1]: Reload requested from client PID 1946 ('systemctl') (unit waagent.service)... Nov 6 23:17:48.140221 systemd[1]: Reloading... Nov 6 23:17:48.223978 zram_generator::config[1986]: No configuration found. Nov 6 23:17:48.332189 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:48.428438 systemd[1]: Reloading finished in 287 ms. Nov 6 23:17:48.443312 waagent[1933]: 2025-11-06T23:17:48.442921Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Nov 6 23:17:48.449449 systemd[1]: Reload requested from client PID 2040 ('systemctl') (unit waagent.service)... Nov 6 23:17:48.449462 systemd[1]: Reloading... Nov 6 23:17:48.530796 zram_generator::config[2077]: No configuration found. Nov 6 23:17:48.634483 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:17:48.731689 systemd[1]: Reloading finished in 281 ms. Nov 6 23:17:48.744716 waagent[1933]: 2025-11-06T23:17:48.743927Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 6 23:17:48.744716 waagent[1933]: 2025-11-06T23:17:48.744098Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 6 23:17:49.241072 waagent[1933]: 2025-11-06T23:17:49.240978Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 6 23:17:49.241683 waagent[1933]: 2025-11-06T23:17:49.241613Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Nov 6 23:17:49.242466 waagent[1933]: 2025-11-06T23:17:49.242391Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 6 23:17:49.242897 waagent[1933]: 2025-11-06T23:17:49.242749Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 6 23:17:49.243798 waagent[1933]: 2025-11-06T23:17:49.243107Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:17:49.243798 waagent[1933]: 2025-11-06T23:17:49.243192Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:17:49.243798 waagent[1933]: 2025-11-06T23:17:49.243386Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 6 23:17:49.243798 waagent[1933]: 2025-11-06T23:17:49.243552Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 6 23:17:49.243798 waagent[1933]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 6 23:17:49.243798 waagent[1933]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Nov 6 23:17:49.243798 waagent[1933]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 6 23:17:49.243798 waagent[1933]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:17:49.243798 waagent[1933]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:17:49.243798 waagent[1933]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:17:49.244445 waagent[1933]: 2025-11-06T23:17:49.244113Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 6 23:17:49.244445 waagent[1933]: 2025-11-06T23:17:49.244278Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 6 23:17:49.244568 waagent[1933]: 2025-11-06T23:17:49.244518Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 6 23:17:49.244667 waagent[1933]: 2025-11-06T23:17:49.244625Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 6 23:17:49.244820 waagent[1933]: 2025-11-06T23:17:49.244780Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:17:49.244913 waagent[1933]: 2025-11-06T23:17:49.244876Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 6 23:17:49.245266 waagent[1933]: 2025-11-06T23:17:49.245226Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:17:49.246368 waagent[1933]: 2025-11-06T23:17:49.246303Z INFO EnvHandler ExtHandler Configure routes Nov 6 23:17:49.246887 waagent[1933]: 2025-11-06T23:17:49.246799Z INFO EnvHandler ExtHandler Gateway:None Nov 6 23:17:49.247345 waagent[1933]: 2025-11-06T23:17:49.247305Z INFO EnvHandler ExtHandler Routes:None Nov 6 23:17:49.255409 waagent[1933]: 2025-11-06T23:17:49.253807Z INFO ExtHandler ExtHandler Nov 6 23:17:49.255409 waagent[1933]: 2025-11-06T23:17:49.253896Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 5d7b5b7d-50fe-4d83-9919-1cd52e7a0875 correlation 3761365e-34a4-483c-a804-36d08e026243 created: 2025-11-06T23:16:14.512268Z] Nov 6 23:17:49.255409 waagent[1933]: 2025-11-06T23:17:49.254231Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 6 23:17:49.255409 waagent[1933]: 2025-11-06T23:17:49.254735Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Nov 6 23:17:49.284754 waagent[1933]: 2025-11-06T23:17:49.284373Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: C1F8EAEB-A584-4AD0-800C-563909D21FB1;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Nov 6 23:17:49.390801 waagent[1933]: 2025-11-06T23:17:49.390276Z INFO MonitorHandler ExtHandler Network interfaces: Nov 6 23:17:49.390801 waagent[1933]: Executing ['ip', '-a', '-o', 'link']: Nov 6 23:17:49.390801 waagent[1933]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 6 23:17:49.390801 waagent[1933]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f9:a1:71 brd ff:ff:ff:ff:ff:ff Nov 6 23:17:49.390801 waagent[1933]: 3: enP51219s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f9:a1:71 brd ff:ff:ff:ff:ff:ff\ altname enP51219p0s2 Nov 6 23:17:49.390801 waagent[1933]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 6 23:17:49.390801 waagent[1933]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 6 23:17:49.390801 waagent[1933]: 2: eth0 inet 10.200.20.19/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 6 23:17:49.390801 waagent[1933]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 6 23:17:49.390801 waagent[1933]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 6 23:17:49.390801 waagent[1933]: 2: eth0 inet6 fe80::20d:3aff:fef9:a171/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 6 23:17:49.434660 waagent[1933]: 2025-11-06T23:17:49.434590Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Nov 6 23:17:49.434660 waagent[1933]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:49.434660 waagent[1933]: pkts bytes target prot opt in out source destination Nov 6 23:17:49.434660 waagent[1933]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:49.434660 waagent[1933]: pkts bytes target prot opt in out source destination Nov 6 23:17:49.434660 waagent[1933]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:49.434660 waagent[1933]: pkts bytes target prot opt in out source destination Nov 6 23:17:49.434660 waagent[1933]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 23:17:49.434660 waagent[1933]: 8 820 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 23:17:49.434660 waagent[1933]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 23:17:49.437828 waagent[1933]: 2025-11-06T23:17:49.437685Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 6 23:17:49.437828 waagent[1933]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:49.437828 waagent[1933]: pkts bytes target prot opt in out source destination Nov 6 23:17:49.437828 waagent[1933]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:49.437828 waagent[1933]: pkts bytes target prot opt in out source destination Nov 6 23:17:49.437828 waagent[1933]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:17:49.437828 waagent[1933]: pkts bytes target prot opt in out source destination Nov 6 23:17:49.437828 waagent[1933]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 23:17:49.437828 waagent[1933]: 11 1353 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 23:17:49.437828 waagent[1933]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 23:17:49.439198 waagent[1933]: 2025-11-06T23:17:49.438877Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 6 23:17:53.991391 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Nov 6 23:17:53.996947 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:17:54.117308 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:17:54.121457 (kubelet)[2172]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 6 23:17:54.225956 kubelet[2172]: E1106 23:17:54.225851 2172 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 6 23:17:54.228931 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 6 23:17:54.229077 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 6 23:17:54.230857 systemd[1]: kubelet.service: Consumed 140ms CPU time, 107.2M memory peak. Nov 6 23:18:00.999931 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 6 23:18:01.009067 systemd[1]: Started sshd@0-10.200.20.19:22-10.200.16.10:58042.service - OpenSSH per-connection server daemon (10.200.16.10:58042). Nov 6 23:18:01.631985 sshd[2180]: Accepted publickey for core from 10.200.16.10 port 58042 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:18:01.633271 sshd-session[2180]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:18:01.637993 systemd-logind[1706]: New session 3 of user core. Nov 6 23:18:01.646958 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 6 23:18:02.071039 systemd[1]: Started sshd@1-10.200.20.19:22-10.200.16.10:58058.service - OpenSSH per-connection server daemon (10.200.16.10:58058). Nov 6 23:18:02.564433 sshd[2185]: Accepted publickey for core from 10.200.16.10 port 58058 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:18:02.565707 sshd-session[2185]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:18:02.570174 systemd-logind[1706]: New session 4 of user core. Nov 6 23:18:02.582982 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 6 23:18:02.938232 sshd[2187]: Connection closed by 10.200.16.10 port 58058 Nov 6 23:18:02.938992 sshd-session[2185]: pam_unix(sshd:session): session closed for user core Nov 6 23:18:02.942758 systemd[1]: sshd@1-10.200.20.19:22-10.200.16.10:58058.service: Deactivated successfully. Nov 6 23:18:02.944466 systemd[1]: session-4.scope: Deactivated successfully. Nov 6 23:18:02.945902 systemd-logind[1706]: Session 4 logged out. Waiting for processes to exit. Nov 6 23:18:02.947046 systemd-logind[1706]: Removed session 4. Nov 6 23:18:03.014096 systemd[1]: Started sshd@2-10.200.20.19:22-10.200.16.10:58062.service - OpenSSH per-connection server daemon (10.200.16.10:58062). Nov 6 23:18:03.424637 sshd[2193]: Accepted publickey for core from 10.200.16.10 port 58062 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:18:03.425894 sshd-session[2193]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:18:03.430735 systemd-logind[1706]: New session 5 of user core. Nov 6 23:18:03.435934 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 6 23:18:03.729573 sshd[2195]: Connection closed by 10.200.16.10 port 58062 Nov 6 23:18:03.730276 sshd-session[2193]: pam_unix(sshd:session): session closed for user core Nov 6 23:18:03.733133 systemd-logind[1706]: Session 5 logged out. Waiting for processes to exit. Nov 6 23:18:03.733362 systemd[1]: sshd@2-10.200.20.19:22-10.200.16.10:58062.service: Deactivated successfully. Nov 6 23:18:03.735532 systemd[1]: session-5.scope: Deactivated successfully. Nov 6 23:18:03.736965 systemd-logind[1706]: Removed session 5. Nov 6 23:18:03.812969 systemd[1]: Started sshd@3-10.200.20.19:22-10.200.16.10:58076.service - OpenSSH per-connection server daemon (10.200.16.10:58076). Nov 6 23:18:04.241395 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Nov 6 23:18:04.251219 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:18:04.265799 sshd[2201]: Accepted publickey for core from 10.200.16.10 port 58076 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:18:04.266952 sshd-session[2201]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:18:04.275628 systemd-logind[1706]: New session 6 of user core. Nov 6 23:18:04.284164 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 6 23:18:04.364671 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:18:04.368641 (kubelet)[2212]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 6 23:18:04.458008 kubelet[2212]: E1106 23:18:04.457933 2212 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 6 23:18:04.460112 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 6 23:18:04.460251 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 6 23:18:04.460878 systemd[1]: kubelet.service: Consumed 133ms CPU time, 104.5M memory peak. Nov 6 23:18:04.590876 sshd[2206]: Connection closed by 10.200.16.10 port 58076 Nov 6 23:18:04.591683 sshd-session[2201]: pam_unix(sshd:session): session closed for user core Nov 6 23:18:04.595277 systemd[1]: sshd@3-10.200.20.19:22-10.200.16.10:58076.service: Deactivated successfully. Nov 6 23:18:04.597074 systemd[1]: session-6.scope: Deactivated successfully. Nov 6 23:18:04.599307 systemd-logind[1706]: Session 6 logged out. Waiting for processes to exit. Nov 6 23:18:04.600147 systemd-logind[1706]: Removed session 6. Nov 6 23:18:04.673661 systemd[1]: Started sshd@4-10.200.20.19:22-10.200.16.10:58092.service - OpenSSH per-connection server daemon (10.200.16.10:58092). Nov 6 23:18:05.127218 sshd[2224]: Accepted publickey for core from 10.200.16.10 port 58092 ssh2: RSA SHA256:HMgl6UQqizAm8waU5PerJOdlJuseh6eeBoEDdEuIWJ8 Nov 6 23:18:05.128526 sshd-session[2224]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:18:05.132726 systemd-logind[1706]: New session 7 of user core. Nov 6 23:18:05.141919 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 6 23:18:05.601021 sudo[2227]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 6 23:18:05.601296 sudo[2227]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 6 23:18:06.049862 chronyd[1708]: Selected source PHC0 Nov 6 23:18:06.113008 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:18:06.113154 systemd[1]: kubelet.service: Consumed 133ms CPU time, 104.5M memory peak. Nov 6 23:18:06.119150 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:18:06.145314 systemd[1]: Reload requested from client PID 2259 ('systemctl') (unit session-7.scope)... Nov 6 23:18:06.145332 systemd[1]: Reloading... Nov 6 23:18:06.272849 zram_generator::config[2314]: No configuration found. Nov 6 23:18:06.381204 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:18:06.481969 systemd[1]: Reloading finished in 336 ms. Nov 6 23:18:06.681228 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 6 23:18:06.681332 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 6 23:18:06.681625 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:18:06.681688 systemd[1]: kubelet.service: Consumed 80ms CPU time, 94.1M memory peak. Nov 6 23:18:06.686070 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 6 23:18:06.785719 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 6 23:18:06.796071 (kubelet)[2371]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 6 23:18:06.909028 kubelet[2371]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 6 23:18:06.909028 kubelet[2371]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 6 23:18:06.909028 kubelet[2371]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 6 23:18:06.909364 kubelet[2371]: I1106 23:18:06.909079 2371 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 6 23:18:07.475970 kubelet[2371]: I1106 23:18:07.475936 2371 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Nov 6 23:18:07.475970 kubelet[2371]: I1106 23:18:07.475961 2371 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 6 23:18:07.476249 kubelet[2371]: I1106 23:18:07.476231 2371 server.go:954] "Client rotation is on, will bootstrap in background" Nov 6 23:18:07.508502 kubelet[2371]: I1106 23:18:07.508471 2371 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 6 23:18:07.514444 kubelet[2371]: E1106 23:18:07.514294 2371 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Nov 6 23:18:07.514444 kubelet[2371]: I1106 23:18:07.514326 2371 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Nov 6 23:18:07.517849 kubelet[2371]: I1106 23:18:07.517596 2371 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 6 23:18:07.518368 kubelet[2371]: I1106 23:18:07.518334 2371 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 6 23:18:07.518640 kubelet[2371]: I1106 23:18:07.518434 2371 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.200.20.19","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 6 23:18:07.518812 kubelet[2371]: I1106 23:18:07.518797 2371 topology_manager.go:138] "Creating topology manager with none policy" Nov 6 23:18:07.518952 kubelet[2371]: I1106 23:18:07.518884 2371 container_manager_linux.go:304] "Creating device plugin manager" Nov 6 23:18:07.519103 kubelet[2371]: I1106 23:18:07.519092 2371 state_mem.go:36] "Initialized new in-memory state store" Nov 6 23:18:07.521727 kubelet[2371]: I1106 23:18:07.521484 2371 kubelet.go:446] "Attempting to sync node with API server" Nov 6 23:18:07.521727 kubelet[2371]: I1106 23:18:07.521512 2371 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 6 23:18:07.521727 kubelet[2371]: I1106 23:18:07.521531 2371 kubelet.go:352] "Adding apiserver pod source" Nov 6 23:18:07.521727 kubelet[2371]: I1106 23:18:07.521541 2371 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 6 23:18:07.523065 kubelet[2371]: E1106 23:18:07.523042 2371 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:07.523115 kubelet[2371]: E1106 23:18:07.523102 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:07.524820 kubelet[2371]: I1106 23:18:07.524803 2371 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Nov 6 23:18:07.525460 kubelet[2371]: I1106 23:18:07.525444 2371 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 6 23:18:07.525583 kubelet[2371]: W1106 23:18:07.525573 2371 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 6 23:18:07.526196 kubelet[2371]: I1106 23:18:07.526175 2371 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 6 23:18:07.526295 kubelet[2371]: I1106 23:18:07.526285 2371 server.go:1287] "Started kubelet" Nov 6 23:18:07.526799 kubelet[2371]: I1106 23:18:07.526749 2371 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Nov 6 23:18:07.528090 kubelet[2371]: I1106 23:18:07.527516 2371 server.go:479] "Adding debug handlers to kubelet server" Nov 6 23:18:07.528945 kubelet[2371]: I1106 23:18:07.528901 2371 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 6 23:18:07.529290 kubelet[2371]: I1106 23:18:07.529272 2371 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 6 23:18:07.529594 kubelet[2371]: I1106 23:18:07.529567 2371 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 6 23:18:07.533367 kubelet[2371]: E1106 23:18:07.533269 2371 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.200.20.19.18758e1dbae645f6 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.200.20.19,UID:10.200.20.19,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.200.20.19,},FirstTimestamp:2025-11-06 23:18:07.526266358 +0000 UTC m=+0.726995546,LastTimestamp:2025-11-06 23:18:07.526266358 +0000 UTC m=+0.726995546,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.200.20.19,}" Nov 6 23:18:07.536586 kubelet[2371]: I1106 23:18:07.536441 2371 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 6 23:18:07.537648 kubelet[2371]: I1106 23:18:07.537616 2371 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 6 23:18:07.537847 kubelet[2371]: E1106 23:18:07.537830 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:07.539811 kubelet[2371]: E1106 23:18:07.539788 2371 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 6 23:18:07.540458 kubelet[2371]: I1106 23:18:07.540426 2371 factory.go:221] Registration of the systemd container factory successfully Nov 6 23:18:07.540801 kubelet[2371]: I1106 23:18:07.540779 2371 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 6 23:18:07.541660 kubelet[2371]: W1106 23:18:07.541638 2371 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.200.20.19" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 6 23:18:07.542657 kubelet[2371]: E1106 23:18:07.541846 2371 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.200.20.19\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 6 23:18:07.542657 kubelet[2371]: W1106 23:18:07.541904 2371 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Nov 6 23:18:07.542657 kubelet[2371]: E1106 23:18:07.541917 2371 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 6 23:18:07.542657 kubelet[2371]: I1106 23:18:07.540463 2371 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 6 23:18:07.542657 kubelet[2371]: I1106 23:18:07.540514 2371 reconciler.go:26] "Reconciler: start to sync state" Nov 6 23:18:07.547108 kubelet[2371]: I1106 23:18:07.547086 2371 factory.go:221] Registration of the containerd container factory successfully Nov 6 23:18:07.564550 kubelet[2371]: E1106 23:18:07.564518 2371 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.200.20.19\" not found" node="10.200.20.19" Nov 6 23:18:07.565749 kubelet[2371]: I1106 23:18:07.565730 2371 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 6 23:18:07.565749 kubelet[2371]: I1106 23:18:07.565743 2371 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 6 23:18:07.565849 kubelet[2371]: I1106 23:18:07.565760 2371 state_mem.go:36] "Initialized new in-memory state store" Nov 6 23:18:07.571950 kubelet[2371]: I1106 23:18:07.571921 2371 policy_none.go:49] "None policy: Start" Nov 6 23:18:07.571950 kubelet[2371]: I1106 23:18:07.571948 2371 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 6 23:18:07.572069 kubelet[2371]: I1106 23:18:07.571966 2371 state_mem.go:35] "Initializing new in-memory state store" Nov 6 23:18:07.582583 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 6 23:18:07.596143 kubelet[2371]: I1106 23:18:07.594419 2371 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 6 23:18:07.596035 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 6 23:18:07.596744 kubelet[2371]: I1106 23:18:07.596714 2371 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 6 23:18:07.596744 kubelet[2371]: I1106 23:18:07.596740 2371 status_manager.go:227] "Starting to sync pod status with apiserver" Nov 6 23:18:07.596857 kubelet[2371]: I1106 23:18:07.596780 2371 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 6 23:18:07.596857 kubelet[2371]: I1106 23:18:07.596796 2371 kubelet.go:2382] "Starting kubelet main sync loop" Nov 6 23:18:07.596857 kubelet[2371]: E1106 23:18:07.596834 2371 kubelet.go:2406] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 6 23:18:07.605054 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 6 23:18:07.617183 kubelet[2371]: I1106 23:18:07.616652 2371 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 6 23:18:07.617183 kubelet[2371]: I1106 23:18:07.616870 2371 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 6 23:18:07.617183 kubelet[2371]: I1106 23:18:07.616882 2371 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 6 23:18:07.617183 kubelet[2371]: I1106 23:18:07.617113 2371 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 6 23:18:07.618242 kubelet[2371]: E1106 23:18:07.618207 2371 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 6 23:18:07.618242 kubelet[2371]: E1106 23:18:07.618244 2371 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.200.20.19\" not found" Nov 6 23:18:07.718078 kubelet[2371]: I1106 23:18:07.717818 2371 kubelet_node_status.go:75] "Attempting to register node" node="10.200.20.19" Nov 6 23:18:07.725262 kubelet[2371]: I1106 23:18:07.725233 2371 kubelet_node_status.go:78] "Successfully registered node" node="10.200.20.19" Nov 6 23:18:07.725359 kubelet[2371]: E1106 23:18:07.725267 2371 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.200.20.19\": node \"10.200.20.19\" not found" Nov 6 23:18:07.736783 kubelet[2371]: E1106 23:18:07.736666 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:07.824211 sudo[2227]: pam_unix(sudo:session): session closed for user root Nov 6 23:18:07.836947 kubelet[2371]: E1106 23:18:07.836906 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:07.908194 sshd[2226]: Connection closed by 10.200.16.10 port 58092 Nov 6 23:18:07.908707 sshd-session[2224]: pam_unix(sshd:session): session closed for user core Nov 6 23:18:07.911497 systemd-logind[1706]: Session 7 logged out. Waiting for processes to exit. Nov 6 23:18:07.911757 systemd[1]: sshd@4-10.200.20.19:22-10.200.16.10:58092.service: Deactivated successfully. Nov 6 23:18:07.913975 systemd[1]: session-7.scope: Deactivated successfully. Nov 6 23:18:07.914149 systemd[1]: session-7.scope: Consumed 368ms CPU time, 72M memory peak. Nov 6 23:18:07.916421 systemd-logind[1706]: Removed session 7. Nov 6 23:18:07.937379 kubelet[2371]: E1106 23:18:07.937343 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:08.038124 kubelet[2371]: E1106 23:18:08.038084 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:08.138565 kubelet[2371]: E1106 23:18:08.138537 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:08.238997 kubelet[2371]: E1106 23:18:08.238976 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:08.339592 kubelet[2371]: E1106 23:18:08.339511 2371 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.200.20.19\" not found" Nov 6 23:18:08.440361 kubelet[2371]: I1106 23:18:08.440217 2371 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 6 23:18:08.440639 containerd[1724]: time="2025-11-06T23:18:08.440597134Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 6 23:18:08.440941 kubelet[2371]: I1106 23:18:08.440800 2371 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 6 23:18:08.477624 kubelet[2371]: I1106 23:18:08.477582 2371 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 6 23:18:08.477932 kubelet[2371]: W1106 23:18:08.477837 2371 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 6 23:18:08.477932 kubelet[2371]: W1106 23:18:08.477884 2371 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Node ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 6 23:18:08.477932 kubelet[2371]: W1106 23:18:08.477885 2371 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 6 23:18:08.523267 kubelet[2371]: I1106 23:18:08.522872 2371 apiserver.go:52] "Watching apiserver" Nov 6 23:18:08.523267 kubelet[2371]: E1106 23:18:08.523228 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:08.542815 kubelet[2371]: I1106 23:18:08.542692 2371 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 6 23:18:08.543164 systemd[1]: Created slice kubepods-besteffort-podcc748a72_d53d_4a9c_ba57_20f8ba799a5d.slice - libcontainer container kubepods-besteffort-podcc748a72_d53d_4a9c_ba57_20f8ba799a5d.slice. Nov 6 23:18:08.548086 kubelet[2371]: I1106 23:18:08.547853 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/cc748a72-d53d-4a9c-ba57-20f8ba799a5d-lib-modules\") pod \"kube-proxy-tlh74\" (UID: \"cc748a72-d53d-4a9c-ba57-20f8ba799a5d\") " pod="kube-system/kube-proxy-tlh74" Nov 6 23:18:08.548086 kubelet[2371]: I1106 23:18:08.547886 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/6ff9ccea-6b80-4403-b460-cbb887a612ca-cni-plugin\") pod \"kube-flannel-ds-m2nr4\" (UID: \"6ff9ccea-6b80-4403-b460-cbb887a612ca\") " pod="kube-flannel/kube-flannel-ds-m2nr4" Nov 6 23:18:08.548086 kubelet[2371]: I1106 23:18:08.547905 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/6ff9ccea-6b80-4403-b460-cbb887a612ca-flannel-cfg\") pod \"kube-flannel-ds-m2nr4\" (UID: \"6ff9ccea-6b80-4403-b460-cbb887a612ca\") " pod="kube-flannel/kube-flannel-ds-m2nr4" Nov 6 23:18:08.548086 kubelet[2371]: I1106 23:18:08.547920 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wchjv\" (UniqueName: \"kubernetes.io/projected/6ff9ccea-6b80-4403-b460-cbb887a612ca-kube-api-access-wchjv\") pod \"kube-flannel-ds-m2nr4\" (UID: \"6ff9ccea-6b80-4403-b460-cbb887a612ca\") " pod="kube-flannel/kube-flannel-ds-m2nr4" Nov 6 23:18:08.548086 kubelet[2371]: I1106 23:18:08.547936 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/cc748a72-d53d-4a9c-ba57-20f8ba799a5d-kube-proxy\") pod \"kube-proxy-tlh74\" (UID: \"cc748a72-d53d-4a9c-ba57-20f8ba799a5d\") " pod="kube-system/kube-proxy-tlh74" Nov 6 23:18:08.548275 kubelet[2371]: I1106 23:18:08.547950 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/cc748a72-d53d-4a9c-ba57-20f8ba799a5d-xtables-lock\") pod \"kube-proxy-tlh74\" (UID: \"cc748a72-d53d-4a9c-ba57-20f8ba799a5d\") " pod="kube-system/kube-proxy-tlh74" Nov 6 23:18:08.548275 kubelet[2371]: I1106 23:18:08.547963 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/6ff9ccea-6b80-4403-b460-cbb887a612ca-run\") pod \"kube-flannel-ds-m2nr4\" (UID: \"6ff9ccea-6b80-4403-b460-cbb887a612ca\") " pod="kube-flannel/kube-flannel-ds-m2nr4" Nov 6 23:18:08.548275 kubelet[2371]: I1106 23:18:08.547978 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/6ff9ccea-6b80-4403-b460-cbb887a612ca-cni\") pod \"kube-flannel-ds-m2nr4\" (UID: \"6ff9ccea-6b80-4403-b460-cbb887a612ca\") " pod="kube-flannel/kube-flannel-ds-m2nr4" Nov 6 23:18:08.548275 kubelet[2371]: I1106 23:18:08.547994 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6ff9ccea-6b80-4403-b460-cbb887a612ca-xtables-lock\") pod \"kube-flannel-ds-m2nr4\" (UID: \"6ff9ccea-6b80-4403-b460-cbb887a612ca\") " pod="kube-flannel/kube-flannel-ds-m2nr4" Nov 6 23:18:08.548275 kubelet[2371]: I1106 23:18:08.548008 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-j8m8q\" (UniqueName: \"kubernetes.io/projected/cc748a72-d53d-4a9c-ba57-20f8ba799a5d-kube-api-access-j8m8q\") pod \"kube-proxy-tlh74\" (UID: \"cc748a72-d53d-4a9c-ba57-20f8ba799a5d\") " pod="kube-system/kube-proxy-tlh74" Nov 6 23:18:08.552607 systemd[1]: Created slice kubepods-burstable-pod6ff9ccea_6b80_4403_b460_cbb887a612ca.slice - libcontainer container kubepods-burstable-pod6ff9ccea_6b80_4403_b460_cbb887a612ca.slice. Nov 6 23:18:08.850782 containerd[1724]: time="2025-11-06T23:18:08.850475175Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tlh74,Uid:cc748a72-d53d-4a9c-ba57-20f8ba799a5d,Namespace:kube-system,Attempt:0,}" Nov 6 23:18:08.863613 containerd[1724]: time="2025-11-06T23:18:08.863570284Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-m2nr4,Uid:6ff9ccea-6b80-4403-b460-cbb887a612ca,Namespace:kube-flannel,Attempt:0,}" Nov 6 23:18:09.523813 kubelet[2371]: E1106 23:18:09.523778 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:09.591234 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3566657538.mount: Deactivated successfully. Nov 6 23:18:09.615180 containerd[1724]: time="2025-11-06T23:18:09.615129665Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:18:09.627082 containerd[1724]: time="2025-11-06T23:18:09.627038494Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Nov 6 23:18:09.630695 containerd[1724]: time="2025-11-06T23:18:09.629968652Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:18:09.633622 containerd[1724]: time="2025-11-06T23:18:09.633586568Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:18:09.635843 containerd[1724]: time="2025-11-06T23:18:09.635811366Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 6 23:18:09.639596 containerd[1724]: time="2025-11-06T23:18:09.639564083Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 6 23:18:09.640493 containerd[1724]: time="2025-11-06T23:18:09.640348123Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 789.792588ms" Nov 6 23:18:09.644800 containerd[1724]: time="2025-11-06T23:18:09.644016559Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 780.351555ms" Nov 6 23:18:10.524681 kubelet[2371]: E1106 23:18:10.524645 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:10.568521 containerd[1724]: time="2025-11-06T23:18:10.568299429Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:18:10.569217 containerd[1724]: time="2025-11-06T23:18:10.568450749Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:18:10.569217 containerd[1724]: time="2025-11-06T23:18:10.569038908Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:10.569217 containerd[1724]: time="2025-11-06T23:18:10.569117148Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:10.573464 containerd[1724]: time="2025-11-06T23:18:10.571329186Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:18:10.573464 containerd[1724]: time="2025-11-06T23:18:10.571371546Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:18:10.573464 containerd[1724]: time="2025-11-06T23:18:10.571382186Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:10.573464 containerd[1724]: time="2025-11-06T23:18:10.571435306Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:10.933946 systemd[1]: Started cri-containerd-4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c.scope - libcontainer container 4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c. Nov 6 23:18:10.936235 systemd[1]: Started cri-containerd-e81241cbc0a664498c2710f851cd4bc144363ae8a6c791f25963ae1ac51d8b6b.scope - libcontainer container e81241cbc0a664498c2710f851cd4bc144363ae8a6c791f25963ae1ac51d8b6b. Nov 6 23:18:10.969703 containerd[1724]: time="2025-11-06T23:18:10.969557877Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tlh74,Uid:cc748a72-d53d-4a9c-ba57-20f8ba799a5d,Namespace:kube-system,Attempt:0,} returns sandbox id \"e81241cbc0a664498c2710f851cd4bc144363ae8a6c791f25963ae1ac51d8b6b\"" Nov 6 23:18:10.970538 containerd[1724]: time="2025-11-06T23:18:10.970120397Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-m2nr4,Uid:6ff9ccea-6b80-4403-b460-cbb887a612ca,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c\"" Nov 6 23:18:10.972241 containerd[1724]: time="2025-11-06T23:18:10.972209635Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\"" Nov 6 23:18:11.524905 kubelet[2371]: E1106 23:18:11.524868 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:11.998155 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3241743788.mount: Deactivated successfully. Nov 6 23:18:12.355504 containerd[1724]: time="2025-11-06T23:18:12.355382862Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:12.359341 containerd[1724]: time="2025-11-06T23:18:12.359302098Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.9: active requests=0, bytes read=27417817" Nov 6 23:18:12.366752 containerd[1724]: time="2025-11-06T23:18:12.366719772Z" level=info msg="ImageCreate event name:\"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:12.372205 containerd[1724]: time="2025-11-06T23:18:12.372170847Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:12.372928 containerd[1724]: time="2025-11-06T23:18:12.372525487Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.9\" with image id \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\", repo tag \"registry.k8s.io/kube-proxy:v1.32.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\", size \"27416836\" in 1.400273892s" Nov 6 23:18:12.372928 containerd[1724]: time="2025-11-06T23:18:12.372555287Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\" returns image reference \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\"" Nov 6 23:18:12.374218 containerd[1724]: time="2025-11-06T23:18:12.374188325Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 6 23:18:12.375175 containerd[1724]: time="2025-11-06T23:18:12.375050925Z" level=info msg="CreateContainer within sandbox \"e81241cbc0a664498c2710f851cd4bc144363ae8a6c791f25963ae1ac51d8b6b\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 6 23:18:12.443844 containerd[1724]: time="2025-11-06T23:18:12.443723824Z" level=info msg="CreateContainer within sandbox \"e81241cbc0a664498c2710f851cd4bc144363ae8a6c791f25963ae1ac51d8b6b\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"9756bbf6ca706967d52605a0af17e13ce378bfd5d817a7022f635272fda73b36\"" Nov 6 23:18:12.444894 containerd[1724]: time="2025-11-06T23:18:12.444215464Z" level=info msg="StartContainer for \"9756bbf6ca706967d52605a0af17e13ce378bfd5d817a7022f635272fda73b36\"" Nov 6 23:18:12.471979 systemd[1]: Started cri-containerd-9756bbf6ca706967d52605a0af17e13ce378bfd5d817a7022f635272fda73b36.scope - libcontainer container 9756bbf6ca706967d52605a0af17e13ce378bfd5d817a7022f635272fda73b36. Nov 6 23:18:12.505476 containerd[1724]: time="2025-11-06T23:18:12.505302130Z" level=info msg="StartContainer for \"9756bbf6ca706967d52605a0af17e13ce378bfd5d817a7022f635272fda73b36\" returns successfully" Nov 6 23:18:12.525275 kubelet[2371]: E1106 23:18:12.525247 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:12.627844 kubelet[2371]: I1106 23:18:12.627699 2371 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-tlh74" podStartSLOduration=4.225773252 podStartE2EDuration="5.627683743s" podCreationTimestamp="2025-11-06 23:18:07 +0000 UTC" firstStartedPulling="2025-11-06 23:18:10.971486395 +0000 UTC m=+4.172215583" lastFinishedPulling="2025-11-06 23:18:12.373396886 +0000 UTC m=+5.574126074" observedRunningTime="2025-11-06 23:18:12.626848984 +0000 UTC m=+5.827578172" watchObservedRunningTime="2025-11-06 23:18:12.627683743 +0000 UTC m=+5.828412931" Nov 6 23:18:13.526086 kubelet[2371]: E1106 23:18:13.526045 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:14.352887 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3177862771.mount: Deactivated successfully. Nov 6 23:18:14.451806 containerd[1724]: time="2025-11-06T23:18:14.451523926Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:14.454848 containerd[1724]: time="2025-11-06T23:18:14.454790845Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Nov 6 23:18:14.458396 containerd[1724]: time="2025-11-06T23:18:14.458353363Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:14.463659 containerd[1724]: time="2025-11-06T23:18:14.463607040Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:14.464638 containerd[1724]: time="2025-11-06T23:18:14.464525000Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 2.090303035s" Nov 6 23:18:14.464638 containerd[1724]: time="2025-11-06T23:18:14.464555440Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 6 23:18:14.466754 containerd[1724]: time="2025-11-06T23:18:14.466725239Z" level=info msg="CreateContainer within sandbox \"4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 6 23:18:14.512977 containerd[1724]: time="2025-11-06T23:18:14.512897175Z" level=info msg="CreateContainer within sandbox \"4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c\"" Nov 6 23:18:14.513591 containerd[1724]: time="2025-11-06T23:18:14.513560655Z" level=info msg="StartContainer for \"7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c\"" Nov 6 23:18:14.527232 kubelet[2371]: E1106 23:18:14.527176 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:14.538909 systemd[1]: Started cri-containerd-7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c.scope - libcontainer container 7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c. Nov 6 23:18:14.557823 systemd[1]: cri-containerd-7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c.scope: Deactivated successfully. Nov 6 23:18:14.564456 containerd[1724]: time="2025-11-06T23:18:14.564355108Z" level=info msg="StartContainer for \"7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c\" returns successfully" Nov 6 23:18:15.136892 containerd[1724]: time="2025-11-06T23:18:15.136823735Z" level=info msg="shim disconnected" id=7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c namespace=k8s.io Nov 6 23:18:15.136892 containerd[1724]: time="2025-11-06T23:18:15.136887695Z" level=warning msg="cleaning up after shim disconnected" id=7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c namespace=k8s.io Nov 6 23:18:15.136892 containerd[1724]: time="2025-11-06T23:18:15.136896335Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 6 23:18:15.291742 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-7d5e6a974787c56f25db39d70a68980b5dec54e4ab1d6fe1b75a9f70b88c822c-rootfs.mount: Deactivated successfully. Nov 6 23:18:15.528017 kubelet[2371]: E1106 23:18:15.527981 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:15.623719 containerd[1724]: time="2025-11-06T23:18:15.623488045Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 6 23:18:16.528205 kubelet[2371]: E1106 23:18:16.528173 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:17.528988 kubelet[2371]: E1106 23:18:17.528951 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:18.529353 kubelet[2371]: E1106 23:18:18.529307 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:19.268069 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1638598671.mount: Deactivated successfully. Nov 6 23:18:19.529590 kubelet[2371]: E1106 23:18:19.529454 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:19.819629 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Nov 6 23:18:20.530428 kubelet[2371]: E1106 23:18:20.530386 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:21.530888 kubelet[2371]: E1106 23:18:21.530851 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:22.531165 kubelet[2371]: E1106 23:18:22.531124 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:23.531683 kubelet[2371]: E1106 23:18:23.531649 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:24.532032 kubelet[2371]: E1106 23:18:24.531970 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:25.532211 kubelet[2371]: E1106 23:18:25.532171 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:26.532541 kubelet[2371]: E1106 23:18:26.532510 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:26.933213 containerd[1724]: time="2025-11-06T23:18:26.932925885Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:26.979324 containerd[1724]: time="2025-11-06T23:18:26.979292813Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Nov 6 23:18:26.983215 containerd[1724]: time="2025-11-06T23:18:26.983189530Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:27.029783 containerd[1724]: time="2025-11-06T23:18:27.028613019Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:27.029783 containerd[1724]: time="2025-11-06T23:18:27.029691738Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 11.406170253s" Nov 6 23:18:27.029783 containerd[1724]: time="2025-11-06T23:18:27.029715978Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 6 23:18:27.032174 containerd[1724]: time="2025-11-06T23:18:27.032034096Z" level=info msg="CreateContainer within sandbox \"4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 6 23:18:27.464466 update_engine[1711]: I20251106 23:18:27.463938 1711 update_attempter.cc:509] Updating boot flags... Nov 6 23:18:27.522450 kubelet[2371]: E1106 23:18:27.522424 2371 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:27.532811 kubelet[2371]: E1106 23:18:27.532794 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:27.637577 containerd[1724]: time="2025-11-06T23:18:27.637108279Z" level=info msg="CreateContainer within sandbox \"4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8\"" Nov 6 23:18:27.637715 containerd[1724]: time="2025-11-06T23:18:27.637683959Z" level=info msg="StartContainer for \"a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8\"" Nov 6 23:18:27.663017 systemd[1]: run-containerd-runc-k8s.io-a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8-runc.BheLx2.mount: Deactivated successfully. Nov 6 23:18:27.674924 systemd[1]: Started cri-containerd-a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8.scope - libcontainer container a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8. Nov 6 23:18:27.706817 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2808) Nov 6 23:18:27.715382 systemd[1]: cri-containerd-a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8.scope: Deactivated successfully. Nov 6 23:18:27.720963 containerd[1724]: time="2025-11-06T23:18:27.720838102Z" level=warning msg="error from *cgroupsv2.Manager.EventChan" error="failed to add inotify watch for \"/sys/fs/cgroup/kubepods.slice/kubepods-burstable.slice/kubepods-burstable-pod6ff9ccea_6b80_4403_b460_cbb887a612ca.slice/cri-containerd-a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8.scope/memory.events\": no such file or directory" Nov 6 23:18:27.792273 kubelet[2371]: I1106 23:18:27.792248 2371 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 6 23:18:27.838241 containerd[1724]: time="2025-11-06T23:18:27.838196061Z" level=info msg="StartContainer for \"a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8\" returns successfully" Nov 6 23:18:28.533282 kubelet[2371]: E1106 23:18:28.533241 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:29.331967 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2807) Nov 6 23:18:28.537635 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8-rootfs.mount: Deactivated successfully. Nov 6 23:18:29.534178 kubelet[2371]: E1106 23:18:29.534137 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:30.314839 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2807) Nov 6 23:18:30.534573 kubelet[2371]: E1106 23:18:30.534542 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:31.535394 kubelet[2371]: E1106 23:18:31.535357 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:32.535506 kubelet[2371]: E1106 23:18:32.535471 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:33.536082 kubelet[2371]: E1106 23:18:33.536042 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:33.561743 systemd[1]: Created slice kubepods-besteffort-pod5bbd8455_88f5_4587_9b0a_08c01dee7c59.slice - libcontainer container kubepods-besteffort-pod5bbd8455_88f5_4587_9b0a_08c01dee7c59.slice. Nov 6 23:18:35.185855 kubelet[2371]: I1106 23:18:33.572712 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lzzsz\" (UniqueName: \"kubernetes.io/projected/5bbd8455-88f5-4587-9b0a-08c01dee7c59-kube-api-access-lzzsz\") pod \"nginx-deployment-7fcdb87857-bk8dj\" (UID: \"5bbd8455-88f5-4587-9b0a-08c01dee7c59\") " pod="default/nginx-deployment-7fcdb87857-bk8dj" Nov 6 23:18:35.185855 kubelet[2371]: E1106 23:18:34.536615 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:35.185855 kubelet[2371]: E1106 23:18:35.185631 2371 kubelet.go:2573] "Housekeeping took longer than expected" err="housekeeping took too long" expected="1s" actual="1.588s" Nov 6 23:18:35.382983 containerd[1724]: time="2025-11-06T23:18:35.382858565Z" level=info msg="shim disconnected" id=a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8 namespace=k8s.io Nov 6 23:18:35.382983 containerd[1724]: time="2025-11-06T23:18:35.382920965Z" level=warning msg="cleaning up after shim disconnected" id=a9cd35dbfc1c0f84687a4fa883298b4cd21f740a34abb0feed7965e9088fcdd8 namespace=k8s.io Nov 6 23:18:35.382983 containerd[1724]: time="2025-11-06T23:18:35.382929205Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 6 23:18:35.487062 containerd[1724]: time="2025-11-06T23:18:35.486700764Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-bk8dj,Uid:5bbd8455-88f5-4587-9b0a-08c01dee7c59,Namespace:default,Attempt:0,}" Nov 6 23:18:35.537343 kubelet[2371]: E1106 23:18:35.537303 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:35.663057 containerd[1724]: time="2025-11-06T23:18:35.662927388Z" level=info msg="CreateContainer within sandbox \"4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 6 23:18:36.213020 systemd[1]: run-netns-cni\x2df2cc1ab3\x2d7d34\x2d508f\x2d92bf\x2dba5792266e1b.mount: Deactivated successfully. Nov 6 23:18:36.213100 systemd[1]: run-containerd-io.containerd.grpc.v1.cri-sandboxes-ac528030fb838f8a27582a0fd44a3d7cc5a4b8dacc0e1c297133eff4fb2843d3-shm.mount: Deactivated successfully. Nov 6 23:18:36.399611 containerd[1724]: time="2025-11-06T23:18:36.399379737Z" level=info msg="CreateContainer within sandbox \"4fa6506064d44de210b9336cd83cd99fe06930d021a88408de067f852af2ce8c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"ba40e5e8541e0dbec8dc32a9528c021fc6b9f6cdb60998dec279c9bad00f9d30\"" Nov 6 23:18:36.400985 containerd[1724]: time="2025-11-06T23:18:36.400181456Z" level=info msg="StartContainer for \"ba40e5e8541e0dbec8dc32a9528c021fc6b9f6cdb60998dec279c9bad00f9d30\"" Nov 6 23:18:36.402352 containerd[1724]: time="2025-11-06T23:18:36.402303054Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-bk8dj,Uid:5bbd8455-88f5-4587-9b0a-08c01dee7c59,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"ac528030fb838f8a27582a0fd44a3d7cc5a4b8dacc0e1c297133eff4fb2843d3\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 6 23:18:36.402594 kubelet[2371]: E1106 23:18:36.402553 2371 log.go:32] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"ac528030fb838f8a27582a0fd44a3d7cc5a4b8dacc0e1c297133eff4fb2843d3\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 6 23:18:36.402834 kubelet[2371]: E1106 23:18:36.402619 2371 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"ac528030fb838f8a27582a0fd44a3d7cc5a4b8dacc0e1c297133eff4fb2843d3\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-7fcdb87857-bk8dj" Nov 6 23:18:36.402834 kubelet[2371]: E1106 23:18:36.402638 2371 kuberuntime_manager.go:1237] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"ac528030fb838f8a27582a0fd44a3d7cc5a4b8dacc0e1c297133eff4fb2843d3\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-7fcdb87857-bk8dj" Nov 6 23:18:36.402834 kubelet[2371]: E1106 23:18:36.402675 2371 pod_workers.go:1301] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-7fcdb87857-bk8dj_default(5bbd8455-88f5-4587-9b0a-08c01dee7c59)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-7fcdb87857-bk8dj_default(5bbd8455-88f5-4587-9b0a-08c01dee7c59)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"ac528030fb838f8a27582a0fd44a3d7cc5a4b8dacc0e1c297133eff4fb2843d3\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-7fcdb87857-bk8dj" podUID="5bbd8455-88f5-4587-9b0a-08c01dee7c59" Nov 6 23:18:36.428903 systemd[1]: Started cri-containerd-ba40e5e8541e0dbec8dc32a9528c021fc6b9f6cdb60998dec279c9bad00f9d30.scope - libcontainer container ba40e5e8541e0dbec8dc32a9528c021fc6b9f6cdb60998dec279c9bad00f9d30. Nov 6 23:18:36.457946 containerd[1724]: time="2025-11-06T23:18:36.457907171Z" level=info msg="StartContainer for \"ba40e5e8541e0dbec8dc32a9528c021fc6b9f6cdb60998dec279c9bad00f9d30\" returns successfully" Nov 6 23:18:36.538032 kubelet[2371]: E1106 23:18:36.537991 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:36.678089 kubelet[2371]: I1106 23:18:36.677873 2371 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-m2nr4" podStartSLOduration=13.619099019 podStartE2EDuration="29.677857281s" podCreationTimestamp="2025-11-06 23:18:07 +0000 UTC" firstStartedPulling="2025-11-06 23:18:10.971912755 +0000 UTC m=+4.172641903" lastFinishedPulling="2025-11-06 23:18:27.030671017 +0000 UTC m=+20.231400165" observedRunningTime="2025-11-06 23:18:36.677785321 +0000 UTC m=+29.878514509" watchObservedRunningTime="2025-11-06 23:18:36.677857281 +0000 UTC m=+29.878586469" Nov 6 23:18:37.538439 kubelet[2371]: E1106 23:18:37.538141 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:37.632066 systemd-networkd[1339]: flannel.1: Link UP Nov 6 23:18:37.632073 systemd-networkd[1339]: flannel.1: Gained carrier Nov 6 23:18:38.538420 kubelet[2371]: E1106 23:18:38.538387 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:39.041927 systemd-networkd[1339]: flannel.1: Gained IPv6LL Nov 6 23:18:39.539495 kubelet[2371]: E1106 23:18:39.539460 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:40.540538 kubelet[2371]: E1106 23:18:40.540500 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:41.541565 kubelet[2371]: E1106 23:18:41.541525 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:42.542580 kubelet[2371]: E1106 23:18:42.542528 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:43.543202 kubelet[2371]: E1106 23:18:43.543159 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:44.544264 kubelet[2371]: E1106 23:18:44.544203 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:45.544550 kubelet[2371]: E1106 23:18:45.544502 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:46.545376 kubelet[2371]: E1106 23:18:46.545323 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:46.598647 containerd[1724]: time="2025-11-06T23:18:46.598222180Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-bk8dj,Uid:5bbd8455-88f5-4587-9b0a-08c01dee7c59,Namespace:default,Attempt:0,}" Nov 6 23:18:46.647809 systemd-networkd[1339]: cni0: Link UP Nov 6 23:18:46.647818 systemd-networkd[1339]: cni0: Gained carrier Nov 6 23:18:46.651713 systemd-networkd[1339]: cni0: Lost carrier Nov 6 23:18:46.701870 systemd-networkd[1339]: veth74e5d33b: Link UP Nov 6 23:18:46.709688 kernel: cni0: port 1(veth74e5d33b) entered blocking state Nov 6 23:18:46.709990 kernel: cni0: port 1(veth74e5d33b) entered disabled state Nov 6 23:18:46.710039 kernel: veth74e5d33b: entered allmulticast mode Nov 6 23:18:46.713196 kernel: veth74e5d33b: entered promiscuous mode Nov 6 23:18:46.719207 kernel: cni0: port 1(veth74e5d33b) entered blocking state Nov 6 23:18:46.719290 kernel: cni0: port 1(veth74e5d33b) entered forwarding state Nov 6 23:18:46.726336 kernel: cni0: port 1(veth74e5d33b) entered disabled state Nov 6 23:18:46.737428 kernel: cni0: port 1(veth74e5d33b) entered blocking state Nov 6 23:18:46.737570 kernel: cni0: port 1(veth74e5d33b) entered forwarding state Nov 6 23:18:46.737861 systemd-networkd[1339]: veth74e5d33b: Gained carrier Nov 6 23:18:46.738117 systemd-networkd[1339]: cni0: Gained carrier Nov 6 23:18:46.740567 containerd[1724]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016678), "name":"cbr0", "type":"bridge"} Nov 6 23:18:46.740567 containerd[1724]: delegateAdd: netconf sent to delegate plugin: Nov 6 23:18:46.762259 containerd[1724]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-06T23:18:46.762148691Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:18:46.762756 containerd[1724]: time="2025-11-06T23:18:46.762421131Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:18:46.762756 containerd[1724]: time="2025-11-06T23:18:46.762482971Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:46.764841 containerd[1724]: time="2025-11-06T23:18:46.763081530Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:46.789983 systemd[1]: Started cri-containerd-01d515a93145efb74abe986ad4520d84e8e3e90a749182b15b905f4dc145c730.scope - libcontainer container 01d515a93145efb74abe986ad4520d84e8e3e90a749182b15b905f4dc145c730. Nov 6 23:18:46.819594 containerd[1724]: time="2025-11-06T23:18:46.819527926Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-bk8dj,Uid:5bbd8455-88f5-4587-9b0a-08c01dee7c59,Namespace:default,Attempt:0,} returns sandbox id \"01d515a93145efb74abe986ad4520d84e8e3e90a749182b15b905f4dc145c730\"" Nov 6 23:18:46.820921 containerd[1724]: time="2025-11-06T23:18:46.820888805Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 6 23:18:47.522030 kubelet[2371]: E1106 23:18:47.521982 2371 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:47.545475 kubelet[2371]: E1106 23:18:47.545434 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:47.937960 systemd-networkd[1339]: veth74e5d33b: Gained IPv6LL Nov 6 23:18:48.131068 systemd-networkd[1339]: cni0: Gained IPv6LL Nov 6 23:18:48.545640 kubelet[2371]: E1106 23:18:48.545582 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:49.338108 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2682526287.mount: Deactivated successfully. Nov 6 23:18:49.546696 kubelet[2371]: E1106 23:18:49.546627 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:50.191351 containerd[1724]: time="2025-11-06T23:18:50.191299549Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:50.195139 containerd[1724]: time="2025-11-06T23:18:50.195075186Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 6 23:18:50.198091 containerd[1724]: time="2025-11-06T23:18:50.198037944Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:50.202884 containerd[1724]: time="2025-11-06T23:18:50.202827380Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:50.203992 containerd[1724]: time="2025-11-06T23:18:50.203864219Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 3.382935655s" Nov 6 23:18:50.203992 containerd[1724]: time="2025-11-06T23:18:50.203898259Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 6 23:18:50.206534 containerd[1724]: time="2025-11-06T23:18:50.206365697Z" level=info msg="CreateContainer within sandbox \"01d515a93145efb74abe986ad4520d84e8e3e90a749182b15b905f4dc145c730\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 6 23:18:50.241405 containerd[1724]: time="2025-11-06T23:18:50.240882470Z" level=info msg="CreateContainer within sandbox \"01d515a93145efb74abe986ad4520d84e8e3e90a749182b15b905f4dc145c730\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"f7be6700f55e5c230c83dfb0169c7a3953d59decefb06d7d12f2beb760c17fd9\"" Nov 6 23:18:50.242155 containerd[1724]: time="2025-11-06T23:18:50.242068189Z" level=info msg="StartContainer for \"f7be6700f55e5c230c83dfb0169c7a3953d59decefb06d7d12f2beb760c17fd9\"" Nov 6 23:18:50.273970 systemd[1]: Started cri-containerd-f7be6700f55e5c230c83dfb0169c7a3953d59decefb06d7d12f2beb760c17fd9.scope - libcontainer container f7be6700f55e5c230c83dfb0169c7a3953d59decefb06d7d12f2beb760c17fd9. Nov 6 23:18:50.304811 containerd[1724]: time="2025-11-06T23:18:50.304509220Z" level=info msg="StartContainer for \"f7be6700f55e5c230c83dfb0169c7a3953d59decefb06d7d12f2beb760c17fd9\" returns successfully" Nov 6 23:18:50.547614 kubelet[2371]: E1106 23:18:50.547577 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:51.548570 kubelet[2371]: E1106 23:18:51.548521 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:52.549100 kubelet[2371]: E1106 23:18:52.549052 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:53.550023 kubelet[2371]: E1106 23:18:53.549971 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:54.534610 kubelet[2371]: I1106 23:18:54.533954 2371 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-bk8dj" podStartSLOduration=18.149549105 podStartE2EDuration="21.533932879s" podCreationTimestamp="2025-11-06 23:18:33 +0000 UTC" firstStartedPulling="2025-11-06 23:18:46.820440445 +0000 UTC m=+40.021169633" lastFinishedPulling="2025-11-06 23:18:50.204824219 +0000 UTC m=+43.405553407" observedRunningTime="2025-11-06 23:18:50.696765271 +0000 UTC m=+43.897494459" watchObservedRunningTime="2025-11-06 23:18:54.533932879 +0000 UTC m=+47.734662067" Nov 6 23:18:54.543309 systemd[1]: Created slice kubepods-besteffort-pod9dc29418_3066_4951_8b66_baede005a6b0.slice - libcontainer container kubepods-besteffort-pod9dc29418_3066_4951_8b66_baede005a6b0.slice. Nov 6 23:18:54.550731 kubelet[2371]: E1106 23:18:54.550662 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:54.553940 kubelet[2371]: I1106 23:18:54.553891 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nqvkc\" (UniqueName: \"kubernetes.io/projected/9dc29418-3066-4951-8b66-baede005a6b0-kube-api-access-nqvkc\") pod \"nfs-server-provisioner-0\" (UID: \"9dc29418-3066-4951-8b66-baede005a6b0\") " pod="default/nfs-server-provisioner-0" Nov 6 23:18:54.553940 kubelet[2371]: I1106 23:18:54.553940 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/9dc29418-3066-4951-8b66-baede005a6b0-data\") pod \"nfs-server-provisioner-0\" (UID: \"9dc29418-3066-4951-8b66-baede005a6b0\") " pod="default/nfs-server-provisioner-0" Nov 6 23:18:54.846838 containerd[1724]: time="2025-11-06T23:18:54.846717775Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9dc29418-3066-4951-8b66-baede005a6b0,Namespace:default,Attempt:0,}" Nov 6 23:18:54.896386 systemd-networkd[1339]: vethdac323ac: Link UP Nov 6 23:18:54.904657 kernel: cni0: port 2(vethdac323ac) entered blocking state Nov 6 23:18:54.905043 kernel: cni0: port 2(vethdac323ac) entered disabled state Nov 6 23:18:54.905061 kernel: vethdac323ac: entered allmulticast mode Nov 6 23:18:54.912118 kernel: vethdac323ac: entered promiscuous mode Nov 6 23:18:54.923809 kernel: cni0: port 2(vethdac323ac) entered blocking state Nov 6 23:18:54.923918 kernel: cni0: port 2(vethdac323ac) entered forwarding state Nov 6 23:18:54.924038 systemd-networkd[1339]: vethdac323ac: Gained carrier Nov 6 23:18:54.926271 containerd[1724]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000948e8), "name":"cbr0", "type":"bridge"} Nov 6 23:18:54.926271 containerd[1724]: delegateAdd: netconf sent to delegate plugin: Nov 6 23:18:54.948348 containerd[1724]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-06T23:18:54.948125502Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:18:54.948348 containerd[1724]: time="2025-11-06T23:18:54.948200142Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:18:54.948348 containerd[1724]: time="2025-11-06T23:18:54.948211782Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:54.948348 containerd[1724]: time="2025-11-06T23:18:54.948306262Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:18:54.969972 systemd[1]: Started cri-containerd-740fafe30a5360959b552d78c9571ba49c77e6e4f329daea7a848166ae68aa27.scope - libcontainer container 740fafe30a5360959b552d78c9571ba49c77e6e4f329daea7a848166ae68aa27. Nov 6 23:18:55.001085 containerd[1724]: time="2025-11-06T23:18:55.001038464Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9dc29418-3066-4951-8b66-baede005a6b0,Namespace:default,Attempt:0,} returns sandbox id \"740fafe30a5360959b552d78c9571ba49c77e6e4f329daea7a848166ae68aa27\"" Nov 6 23:18:55.003114 containerd[1724]: time="2025-11-06T23:18:55.003076383Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 6 23:18:55.550909 kubelet[2371]: E1106 23:18:55.550861 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:55.666154 systemd[1]: run-containerd-runc-k8s.io-740fafe30a5360959b552d78c9571ba49c77e6e4f329daea7a848166ae68aa27-runc.zQcChl.mount: Deactivated successfully. Nov 6 23:18:56.257880 systemd-networkd[1339]: vethdac323ac: Gained IPv6LL Nov 6 23:18:56.551321 kubelet[2371]: E1106 23:18:56.551153 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:57.182005 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2168805376.mount: Deactivated successfully. Nov 6 23:18:57.551907 kubelet[2371]: E1106 23:18:57.551836 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:58.552278 kubelet[2371]: E1106 23:18:58.552238 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:18:59.099816 containerd[1724]: time="2025-11-06T23:18:59.098942772Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:59.101672 containerd[1724]: time="2025-11-06T23:18:59.101586690Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 6 23:18:59.104546 containerd[1724]: time="2025-11-06T23:18:59.104486208Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:59.109202 containerd[1724]: time="2025-11-06T23:18:59.109134565Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:18:59.110441 containerd[1724]: time="2025-11-06T23:18:59.110140044Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 4.107021941s" Nov 6 23:18:59.110441 containerd[1724]: time="2025-11-06T23:18:59.110178364Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 6 23:18:59.113027 containerd[1724]: time="2025-11-06T23:18:59.112991442Z" level=info msg="CreateContainer within sandbox \"740fafe30a5360959b552d78c9571ba49c77e6e4f329daea7a848166ae68aa27\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 6 23:18:59.148121 containerd[1724]: time="2025-11-06T23:18:59.148023457Z" level=info msg="CreateContainer within sandbox \"740fafe30a5360959b552d78c9571ba49c77e6e4f329daea7a848166ae68aa27\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"6a809503b929bf00e056e4558305dacc504ff32cbf589cbaf9753d354367b0dd\"" Nov 6 23:18:59.148762 containerd[1724]: time="2025-11-06T23:18:59.148706937Z" level=info msg="StartContainer for \"6a809503b929bf00e056e4558305dacc504ff32cbf589cbaf9753d354367b0dd\"" Nov 6 23:18:59.183039 systemd[1]: Started cri-containerd-6a809503b929bf00e056e4558305dacc504ff32cbf589cbaf9753d354367b0dd.scope - libcontainer container 6a809503b929bf00e056e4558305dacc504ff32cbf589cbaf9753d354367b0dd. Nov 6 23:18:59.213797 containerd[1724]: time="2025-11-06T23:18:59.213317210Z" level=info msg="StartContainer for \"6a809503b929bf00e056e4558305dacc504ff32cbf589cbaf9753d354367b0dd\" returns successfully" Nov 6 23:18:59.553457 kubelet[2371]: E1106 23:18:59.553407 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:00.554433 kubelet[2371]: E1106 23:19:00.554395 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:01.555432 kubelet[2371]: E1106 23:19:01.555382 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:02.555702 kubelet[2371]: E1106 23:19:02.555647 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:03.556700 kubelet[2371]: E1106 23:19:03.556661 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:04.557659 kubelet[2371]: E1106 23:19:04.557611 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:05.558673 kubelet[2371]: E1106 23:19:05.558637 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:06.559160 kubelet[2371]: E1106 23:19:06.559112 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:07.521839 kubelet[2371]: E1106 23:19:07.521803 2371 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:07.559505 kubelet[2371]: E1106 23:19:07.559479 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:08.560304 kubelet[2371]: E1106 23:19:08.560267 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:08.704518 kubelet[2371]: I1106 23:19:08.704435 2371 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.595639151 podStartE2EDuration="14.704410811s" podCreationTimestamp="2025-11-06 23:18:54 +0000 UTC" firstStartedPulling="2025-11-06 23:18:55.002598903 +0000 UTC m=+48.203328091" lastFinishedPulling="2025-11-06 23:18:59.111370603 +0000 UTC m=+52.312099751" observedRunningTime="2025-11-06 23:18:59.729188961 +0000 UTC m=+52.929918109" watchObservedRunningTime="2025-11-06 23:19:08.704410811 +0000 UTC m=+61.905140039" Nov 6 23:19:08.710490 systemd[1]: Created slice kubepods-besteffort-pod844402de_d6f3_4a29_b732_bd2fc145a44d.slice - libcontainer container kubepods-besteffort-pod844402de_d6f3_4a29_b732_bd2fc145a44d.slice. Nov 6 23:19:08.815054 kubelet[2371]: I1106 23:19:08.814956 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-9c8a59eb-52c4-4c3c-b8a3-f696984020cf\" (UniqueName: \"kubernetes.io/nfs/844402de-d6f3-4a29-b732-bd2fc145a44d-pvc-9c8a59eb-52c4-4c3c-b8a3-f696984020cf\") pod \"test-pod-1\" (UID: \"844402de-d6f3-4a29-b732-bd2fc145a44d\") " pod="default/test-pod-1" Nov 6 23:19:08.815054 kubelet[2371]: I1106 23:19:08.814996 2371 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zwb6v\" (UniqueName: \"kubernetes.io/projected/844402de-d6f3-4a29-b732-bd2fc145a44d-kube-api-access-zwb6v\") pod \"test-pod-1\" (UID: \"844402de-d6f3-4a29-b732-bd2fc145a44d\") " pod="default/test-pod-1" Nov 6 23:19:09.560593 kubelet[2371]: E1106 23:19:09.560554 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:10.561086 kubelet[2371]: E1106 23:19:10.561047 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:10.604821 kernel: FS-Cache: Loaded Nov 6 23:19:11.070339 kernel: RPC: Registered named UNIX socket transport module. Nov 6 23:19:11.070485 kernel: RPC: Registered udp transport module. Nov 6 23:19:11.070511 kernel: RPC: Registered tcp transport module. Nov 6 23:19:11.076951 kernel: RPC: Registered tcp-with-tls transport module. Nov 6 23:19:11.077013 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 6 23:19:11.561595 kubelet[2371]: E1106 23:19:11.561561 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:11.712036 kernel: NFS: Registering the id_resolver key type Nov 6 23:19:11.712179 kernel: Key type id_resolver registered Nov 6 23:19:11.712200 kernel: Key type id_legacy registered Nov 6 23:19:12.449814 nfsidmap[3631]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.4-n-0cdd2482db' Nov 6 23:19:12.454186 nfsidmap[3633]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.4-n-0cdd2482db' Nov 6 23:19:12.562636 kubelet[2371]: E1106 23:19:12.562592 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:12.688862 containerd[1724]: time="2025-11-06T23:19:12.613391259Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:844402de-d6f3-4a29-b732-bd2fc145a44d,Namespace:default,Attempt:0,}" Nov 6 23:19:13.563002 kubelet[2371]: E1106 23:19:13.562966 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:14.395861 systemd-networkd[1339]: vethf5b25952: Link UP Nov 6 23:19:14.401703 kernel: cni0: port 3(vethf5b25952) entered blocking state Nov 6 23:19:14.401932 kernel: cni0: port 3(vethf5b25952) entered disabled state Nov 6 23:19:14.401958 kernel: vethf5b25952: entered allmulticast mode Nov 6 23:19:14.407786 kernel: vethf5b25952: entered promiscuous mode Nov 6 23:19:14.418898 kernel: cni0: port 3(vethf5b25952) entered blocking state Nov 6 23:19:14.418974 kernel: cni0: port 3(vethf5b25952) entered forwarding state Nov 6 23:19:14.419218 systemd-networkd[1339]: vethf5b25952: Gained carrier Nov 6 23:19:14.421347 containerd[1724]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Nov 6 23:19:14.421347 containerd[1724]: delegateAdd: netconf sent to delegate plugin: Nov 6 23:19:14.451912 containerd[1724]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-06T23:19:14.451808544Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 6 23:19:14.451912 containerd[1724]: time="2025-11-06T23:19:14.451871264Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 6 23:19:14.451912 containerd[1724]: time="2025-11-06T23:19:14.451886904Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:19:14.452421 containerd[1724]: time="2025-11-06T23:19:14.451962904Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 6 23:19:14.468208 systemd[1]: run-containerd-runc-k8s.io-28e2451e3ada15b896d7b873f203c0a3e8adf5c7ea278f0a5e13dbf349ad51e3-runc.C3joN6.mount: Deactivated successfully. Nov 6 23:19:14.477973 systemd[1]: Started cri-containerd-28e2451e3ada15b896d7b873f203c0a3e8adf5c7ea278f0a5e13dbf349ad51e3.scope - libcontainer container 28e2451e3ada15b896d7b873f203c0a3e8adf5c7ea278f0a5e13dbf349ad51e3. Nov 6 23:19:14.505704 containerd[1724]: time="2025-11-06T23:19:14.505671742Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:844402de-d6f3-4a29-b732-bd2fc145a44d,Namespace:default,Attempt:0,} returns sandbox id \"28e2451e3ada15b896d7b873f203c0a3e8adf5c7ea278f0a5e13dbf349ad51e3\"" Nov 6 23:19:14.507285 containerd[1724]: time="2025-11-06T23:19:14.507027581Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 6 23:19:14.563957 kubelet[2371]: E1106 23:19:14.563909 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:15.547462 containerd[1724]: time="2025-11-06T23:19:15.547414889Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 6 23:19:15.565246 kubelet[2371]: E1106 23:19:15.565210 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:15.591095 containerd[1724]: time="2025-11-06T23:19:15.591023255Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 6 23:19:15.594086 containerd[1724]: time="2025-11-06T23:19:15.593969732Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 1.086911951s" Nov 6 23:19:15.594086 containerd[1724]: time="2025-11-06T23:19:15.594002652Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 6 23:19:15.596136 containerd[1724]: time="2025-11-06T23:19:15.596032291Z" level=info msg="CreateContainer within sandbox \"28e2451e3ada15b896d7b873f203c0a3e8adf5c7ea278f0a5e13dbf349ad51e3\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 6 23:19:15.947448 containerd[1724]: time="2025-11-06T23:19:15.947331897Z" level=info msg="CreateContainer within sandbox \"28e2451e3ada15b896d7b873f203c0a3e8adf5c7ea278f0a5e13dbf349ad51e3\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"452c5d684460471f81018649d2ddf5d5660ce46259c70cae1437c9374293ec47\"" Nov 6 23:19:15.949300 containerd[1724]: time="2025-11-06T23:19:15.948384496Z" level=info msg="StartContainer for \"452c5d684460471f81018649d2ddf5d5660ce46259c70cae1437c9374293ec47\"" Nov 6 23:19:15.972923 systemd[1]: Started cri-containerd-452c5d684460471f81018649d2ddf5d5660ce46259c70cae1437c9374293ec47.scope - libcontainer container 452c5d684460471f81018649d2ddf5d5660ce46259c70cae1437c9374293ec47. Nov 6 23:19:15.997251 containerd[1724]: time="2025-11-06T23:19:15.997129978Z" level=info msg="StartContainer for \"452c5d684460471f81018649d2ddf5d5660ce46259c70cae1437c9374293ec47\" returns successfully" Nov 6 23:19:16.161917 systemd-networkd[1339]: vethf5b25952: Gained IPv6LL Nov 6 23:19:16.565603 kubelet[2371]: E1106 23:19:16.565552 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:17.565727 kubelet[2371]: E1106 23:19:17.565675 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:18.566069 kubelet[2371]: E1106 23:19:18.566030 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:19.566424 kubelet[2371]: E1106 23:19:19.566385 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:20.566508 kubelet[2371]: E1106 23:19:20.566476 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:21.567126 kubelet[2371]: E1106 23:19:21.567094 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:22.567879 kubelet[2371]: E1106 23:19:22.567848 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 6 23:19:23.568122 kubelet[2371]: E1106 23:19:23.568088 2371 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"