Aug 5 22:24:45.926533 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Aug 5 22:24:45.926556 kernel: Linux version 6.6.43-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT Mon Aug 5 20:37:57 -00 2024 Aug 5 22:24:45.926566 kernel: KASLR enabled Aug 5 22:24:45.926571 kernel: efi: EFI v2.7 by EDK II Aug 5 22:24:45.926577 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb900018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Aug 5 22:24:45.926583 kernel: random: crng init done Aug 5 22:24:45.926590 kernel: ACPI: Early table checksum verification disabled Aug 5 22:24:45.926596 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Aug 5 22:24:45.926602 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Aug 5 22:24:45.926609 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926616 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926622 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926627 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926634 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926641 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926649 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926655 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926661 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:24:45.926668 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Aug 5 22:24:45.926674 kernel: NUMA: Failed to initialise from firmware Aug 5 22:24:45.926680 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 22:24:45.926687 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Aug 5 22:24:45.926693 kernel: Zone ranges: Aug 5 22:24:45.926700 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 22:24:45.926706 kernel: DMA32 empty Aug 5 22:24:45.926714 kernel: Normal empty Aug 5 22:24:45.926720 kernel: Movable zone start for each node Aug 5 22:24:45.926726 kernel: Early memory node ranges Aug 5 22:24:45.926733 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Aug 5 22:24:45.926739 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Aug 5 22:24:45.926746 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Aug 5 22:24:45.926752 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Aug 5 22:24:45.926759 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Aug 5 22:24:45.926765 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Aug 5 22:24:45.926771 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Aug 5 22:24:45.926777 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 22:24:45.926795 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Aug 5 22:24:45.926804 kernel: psci: probing for conduit method from ACPI. Aug 5 22:24:45.926810 kernel: psci: PSCIv1.1 detected in firmware. Aug 5 22:24:45.926817 kernel: psci: Using standard PSCI v0.2 function IDs Aug 5 22:24:45.926826 kernel: psci: Trusted OS migration not required Aug 5 22:24:45.926833 kernel: psci: SMC Calling Convention v1.1 Aug 5 22:24:45.926840 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Aug 5 22:24:45.926848 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Aug 5 22:24:45.926855 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Aug 5 22:24:45.926862 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Aug 5 22:24:45.926869 kernel: Detected PIPT I-cache on CPU0 Aug 5 22:24:45.926875 kernel: CPU features: detected: GIC system register CPU interface Aug 5 22:24:45.926882 kernel: CPU features: detected: Hardware dirty bit management Aug 5 22:24:45.926889 kernel: CPU features: detected: Spectre-v4 Aug 5 22:24:45.926895 kernel: CPU features: detected: Spectre-BHB Aug 5 22:24:45.926902 kernel: CPU features: kernel page table isolation forced ON by KASLR Aug 5 22:24:45.926909 kernel: CPU features: detected: Kernel page table isolation (KPTI) Aug 5 22:24:45.926917 kernel: CPU features: detected: ARM erratum 1418040 Aug 5 22:24:45.926924 kernel: alternatives: applying boot alternatives Aug 5 22:24:45.926932 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=4052403b8e39e55d48e6afcca927358798017aa0d33c868bc3038260a8d9be90 Aug 5 22:24:45.926939 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 5 22:24:45.926945 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 5 22:24:45.926952 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 5 22:24:45.926959 kernel: Fallback order for Node 0: 0 Aug 5 22:24:45.926966 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Aug 5 22:24:45.926973 kernel: Policy zone: DMA Aug 5 22:24:45.926979 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 5 22:24:45.926986 kernel: software IO TLB: area num 4. Aug 5 22:24:45.926994 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Aug 5 22:24:45.927001 kernel: Memory: 2386852K/2572288K available (10240K kernel code, 2182K rwdata, 8072K rodata, 39040K init, 897K bss, 185436K reserved, 0K cma-reserved) Aug 5 22:24:45.927008 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 5 22:24:45.927015 kernel: trace event string verifier disabled Aug 5 22:24:45.927022 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 5 22:24:45.927029 kernel: rcu: RCU event tracing is enabled. Aug 5 22:24:45.927036 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Aug 5 22:24:45.927043 kernel: Trampoline variant of Tasks RCU enabled. Aug 5 22:24:45.927050 kernel: Tracing variant of Tasks RCU enabled. Aug 5 22:24:45.927057 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 5 22:24:45.927064 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 5 22:24:45.927071 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Aug 5 22:24:45.927079 kernel: GICv3: 256 SPIs implemented Aug 5 22:24:45.927086 kernel: GICv3: 0 Extended SPIs implemented Aug 5 22:24:45.927093 kernel: Root IRQ handler: gic_handle_irq Aug 5 22:24:45.927099 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Aug 5 22:24:45.927119 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Aug 5 22:24:45.927126 kernel: ITS [mem 0x08080000-0x0809ffff] Aug 5 22:24:45.927133 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Aug 5 22:24:45.927141 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Aug 5 22:24:45.927148 kernel: GICv3: using LPI property table @0x00000000400f0000 Aug 5 22:24:45.927155 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Aug 5 22:24:45.927161 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 5 22:24:45.927170 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:24:45.927176 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Aug 5 22:24:45.927183 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Aug 5 22:24:45.927190 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Aug 5 22:24:45.927197 kernel: arm-pv: using stolen time PV Aug 5 22:24:45.927204 kernel: Console: colour dummy device 80x25 Aug 5 22:24:45.927212 kernel: ACPI: Core revision 20230628 Aug 5 22:24:45.927219 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Aug 5 22:24:45.927226 kernel: pid_max: default: 32768 minimum: 301 Aug 5 22:24:45.927234 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Aug 5 22:24:45.927242 kernel: SELinux: Initializing. Aug 5 22:24:45.927249 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 22:24:45.927256 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 22:24:45.927263 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:24:45.927270 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:24:45.927284 kernel: rcu: Hierarchical SRCU implementation. Aug 5 22:24:45.927291 kernel: rcu: Max phase no-delay instances is 400. Aug 5 22:24:45.927298 kernel: Platform MSI: ITS@0x8080000 domain created Aug 5 22:24:45.927305 kernel: PCI/MSI: ITS@0x8080000 domain created Aug 5 22:24:45.927314 kernel: Remapping and enabling EFI services. Aug 5 22:24:45.927321 kernel: smp: Bringing up secondary CPUs ... Aug 5 22:24:45.927328 kernel: Detected PIPT I-cache on CPU1 Aug 5 22:24:45.927335 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Aug 5 22:24:45.927342 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Aug 5 22:24:45.927349 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:24:45.927356 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Aug 5 22:24:45.927363 kernel: Detected PIPT I-cache on CPU2 Aug 5 22:24:45.927370 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Aug 5 22:24:45.927377 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Aug 5 22:24:45.927385 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:24:45.927392 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Aug 5 22:24:45.927405 kernel: Detected PIPT I-cache on CPU3 Aug 5 22:24:45.927413 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Aug 5 22:24:45.927421 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Aug 5 22:24:45.927428 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:24:45.927435 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Aug 5 22:24:45.927443 kernel: smp: Brought up 1 node, 4 CPUs Aug 5 22:24:45.927450 kernel: SMP: Total of 4 processors activated. Aug 5 22:24:45.927459 kernel: CPU features: detected: 32-bit EL0 Support Aug 5 22:24:45.927466 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Aug 5 22:24:45.927474 kernel: CPU features: detected: Common not Private translations Aug 5 22:24:45.927492 kernel: CPU features: detected: CRC32 instructions Aug 5 22:24:45.927500 kernel: CPU features: detected: Enhanced Virtualization Traps Aug 5 22:24:45.927507 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Aug 5 22:24:45.927515 kernel: CPU features: detected: LSE atomic instructions Aug 5 22:24:45.927522 kernel: CPU features: detected: Privileged Access Never Aug 5 22:24:45.927531 kernel: CPU features: detected: RAS Extension Support Aug 5 22:24:45.927539 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Aug 5 22:24:45.927546 kernel: CPU: All CPU(s) started at EL1 Aug 5 22:24:45.927554 kernel: alternatives: applying system-wide alternatives Aug 5 22:24:45.927562 kernel: devtmpfs: initialized Aug 5 22:24:45.927569 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 5 22:24:45.927577 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 5 22:24:45.927585 kernel: pinctrl core: initialized pinctrl subsystem Aug 5 22:24:45.927592 kernel: SMBIOS 3.0.0 present. Aug 5 22:24:45.927601 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Aug 5 22:24:45.927609 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 5 22:24:45.927617 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Aug 5 22:24:45.927624 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Aug 5 22:24:45.927632 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Aug 5 22:24:45.927640 kernel: audit: initializing netlink subsys (disabled) Aug 5 22:24:45.927647 kernel: audit: type=2000 audit(0.025:1): state=initialized audit_enabled=0 res=1 Aug 5 22:24:45.927655 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 5 22:24:45.927662 kernel: cpuidle: using governor menu Aug 5 22:24:45.927671 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Aug 5 22:24:45.927679 kernel: ASID allocator initialised with 32768 entries Aug 5 22:24:45.927686 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 5 22:24:45.927694 kernel: Serial: AMBA PL011 UART driver Aug 5 22:24:45.927701 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Aug 5 22:24:45.927709 kernel: Modules: 0 pages in range for non-PLT usage Aug 5 22:24:45.927717 kernel: Modules: 509120 pages in range for PLT usage Aug 5 22:24:45.927724 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 5 22:24:45.927732 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Aug 5 22:24:45.927741 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Aug 5 22:24:45.927749 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Aug 5 22:24:45.927756 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 5 22:24:45.927764 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Aug 5 22:24:45.927771 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Aug 5 22:24:45.927779 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Aug 5 22:24:45.927791 kernel: ACPI: Added _OSI(Module Device) Aug 5 22:24:45.927799 kernel: ACPI: Added _OSI(Processor Device) Aug 5 22:24:45.927807 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 5 22:24:45.927817 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 5 22:24:45.927824 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 5 22:24:45.927832 kernel: ACPI: Interpreter enabled Aug 5 22:24:45.927839 kernel: ACPI: Using GIC for interrupt routing Aug 5 22:24:45.927847 kernel: ACPI: MCFG table detected, 1 entries Aug 5 22:24:45.927854 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Aug 5 22:24:45.927862 kernel: printk: console [ttyAMA0] enabled Aug 5 22:24:45.927870 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 5 22:24:45.928007 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 5 22:24:45.928091 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Aug 5 22:24:45.928159 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Aug 5 22:24:45.928226 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Aug 5 22:24:45.928300 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Aug 5 22:24:45.928311 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Aug 5 22:24:45.928319 kernel: PCI host bridge to bus 0000:00 Aug 5 22:24:45.928394 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Aug 5 22:24:45.928460 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Aug 5 22:24:45.928523 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Aug 5 22:24:45.928585 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 5 22:24:45.928677 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Aug 5 22:24:45.928757 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Aug 5 22:24:45.928840 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Aug 5 22:24:45.928916 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Aug 5 22:24:45.928986 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Aug 5 22:24:45.929055 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Aug 5 22:24:45.929124 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Aug 5 22:24:45.929194 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Aug 5 22:24:45.929257 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Aug 5 22:24:45.929326 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Aug 5 22:24:45.929388 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Aug 5 22:24:45.929398 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Aug 5 22:24:45.929406 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Aug 5 22:24:45.929414 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Aug 5 22:24:45.929421 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Aug 5 22:24:45.929428 kernel: iommu: Default domain type: Translated Aug 5 22:24:45.929436 kernel: iommu: DMA domain TLB invalidation policy: strict mode Aug 5 22:24:45.929443 kernel: efivars: Registered efivars operations Aug 5 22:24:45.929450 kernel: vgaarb: loaded Aug 5 22:24:45.929460 kernel: clocksource: Switched to clocksource arch_sys_counter Aug 5 22:24:45.929467 kernel: VFS: Disk quotas dquot_6.6.0 Aug 5 22:24:45.929474 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 5 22:24:45.929481 kernel: pnp: PnP ACPI init Aug 5 22:24:45.929552 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Aug 5 22:24:45.929563 kernel: pnp: PnP ACPI: found 1 devices Aug 5 22:24:45.929570 kernel: NET: Registered PF_INET protocol family Aug 5 22:24:45.929577 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 5 22:24:45.929587 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 5 22:24:45.929595 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 5 22:24:45.929602 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 5 22:24:45.929610 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 5 22:24:45.929617 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 5 22:24:45.929625 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 22:24:45.929632 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 22:24:45.929640 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 5 22:24:45.929647 kernel: PCI: CLS 0 bytes, default 64 Aug 5 22:24:45.929656 kernel: kvm [1]: HYP mode not available Aug 5 22:24:45.929663 kernel: Initialise system trusted keyrings Aug 5 22:24:45.929670 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 5 22:24:45.929678 kernel: Key type asymmetric registered Aug 5 22:24:45.929685 kernel: Asymmetric key parser 'x509' registered Aug 5 22:24:45.929693 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 5 22:24:45.929700 kernel: io scheduler mq-deadline registered Aug 5 22:24:45.929707 kernel: io scheduler kyber registered Aug 5 22:24:45.929715 kernel: io scheduler bfq registered Aug 5 22:24:45.929724 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 5 22:24:45.929731 kernel: ACPI: button: Power Button [PWRB] Aug 5 22:24:45.929739 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Aug 5 22:24:45.929884 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Aug 5 22:24:45.929897 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 5 22:24:45.929905 kernel: thunder_xcv, ver 1.0 Aug 5 22:24:45.929912 kernel: thunder_bgx, ver 1.0 Aug 5 22:24:45.929919 kernel: nicpf, ver 1.0 Aug 5 22:24:45.929927 kernel: nicvf, ver 1.0 Aug 5 22:24:45.930012 kernel: rtc-efi rtc-efi.0: registered as rtc0 Aug 5 22:24:45.930078 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-08-05T22:24:45 UTC (1722896685) Aug 5 22:24:45.930088 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 5 22:24:45.930096 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Aug 5 22:24:45.930104 kernel: watchdog: Delayed init of the lockup detector failed: -19 Aug 5 22:24:45.930111 kernel: watchdog: Hard watchdog permanently disabled Aug 5 22:24:45.930118 kernel: NET: Registered PF_INET6 protocol family Aug 5 22:24:45.930126 kernel: Segment Routing with IPv6 Aug 5 22:24:45.930135 kernel: In-situ OAM (IOAM) with IPv6 Aug 5 22:24:45.930143 kernel: NET: Registered PF_PACKET protocol family Aug 5 22:24:45.930150 kernel: Key type dns_resolver registered Aug 5 22:24:45.930157 kernel: registered taskstats version 1 Aug 5 22:24:45.930164 kernel: Loading compiled-in X.509 certificates Aug 5 22:24:45.930172 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.43-flatcar: 99cab5c9e2f0f3a5ca972c2df7b3d6ed64d627d4' Aug 5 22:24:45.930179 kernel: Key type .fscrypt registered Aug 5 22:24:45.930186 kernel: Key type fscrypt-provisioning registered Aug 5 22:24:45.930193 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 5 22:24:45.930202 kernel: ima: Allocated hash algorithm: sha1 Aug 5 22:24:45.930209 kernel: ima: No architecture policies found Aug 5 22:24:45.930216 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Aug 5 22:24:45.930224 kernel: clk: Disabling unused clocks Aug 5 22:24:45.930231 kernel: Freeing unused kernel memory: 39040K Aug 5 22:24:45.930238 kernel: Run /init as init process Aug 5 22:24:45.930246 kernel: with arguments: Aug 5 22:24:45.930253 kernel: /init Aug 5 22:24:45.930261 kernel: with environment: Aug 5 22:24:45.930270 kernel: HOME=/ Aug 5 22:24:45.930285 kernel: TERM=linux Aug 5 22:24:45.930292 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 5 22:24:45.930301 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 5 22:24:45.930311 systemd[1]: Detected virtualization kvm. Aug 5 22:24:45.930319 systemd[1]: Detected architecture arm64. Aug 5 22:24:45.930326 systemd[1]: Running in initrd. Aug 5 22:24:45.930334 systemd[1]: No hostname configured, using default hostname. Aug 5 22:24:45.930344 systemd[1]: Hostname set to . Aug 5 22:24:45.930352 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:24:45.930360 systemd[1]: Queued start job for default target initrd.target. Aug 5 22:24:45.930368 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:24:45.930376 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:24:45.930384 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 5 22:24:45.930392 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:24:45.930400 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 5 22:24:45.930410 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 5 22:24:45.930419 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 5 22:24:45.930428 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 5 22:24:45.930435 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:24:45.930443 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:24:45.930451 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:24:45.930460 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:24:45.930468 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:24:45.930476 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:24:45.930484 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:24:45.930492 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:24:45.930500 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 5 22:24:45.930508 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 5 22:24:45.930516 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:24:45.930524 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:24:45.930533 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:24:45.930541 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:24:45.930549 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 5 22:24:45.930557 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:24:45.930565 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 5 22:24:45.930573 systemd[1]: Starting systemd-fsck-usr.service... Aug 5 22:24:45.930580 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:24:45.930588 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:24:45.930596 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:24:45.930606 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 5 22:24:45.930614 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:24:45.930621 systemd[1]: Finished systemd-fsck-usr.service. Aug 5 22:24:45.930630 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 22:24:45.930640 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:24:45.930648 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:24:45.930656 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 5 22:24:45.930663 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:24:45.930688 systemd-journald[239]: Collecting audit messages is disabled. Aug 5 22:24:45.930709 kernel: Bridge firewalling registered Aug 5 22:24:45.930717 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:24:45.930725 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:24:45.930733 systemd-journald[239]: Journal started Aug 5 22:24:45.930752 systemd-journald[239]: Runtime Journal (/run/log/journal/4e599e2d85d04327852b4af9ee86103f) is 5.9M, max 47.3M, 41.4M free. Aug 5 22:24:45.907764 systemd-modules-load[240]: Inserted module 'overlay' Aug 5 22:24:45.932543 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:24:45.925720 systemd-modules-load[240]: Inserted module 'br_netfilter' Aug 5 22:24:45.933631 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:24:45.936544 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:24:45.938760 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:24:45.947508 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:24:45.951845 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:24:45.954544 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:24:45.957060 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 5 22:24:45.959422 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:24:45.971981 dracut-cmdline[278]: dracut-dracut-053 Aug 5 22:24:45.974390 dracut-cmdline[278]: 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=4052403b8e39e55d48e6afcca927358798017aa0d33c868bc3038260a8d9be90 Aug 5 22:24:45.988128 systemd-resolved[279]: Positive Trust Anchors: Aug 5 22:24:45.988147 systemd-resolved[279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:24:45.988178 systemd-resolved[279]: 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 corp home internal intranet lan local private test Aug 5 22:24:45.993732 systemd-resolved[279]: Defaulting to hostname 'linux'. Aug 5 22:24:45.994726 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:24:45.997365 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:24:46.049820 kernel: SCSI subsystem initialized Aug 5 22:24:46.054811 kernel: Loading iSCSI transport class v2.0-870. Aug 5 22:24:46.063817 kernel: iscsi: registered transport (tcp) Aug 5 22:24:46.077188 kernel: iscsi: registered transport (qla4xxx) Aug 5 22:24:46.077206 kernel: QLogic iSCSI HBA Driver Aug 5 22:24:46.124854 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 5 22:24:46.136923 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 5 22:24:46.160989 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 5 22:24:46.161029 kernel: device-mapper: uevent: version 1.0.3 Aug 5 22:24:46.162810 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 5 22:24:46.208813 kernel: raid6: neonx8 gen() 15755 MB/s Aug 5 22:24:46.225806 kernel: raid6: neonx4 gen() 15572 MB/s Aug 5 22:24:46.242806 kernel: raid6: neonx2 gen() 13224 MB/s Aug 5 22:24:46.259797 kernel: raid6: neonx1 gen() 10489 MB/s Aug 5 22:24:46.276800 kernel: raid6: int64x8 gen() 6955 MB/s Aug 5 22:24:46.293810 kernel: raid6: int64x4 gen() 7350 MB/s Aug 5 22:24:46.310805 kernel: raid6: int64x2 gen() 6127 MB/s Aug 5 22:24:46.327892 kernel: raid6: int64x1 gen() 5050 MB/s Aug 5 22:24:46.327907 kernel: raid6: using algorithm neonx8 gen() 15755 MB/s Aug 5 22:24:46.345805 kernel: raid6: .... xor() 11920 MB/s, rmw enabled Aug 5 22:24:46.345829 kernel: raid6: using neon recovery algorithm Aug 5 22:24:46.350802 kernel: xor: measuring software checksum speed Aug 5 22:24:46.351799 kernel: 8regs : 19844 MB/sec Aug 5 22:24:46.352800 kernel: 32regs : 19640 MB/sec Aug 5 22:24:46.352812 kernel: arm64_neon : 27161 MB/sec Aug 5 22:24:46.353921 kernel: xor: using function: arm64_neon (27161 MB/sec) Aug 5 22:24:46.407807 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 5 22:24:46.419852 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:24:46.428943 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:24:46.441197 systemd-udevd[461]: Using default interface naming scheme 'v255'. Aug 5 22:24:46.444322 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:24:46.447012 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 5 22:24:46.462074 dracut-pre-trigger[468]: rd.md=0: removing MD RAID activation Aug 5 22:24:46.488528 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:24:46.505971 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:24:46.544428 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:24:46.553930 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 5 22:24:46.569016 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 5 22:24:46.570546 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:24:46.572303 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:24:46.573774 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:24:46.584963 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 5 22:24:46.590805 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Aug 5 22:24:46.605304 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Aug 5 22:24:46.605424 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 5 22:24:46.605439 kernel: GPT:9289727 != 19775487 Aug 5 22:24:46.605450 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 5 22:24:46.605462 kernel: GPT:9289727 != 19775487 Aug 5 22:24:46.605481 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 5 22:24:46.605492 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:24:46.596124 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:24:46.609160 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:24:46.609285 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:24:46.611350 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:24:46.614970 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:24:46.615118 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:24:46.619512 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:24:46.625706 kernel: BTRFS: device fsid 278882ec-4175-45f0-a12b-7fddc0d6d9a3 devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (509) Aug 5 22:24:46.625737 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (521) Aug 5 22:24:46.631016 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:24:46.641704 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 5 22:24:46.643113 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:24:46.654054 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 5 22:24:46.657912 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 5 22:24:46.659176 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 5 22:24:46.665296 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:24:46.685931 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 5 22:24:46.687723 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:24:46.693711 disk-uuid[550]: Primary Header is updated. Aug 5 22:24:46.693711 disk-uuid[550]: Secondary Entries is updated. Aug 5 22:24:46.693711 disk-uuid[550]: Secondary Header is updated. Aug 5 22:24:46.697822 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:24:46.711364 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:24:47.706481 disk-uuid[551]: The operation has completed successfully. Aug 5 22:24:47.707797 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:24:47.727915 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 5 22:24:47.728027 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 5 22:24:47.743932 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 5 22:24:47.746625 sh[570]: Success Aug 5 22:24:47.759816 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Aug 5 22:24:47.785816 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 5 22:24:47.802051 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 5 22:24:47.804815 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 5 22:24:47.815807 kernel: BTRFS info (device dm-0): first mount of filesystem 278882ec-4175-45f0-a12b-7fddc0d6d9a3 Aug 5 22:24:47.815851 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:24:47.815871 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 5 22:24:47.815890 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 5 22:24:47.817144 kernel: BTRFS info (device dm-0): using free space tree Aug 5 22:24:47.820305 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 5 22:24:47.821583 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 5 22:24:47.822293 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 5 22:24:47.824858 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 5 22:24:47.835405 kernel: BTRFS info (device vda6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:24:47.835439 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:24:47.835450 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:24:47.837807 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:24:47.844777 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 5 22:24:47.846588 kernel: BTRFS info (device vda6): last unmount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:24:47.852386 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 5 22:24:47.857925 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 5 22:24:47.918231 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:24:47.931927 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:24:47.945545 ignition[661]: Ignition 2.18.0 Aug 5 22:24:47.946111 ignition[661]: Stage: fetch-offline Aug 5 22:24:47.946162 ignition[661]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:24:47.946171 ignition[661]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:24:47.946265 ignition[661]: parsed url from cmdline: "" Aug 5 22:24:47.946275 ignition[661]: no config URL provided Aug 5 22:24:47.946281 ignition[661]: reading system config file "/usr/lib/ignition/user.ign" Aug 5 22:24:47.946289 ignition[661]: no config at "/usr/lib/ignition/user.ign" Aug 5 22:24:47.946311 ignition[661]: op(1): [started] loading QEMU firmware config module Aug 5 22:24:47.946316 ignition[661]: op(1): executing: "modprobe" "qemu_fw_cfg" Aug 5 22:24:47.955205 systemd-networkd[760]: lo: Link UP Aug 5 22:24:47.955218 systemd-networkd[760]: lo: Gained carrier Aug 5 22:24:47.955891 systemd-networkd[760]: Enumeration completed Aug 5 22:24:47.955965 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:24:47.957044 systemd[1]: Reached target network.target - Network. Aug 5 22:24:47.957240 systemd-networkd[760]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:24:47.957244 systemd-networkd[760]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:24:47.957911 systemd-networkd[760]: eth0: Link UP Aug 5 22:24:47.957914 systemd-networkd[760]: eth0: Gained carrier Aug 5 22:24:47.957921 systemd-networkd[760]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:24:47.965427 ignition[661]: op(1): [finished] loading QEMU firmware config module Aug 5 22:24:47.973284 ignition[661]: parsing config with SHA512: aa9cf0254554027cd9f937938250726a4b9515d251a6a025763c81cf7a46ed40ba7d71588883727346a8e202d2a50d5b68451393c21d84a986925413d72d2d12 Aug 5 22:24:47.974822 systemd-networkd[760]: eth0: DHCPv4 address 10.0.0.157/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 22:24:47.976356 unknown[661]: fetched base config from "system" Aug 5 22:24:47.976369 unknown[661]: fetched user config from "qemu" Aug 5 22:24:47.976657 ignition[661]: fetch-offline: fetch-offline passed Aug 5 22:24:47.978489 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:24:47.976709 ignition[661]: Ignition finished successfully Aug 5 22:24:47.980099 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Aug 5 22:24:47.985927 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 5 22:24:47.998423 ignition[767]: Ignition 2.18.0 Aug 5 22:24:47.998434 ignition[767]: Stage: kargs Aug 5 22:24:47.998654 ignition[767]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:24:47.998668 ignition[767]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:24:47.999529 ignition[767]: kargs: kargs passed Aug 5 22:24:48.002333 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 5 22:24:47.999582 ignition[767]: Ignition finished successfully Aug 5 22:24:48.014941 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 5 22:24:48.024474 ignition[775]: Ignition 2.18.0 Aug 5 22:24:48.024483 ignition[775]: Stage: disks Aug 5 22:24:48.024633 ignition[775]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:24:48.024643 ignition[775]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:24:48.027084 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 5 22:24:48.025320 ignition[775]: disks: disks passed Aug 5 22:24:48.025362 ignition[775]: Ignition finished successfully Aug 5 22:24:48.030149 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 5 22:24:48.031617 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 5 22:24:48.033630 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:24:48.035667 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:24:48.037406 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:24:48.053952 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 5 22:24:48.064227 systemd-fsck[786]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 5 22:24:48.068349 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 5 22:24:48.070524 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 5 22:24:48.117804 kernel: EXT4-fs (vda9): mounted filesystem 44c9fced-dca5-4347-a15f-96911c2e5e61 r/w with ordered data mode. Quota mode: none. Aug 5 22:24:48.118434 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 5 22:24:48.119641 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 5 22:24:48.134873 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:24:48.137047 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 5 22:24:48.138041 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 5 22:24:48.138078 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 5 22:24:48.138102 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:24:48.143852 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 5 22:24:48.146150 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 5 22:24:48.150651 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (794) Aug 5 22:24:48.150679 kernel: BTRFS info (device vda6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:24:48.150690 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:24:48.151697 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:24:48.154803 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:24:48.156115 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:24:48.202019 initrd-setup-root[818]: cut: /sysroot/etc/passwd: No such file or directory Aug 5 22:24:48.205818 initrd-setup-root[825]: cut: /sysroot/etc/group: No such file or directory Aug 5 22:24:48.209963 initrd-setup-root[832]: cut: /sysroot/etc/shadow: No such file or directory Aug 5 22:24:48.213514 initrd-setup-root[839]: cut: /sysroot/etc/gshadow: No such file or directory Aug 5 22:24:48.284826 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 5 22:24:48.296908 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 5 22:24:48.298500 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 5 22:24:48.304819 kernel: BTRFS info (device vda6): last unmount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:24:48.318029 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 5 22:24:48.322584 ignition[907]: INFO : Ignition 2.18.0 Aug 5 22:24:48.322584 ignition[907]: INFO : Stage: mount Aug 5 22:24:48.324228 ignition[907]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:24:48.324228 ignition[907]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:24:48.324228 ignition[907]: INFO : mount: mount passed Aug 5 22:24:48.324228 ignition[907]: INFO : Ignition finished successfully Aug 5 22:24:48.324725 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 5 22:24:48.344944 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 5 22:24:48.813883 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 5 22:24:48.830969 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:24:48.837999 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (922) Aug 5 22:24:48.838037 kernel: BTRFS info (device vda6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:24:48.838048 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:24:48.839793 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:24:48.841806 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:24:48.842668 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:24:48.857937 ignition[939]: INFO : Ignition 2.18.0 Aug 5 22:24:48.857937 ignition[939]: INFO : Stage: files Aug 5 22:24:48.859508 ignition[939]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:24:48.859508 ignition[939]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:24:48.859508 ignition[939]: DEBUG : files: compiled without relabeling support, skipping Aug 5 22:24:48.859508 ignition[939]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 5 22:24:48.859508 ignition[939]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 5 22:24:48.865467 ignition[939]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 5 22:24:48.865467 ignition[939]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 5 22:24:48.865467 ignition[939]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Aug 5 22:24:48.865467 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.28.7-arm64.raw: attempt #1 Aug 5 22:24:48.861538 unknown[939]: wrote ssh authorized keys file for user: core Aug 5 22:24:48.991071 systemd-networkd[760]: eth0: Gained IPv6LL Aug 5 22:24:49.120884 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Aug 5 22:24:49.369371 ignition[939]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Aug 5 22:24:49.369371 ignition[939]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Aug 5 22:24:49.372793 ignition[939]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 22:24:49.372793 ignition[939]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 22:24:49.372793 ignition[939]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Aug 5 22:24:49.372793 ignition[939]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Aug 5 22:24:49.392331 ignition[939]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 22:24:49.396286 ignition[939]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 22:24:49.397819 ignition[939]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Aug 5 22:24:49.397819 ignition[939]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:24:49.397819 ignition[939]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:24:49.397819 ignition[939]: INFO : files: files passed Aug 5 22:24:49.397819 ignition[939]: INFO : Ignition finished successfully Aug 5 22:24:49.399142 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 5 22:24:49.406952 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 5 22:24:49.409396 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 5 22:24:49.410845 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 5 22:24:49.412758 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 5 22:24:49.417034 initrd-setup-root-after-ignition[968]: grep: /sysroot/oem/oem-release: No such file or directory Aug 5 22:24:49.420207 initrd-setup-root-after-ignition[970]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:24:49.420207 initrd-setup-root-after-ignition[970]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:24:49.423109 initrd-setup-root-after-ignition[974]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:24:49.422243 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:24:49.424666 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 5 22:24:49.437985 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 5 22:24:49.458682 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 5 22:24:49.458820 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 5 22:24:49.460962 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 5 22:24:49.462718 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 5 22:24:49.464573 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 5 22:24:49.465330 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 5 22:24:49.480368 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:24:49.488006 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 5 22:24:49.495475 systemd[1]: Stopped target network.target - Network. Aug 5 22:24:49.496491 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:24:49.498256 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:24:49.500177 systemd[1]: Stopped target timers.target - Timer Units. Aug 5 22:24:49.501846 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 5 22:24:49.501967 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:24:49.504385 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 5 22:24:49.506322 systemd[1]: Stopped target basic.target - Basic System. Aug 5 22:24:49.507936 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 5 22:24:49.509568 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:24:49.511453 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 5 22:24:49.513313 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 5 22:24:49.515068 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:24:49.516946 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 5 22:24:49.518814 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 5 22:24:49.520481 systemd[1]: Stopped target swap.target - Swaps. Aug 5 22:24:49.521897 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 5 22:24:49.522022 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:24:49.524261 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:24:49.526142 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:24:49.527971 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 5 22:24:49.528853 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:24:49.530047 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 5 22:24:49.530159 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 5 22:24:49.532891 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 5 22:24:49.533008 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:24:49.534893 systemd[1]: Stopped target paths.target - Path Units. Aug 5 22:24:49.536408 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 5 22:24:49.536512 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:24:49.538373 systemd[1]: Stopped target slices.target - Slice Units. Aug 5 22:24:49.540101 systemd[1]: Stopped target sockets.target - Socket Units. Aug 5 22:24:49.541597 systemd[1]: iscsid.socket: Deactivated successfully. Aug 5 22:24:49.541686 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:24:49.543338 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 5 22:24:49.543421 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:24:49.545383 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 5 22:24:49.545490 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:24:49.547142 systemd[1]: ignition-files.service: Deactivated successfully. Aug 5 22:24:49.547240 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 5 22:24:49.559946 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 5 22:24:49.560823 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 5 22:24:49.560958 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:24:49.564040 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 5 22:24:49.565609 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 5 22:24:49.567578 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 5 22:24:49.570721 ignition[994]: INFO : Ignition 2.18.0 Aug 5 22:24:49.570721 ignition[994]: INFO : Stage: umount Aug 5 22:24:49.570721 ignition[994]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:24:49.569727 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 5 22:24:49.576394 ignition[994]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:24:49.576394 ignition[994]: INFO : umount: umount passed Aug 5 22:24:49.576394 ignition[994]: INFO : Ignition finished successfully Aug 5 22:24:49.569967 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:24:49.570836 systemd-networkd[760]: eth0: DHCPv6 lease lost Aug 5 22:24:49.572076 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 5 22:24:49.572245 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:24:49.576707 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 5 22:24:49.577589 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 5 22:24:49.577683 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 5 22:24:49.580775 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 5 22:24:49.580976 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 5 22:24:49.583371 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 5 22:24:49.584297 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 5 22:24:49.587813 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 5 22:24:49.587929 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 5 22:24:49.591381 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 5 22:24:49.591417 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:24:49.592725 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 5 22:24:49.592778 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 5 22:24:49.594525 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 5 22:24:49.594572 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 5 22:24:49.596200 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 5 22:24:49.596241 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 5 22:24:49.597805 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 5 22:24:49.597852 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 5 22:24:49.611954 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 5 22:24:49.612824 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 5 22:24:49.612890 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:24:49.614957 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 5 22:24:49.615013 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:24:49.616664 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 5 22:24:49.616709 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 5 22:24:49.618735 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 5 22:24:49.618777 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:24:49.621911 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:24:49.630672 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 5 22:24:49.630906 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 5 22:24:49.632886 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 5 22:24:49.632982 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 5 22:24:49.635137 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 5 22:24:49.635285 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:24:49.639329 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 5 22:24:49.639394 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 5 22:24:49.641034 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 5 22:24:49.641067 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:24:49.642773 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 5 22:24:49.642846 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:24:49.645504 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 5 22:24:49.645550 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 5 22:24:49.648178 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:24:49.648229 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:24:49.650884 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 5 22:24:49.650932 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 5 22:24:49.662962 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 5 22:24:49.663993 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 5 22:24:49.664059 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:24:49.666150 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:24:49.666196 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:24:49.668387 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 5 22:24:49.668467 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 5 22:24:49.670534 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 5 22:24:49.672585 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 5 22:24:49.683103 systemd[1]: Switching root. Aug 5 22:24:49.707812 systemd-journald[239]: Received SIGTERM from PID 1 (systemd). Aug 5 22:24:49.707862 systemd-journald[239]: Journal stopped Aug 5 22:24:50.375019 kernel: SELinux: policy capability network_peer_controls=1 Aug 5 22:24:50.375069 kernel: SELinux: policy capability open_perms=1 Aug 5 22:24:50.375081 kernel: SELinux: policy capability extended_socket_class=1 Aug 5 22:24:50.375094 kernel: SELinux: policy capability always_check_network=0 Aug 5 22:24:50.375103 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 5 22:24:50.375112 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 5 22:24:50.375122 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 5 22:24:50.375131 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 5 22:24:50.375141 kernel: audit: type=1403 audit(1722896689.836:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 5 22:24:50.375201 systemd[1]: Successfully loaded SELinux policy in 30.835ms. Aug 5 22:24:50.375225 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.505ms. Aug 5 22:24:50.375237 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 5 22:24:50.375254 systemd[1]: Detected virtualization kvm. Aug 5 22:24:50.375271 systemd[1]: Detected architecture arm64. Aug 5 22:24:50.375283 systemd[1]: Detected first boot. Aug 5 22:24:50.375293 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:24:50.375303 zram_generator::config[1039]: No configuration found. Aug 5 22:24:50.375314 systemd[1]: Populated /etc with preset unit settings. Aug 5 22:24:50.375325 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 5 22:24:50.375336 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 5 22:24:50.375349 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 5 22:24:50.375360 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 5 22:24:50.375371 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 5 22:24:50.375381 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 5 22:24:50.375392 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 5 22:24:50.375402 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 5 22:24:50.375414 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 5 22:24:50.375425 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 5 22:24:50.375435 systemd[1]: Created slice user.slice - User and Session Slice. Aug 5 22:24:50.375446 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:24:50.375457 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:24:50.375467 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 5 22:24:50.375478 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 5 22:24:50.375490 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 5 22:24:50.375502 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:24:50.375512 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Aug 5 22:24:50.375523 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:24:50.375533 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 5 22:24:50.375544 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 5 22:24:50.375554 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 5 22:24:50.375565 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 5 22:24:50.375575 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:24:50.375588 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:24:50.375598 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:24:50.375609 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:24:50.375619 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 5 22:24:50.375630 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 5 22:24:50.375641 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:24:50.375652 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:24:50.375662 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:24:50.375673 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 5 22:24:50.375683 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 5 22:24:50.375695 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 5 22:24:50.375706 systemd[1]: Mounting media.mount - External Media Directory... Aug 5 22:24:50.375718 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 5 22:24:50.375728 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 5 22:24:50.375739 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 5 22:24:50.375750 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). Aug 5 22:24:50.375761 systemd[1]: Reached target machines.target - Containers. Aug 5 22:24:50.375771 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 5 22:24:50.375866 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:24:50.375881 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:24:50.375892 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 5 22:24:50.375907 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:24:50.375917 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:24:50.375928 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:24:50.375938 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 5 22:24:50.375949 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:24:50.375961 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 5 22:24:50.375972 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 5 22:24:50.375983 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 5 22:24:50.375995 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 5 22:24:50.376005 systemd[1]: Stopped systemd-fsck-usr.service. Aug 5 22:24:50.376015 kernel: fuse: init (API version 7.39) Aug 5 22:24:50.376026 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:24:50.376037 kernel: loop: module loaded Aug 5 22:24:50.376047 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:24:50.376059 kernel: ACPI: bus type drm_connector registered Aug 5 22:24:50.376068 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 5 22:24:50.376079 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 5 22:24:50.376089 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:24:50.376118 systemd-journald[1105]: Collecting audit messages is disabled. Aug 5 22:24:50.376142 systemd[1]: verity-setup.service: Deactivated successfully. Aug 5 22:24:50.376153 systemd[1]: Stopped verity-setup.service. Aug 5 22:24:50.376165 systemd-journald[1105]: Journal started Aug 5 22:24:50.376185 systemd-journald[1105]: Runtime Journal (/run/log/journal/4e599e2d85d04327852b4af9ee86103f) is 5.9M, max 47.3M, 41.4M free. Aug 5 22:24:50.178737 systemd[1]: Queued start job for default target multi-user.target. Aug 5 22:24:50.202697 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 5 22:24:50.203056 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 5 22:24:50.379508 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:24:50.380110 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 5 22:24:50.381243 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 5 22:24:50.382412 systemd[1]: Mounted media.mount - External Media Directory. Aug 5 22:24:50.383462 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 5 22:24:50.384629 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 5 22:24:50.385856 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 5 22:24:50.387035 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 5 22:24:50.388379 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:24:50.389845 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 5 22:24:50.389985 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 5 22:24:50.391337 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:24:50.391495 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:24:50.392871 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:24:50.393848 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:24:50.395094 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:24:50.395228 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:24:50.396554 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 5 22:24:50.396689 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 5 22:24:50.397928 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:24:50.398050 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:24:50.399289 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:24:50.400625 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 5 22:24:50.402062 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 5 22:24:50.413365 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 5 22:24:50.426897 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 5 22:24:50.428887 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 5 22:24:50.429960 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 5 22:24:50.429998 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:24:50.431854 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 5 22:24:50.433908 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 5 22:24:50.435763 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 5 22:24:50.436838 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:24:50.438955 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 5 22:24:50.440938 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 5 22:24:50.442154 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:24:50.444956 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 5 22:24:50.446078 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:24:50.449283 systemd-journald[1105]: Time spent on flushing to /var/log/journal/4e599e2d85d04327852b4af9ee86103f is 23.213ms for 834 entries. Aug 5 22:24:50.449283 systemd-journald[1105]: System Journal (/var/log/journal/4e599e2d85d04327852b4af9ee86103f) is 8.0M, max 195.6M, 187.6M free. Aug 5 22:24:50.480710 systemd-journald[1105]: Received client request to flush runtime journal. Aug 5 22:24:50.481009 kernel: loop0: detected capacity change from 0 to 59688 Aug 5 22:24:50.485537 kernel: block loop0: the capability attribute has been deprecated. Aug 5 22:24:50.485679 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 5 22:24:50.449944 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:24:50.455939 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 5 22:24:50.460474 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 5 22:24:50.465349 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:24:50.467590 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 5 22:24:50.471062 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 5 22:24:50.474584 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 5 22:24:50.476098 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 5 22:24:50.488337 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 5 22:24:50.491820 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:24:50.494968 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 5 22:24:50.503915 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 5 22:24:50.508963 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 5 22:24:50.512817 kernel: loop1: detected capacity change from 0 to 113672 Aug 5 22:24:50.521830 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 5 22:24:50.523652 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 5 22:24:50.524215 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 5 22:24:50.533016 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:24:50.534586 udevadm[1166]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Aug 5 22:24:50.555380 systemd-tmpfiles[1171]: ACLs are not supported, ignoring. Aug 5 22:24:50.555395 systemd-tmpfiles[1171]: ACLs are not supported, ignoring. Aug 5 22:24:50.557797 kernel: loop2: detected capacity change from 0 to 193208 Aug 5 22:24:50.559460 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:24:50.592811 kernel: loop3: detected capacity change from 0 to 59688 Aug 5 22:24:50.596836 kernel: loop4: detected capacity change from 0 to 113672 Aug 5 22:24:50.600822 kernel: loop5: detected capacity change from 0 to 193208 Aug 5 22:24:50.604592 (sd-merge)[1176]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Aug 5 22:24:50.604990 (sd-merge)[1176]: Merged extensions into '/usr'. Aug 5 22:24:50.609967 systemd[1]: Reloading requested from client PID 1149 ('systemd-sysext') (unit systemd-sysext.service)... Aug 5 22:24:50.609985 systemd[1]: Reloading... Aug 5 22:24:50.649868 zram_generator::config[1197]: No configuration found. Aug 5 22:24:50.727995 ldconfig[1144]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 5 22:24:50.755546 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. Aug 5 22:24:50.793242 systemd[1]: Reloading finished in 182 ms. Aug 5 22:24:50.829861 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 5 22:24:50.832387 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 5 22:24:50.849032 systemd[1]: Starting ensure-sysext.service... Aug 5 22:24:50.850845 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:24:50.861291 systemd[1]: Reloading requested from client PID 1235 ('systemctl') (unit ensure-sysext.service)... Aug 5 22:24:50.861307 systemd[1]: Reloading... Aug 5 22:24:50.868382 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 5 22:24:50.868631 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 5 22:24:50.869283 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 5 22:24:50.869504 systemd-tmpfiles[1237]: ACLs are not supported, ignoring. Aug 5 22:24:50.869555 systemd-tmpfiles[1237]: ACLs are not supported, ignoring. Aug 5 22:24:50.871619 systemd-tmpfiles[1237]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:24:50.871631 systemd-tmpfiles[1237]: Skipping /boot Aug 5 22:24:50.877922 systemd-tmpfiles[1237]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:24:50.877937 systemd-tmpfiles[1237]: Skipping /boot Aug 5 22:24:50.901806 zram_generator::config[1262]: No configuration found. Aug 5 22:24:50.981925 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. Aug 5 22:24:51.018744 systemd[1]: Reloading finished in 157 ms. Aug 5 22:24:51.032673 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 5 22:24:51.041269 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:24:51.048423 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 22:24:51.050857 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 5 22:24:51.053112 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 5 22:24:51.059179 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:24:51.064126 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:24:51.069174 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 5 22:24:51.072717 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:24:51.074061 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:24:51.079108 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:24:51.084181 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:24:51.085413 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:24:51.090059 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 5 22:24:51.092013 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 5 22:24:51.093890 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:24:51.094061 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:24:51.095818 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:24:51.095943 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:24:51.097652 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:24:51.097798 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:24:51.102141 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 5 22:24:51.105393 systemd-udevd[1307]: Using default interface naming scheme 'v255'. Aug 5 22:24:51.115120 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:24:51.119070 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:24:51.122450 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:24:51.130273 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:24:51.131355 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:24:51.133947 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 5 22:24:51.135215 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). Aug 5 22:24:51.135892 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:24:51.139397 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 5 22:24:51.141298 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:24:51.141433 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:24:51.142971 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:24:51.143080 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:24:51.153716 augenrules[1341]: No rules Aug 5 22:24:51.157038 systemd[1]: Finished ensure-sysext.service. Aug 5 22:24:51.158402 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 22:24:51.160341 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:24:51.160463 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:24:51.164227 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 5 22:24:51.166928 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1344) Aug 5 22:24:51.170800 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1345) Aug 5 22:24:51.173609 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Aug 5 22:24:51.175608 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:24:51.196476 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:24:51.203964 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:24:51.208040 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:24:51.209056 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:24:51.213908 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:24:51.215100 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:24:51.217487 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 5 22:24:51.218974 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). Aug 5 22:24:51.219278 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 5 22:24:51.220951 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:24:51.223191 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:24:51.225169 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:24:51.225324 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:24:51.226667 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:24:51.226820 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:24:51.241382 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:24:51.245907 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 5 22:24:51.247992 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:24:51.267028 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 5 22:24:51.275935 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:24:51.276993 systemd-resolved[1303]: Positive Trust Anchors: Aug 5 22:24:51.277007 systemd-resolved[1303]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:24:51.277039 systemd-resolved[1303]: 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 corp home internal intranet lan local private test Aug 5 22:24:51.282865 systemd-resolved[1303]: Defaulting to hostname 'linux'. Aug 5 22:24:51.285902 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 5 22:24:51.289933 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 5 22:24:51.295498 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:24:51.296773 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:24:51.303132 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 5 22:24:51.304356 systemd[1]: Reached target time-set.target - System Time Set. Aug 5 22:24:51.306796 systemd-networkd[1377]: lo: Link UP Aug 5 22:24:51.307041 systemd-networkd[1377]: lo: Gained carrier Aug 5 22:24:51.307824 systemd-networkd[1377]: Enumeration completed Aug 5 22:24:51.308559 systemd-networkd[1377]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:24:51.308633 systemd-networkd[1377]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:24:51.309548 systemd-networkd[1377]: eth0: Link UP Aug 5 22:24:51.309629 systemd-networkd[1377]: eth0: Gained carrier Aug 5 22:24:51.309683 systemd-networkd[1377]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:24:51.311627 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:24:51.312818 systemd[1]: Reached target network.target - Network. Aug 5 22:24:51.321976 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 5 22:24:51.324961 systemd-networkd[1377]: eth0: DHCPv4 address 10.0.0.157/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 22:24:51.325719 systemd-timesyncd[1378]: Network configuration changed, trying to establish connection. Aug 5 22:24:51.327171 systemd-timesyncd[1378]: Contacted time server 10.0.0.1:123 (10.0.0.1). Aug 5 22:24:51.327228 systemd-timesyncd[1378]: Initial clock synchronization to Mon 2024-08-05 22:24:51.140221 UTC. Aug 5 22:24:51.329014 lvm[1390]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:24:51.349830 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:24:51.361122 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 5 22:24:51.362350 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:24:51.363412 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:24:51.364492 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 5 22:24:51.365679 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 5 22:24:51.366997 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 5 22:24:51.368117 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 5 22:24:51.369273 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 5 22:24:51.370406 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 5 22:24:51.370442 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:24:51.371435 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:24:51.373002 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 5 22:24:51.375228 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 5 22:24:51.391616 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 5 22:24:51.393688 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 5 22:24:51.395115 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 5 22:24:51.396238 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:24:51.397141 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:24:51.398017 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:24:51.398050 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:24:51.398926 systemd[1]: Starting containerd.service - containerd container runtime... Aug 5 22:24:51.400728 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 5 22:24:51.401388 lvm[1401]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:24:51.404991 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 5 22:24:51.408012 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 5 22:24:51.411607 jq[1404]: false Aug 5 22:24:51.410533 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). Aug 5 22:24:51.411591 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 5 22:24:51.415980 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 5 22:24:51.421635 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 5 22:24:51.424529 extend-filesystems[1405]: Found loop3 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found loop4 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found loop5 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda1 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda2 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda3 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found usr Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda4 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda6 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda7 Aug 5 22:24:51.424529 extend-filesystems[1405]: Found vda9 Aug 5 22:24:51.424529 extend-filesystems[1405]: Checking size of /dev/vda9 Aug 5 22:24:51.426057 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 5 22:24:51.464740 extend-filesystems[1405]: Resized partition /dev/vda9 Aug 5 22:24:51.436632 dbus-daemon[1403]: [system] SELinux support is enabled Aug 5 22:24:51.429237 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 5 22:24:51.465974 extend-filesystems[1425]: resize2fs 1.47.0 (5-Feb-2023) Aug 5 22:24:51.429625 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 5 22:24:51.466968 jq[1420]: true Aug 5 22:24:51.430394 systemd[1]: Starting update-engine.service - Update Engine... Aug 5 22:24:51.432519 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 5 22:24:51.435186 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 5 22:24:51.467344 jq[1426]: true Aug 5 22:24:51.437106 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 5 22:24:51.442077 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 5 22:24:51.442228 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 5 22:24:51.442483 systemd[1]: motdgen.service: Deactivated successfully. Aug 5 22:24:51.442609 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 5 22:24:51.445493 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 5 22:24:51.445627 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 5 22:24:51.468867 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1337) Aug 5 22:24:51.468899 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Aug 5 22:24:51.454697 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). Aug 5 22:24:51.454718 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 5 22:24:51.455857 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). Aug 5 22:24:51.455876 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 5 22:24:51.461179 (ntainerd)[1427]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 5 22:24:51.477598 update_engine[1419]: I0805 22:24:51.477406 1419 main.cc:92] Flatcar Update Engine starting Aug 5 22:24:51.482770 update_engine[1419]: I0805 22:24:51.482170 1419 update_check_scheduler.cc:74] Next update check in 9m26s Aug 5 22:24:51.483308 systemd[1]: Started update-engine.service - Update Engine. Aug 5 22:24:51.489992 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 5 22:24:51.498938 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Aug 5 22:24:51.517911 systemd-logind[1415]: Watching system buttons on /dev/input/event0 (Power Button) Aug 5 22:24:51.518824 extend-filesystems[1425]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Aug 5 22:24:51.518824 extend-filesystems[1425]: old_desc_blocks = 1, new_desc_blocks = 1 Aug 5 22:24:51.518824 extend-filesystems[1425]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Aug 5 22:24:51.525007 extend-filesystems[1405]: Resized filesystem in /dev/vda9 Aug 5 22:24:51.520444 systemd-logind[1415]: New seat seat0. Aug 5 22:24:51.521700 systemd[1]: Started systemd-logind.service - User Login Management. Aug 5 22:24:51.524462 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 5 22:24:51.526997 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 5 22:24:51.536196 bash[1453]: Updated "/home/core/.ssh/authorized_keys" Aug 5 22:24:51.538827 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 5 22:24:51.541169 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 5 22:24:51.545022 locksmithd[1439]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 5 22:24:51.655896 containerd[1427]: time="2024-08-05T22:24:51.655803560Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Aug 5 22:24:51.679203 containerd[1427]: time="2024-08-05T22:24:51.679060760Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 5 22:24:51.679203 containerd[1427]: time="2024-08-05T22:24:51.679095760Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681392440Z" 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.43-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681424880Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681618160Z" 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 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681633920Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681698600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681738440Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681750200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681824640Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.681991880Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.682007760Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Aug 5 22:24:51.682315 containerd[1427]: time="2024-08-05T22:24:51.682016800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:24:51.682566 containerd[1427]: time="2024-08-05T22:24:51.682100120Z" 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 Aug 5 22:24:51.682566 containerd[1427]: time="2024-08-05T22:24:51.682113320Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 5 22:24:51.682566 containerd[1427]: time="2024-08-05T22:24:51.682157040Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Aug 5 22:24:51.682566 containerd[1427]: time="2024-08-05T22:24:51.682167520Z" level=info msg="metadata content store policy set" policy=shared Aug 5 22:24:51.685024 containerd[1427]: time="2024-08-05T22:24:51.684991800Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 5 22:24:51.685127 containerd[1427]: time="2024-08-05T22:24:51.685113600Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 5 22:24:51.685188 containerd[1427]: time="2024-08-05T22:24:51.685174680Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 5 22:24:51.685271 containerd[1427]: time="2024-08-05T22:24:51.685247920Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 5 22:24:51.685328 containerd[1427]: time="2024-08-05T22:24:51.685316480Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 5 22:24:51.685412 containerd[1427]: time="2024-08-05T22:24:51.685398520Z" level=info msg="NRI interface is disabled by configuration." Aug 5 22:24:51.685462 containerd[1427]: time="2024-08-05T22:24:51.685450520Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 5 22:24:51.685612 containerd[1427]: time="2024-08-05T22:24:51.685592320Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 5 22:24:51.685675 containerd[1427]: time="2024-08-05T22:24:51.685661360Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 5 22:24:51.685729 containerd[1427]: time="2024-08-05T22:24:51.685716160Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 5 22:24:51.685798 containerd[1427]: time="2024-08-05T22:24:51.685769680Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 5 22:24:51.685871 containerd[1427]: time="2024-08-05T22:24:51.685855360Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.685927 containerd[1427]: time="2024-08-05T22:24:51.685914880Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.685979 containerd[1427]: time="2024-08-05T22:24:51.685966600Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.686029 containerd[1427]: time="2024-08-05T22:24:51.686017720Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.686089 containerd[1427]: time="2024-08-05T22:24:51.686076760Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.686142 containerd[1427]: time="2024-08-05T22:24:51.686129840Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.686192 containerd[1427]: time="2024-08-05T22:24:51.686180760Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.686242 containerd[1427]: time="2024-08-05T22:24:51.686229280Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 5 22:24:51.686417 containerd[1427]: time="2024-08-05T22:24:51.686396040Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 5 22:24:51.686686 containerd[1427]: time="2024-08-05T22:24:51.686667720Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 5 22:24:51.686771 containerd[1427]: time="2024-08-05T22:24:51.686756960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.686865 containerd[1427]: time="2024-08-05T22:24:51.686850240Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 5 22:24:51.686928 containerd[1427]: time="2024-08-05T22:24:51.686916320Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 5 22:24:51.687730 containerd[1427]: time="2024-08-05T22:24:51.687699120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.687848 containerd[1427]: time="2024-08-05T22:24:51.687832200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.687902 containerd[1427]: time="2024-08-05T22:24:51.687890000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.687951 containerd[1427]: time="2024-08-05T22:24:51.687939640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688002 containerd[1427]: time="2024-08-05T22:24:51.687990080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688055 containerd[1427]: time="2024-08-05T22:24:51.688040360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688116 containerd[1427]: time="2024-08-05T22:24:51.688104120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688175 containerd[1427]: time="2024-08-05T22:24:51.688162880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688234 containerd[1427]: time="2024-08-05T22:24:51.688221360Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 5 22:24:51.688419 containerd[1427]: time="2024-08-05T22:24:51.688399160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688492 containerd[1427]: time="2024-08-05T22:24:51.688478480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688543 containerd[1427]: time="2024-08-05T22:24:51.688531440Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688595 containerd[1427]: time="2024-08-05T22:24:51.688582360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688656 containerd[1427]: time="2024-08-05T22:24:51.688643800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688709 containerd[1427]: time="2024-08-05T22:24:51.688697200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688761 containerd[1427]: time="2024-08-05T22:24:51.688749080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.688835 containerd[1427]: time="2024-08-05T22:24:51.688822000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 5 22:24:51.689212 containerd[1427]: time="2024-08-05T22:24:51.689153840Z" 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}" Aug 5 22:24:51.689389 containerd[1427]: time="2024-08-05T22:24:51.689371960Z" level=info msg="Connect containerd service" Aug 5 22:24:51.689468 containerd[1427]: time="2024-08-05T22:24:51.689454920Z" level=info msg="using legacy CRI server" Aug 5 22:24:51.689515 containerd[1427]: time="2024-08-05T22:24:51.689502960Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 5 22:24:51.689696 containerd[1427]: time="2024-08-05T22:24:51.689680120Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690387160Z" 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" Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690439040Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690456560Z" 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 Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690466160Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690478120Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690576480Z" level=info msg="Start subscribing containerd event" Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690735960Z" level=info msg="Start recovering state" Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690811840Z" level=info msg="Start event monitor" Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690826600Z" level=info msg="Start snapshots syncer" Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690835280Z" level=info msg="Start cni network conf syncer for default" Aug 5 22:24:51.690884 containerd[1427]: time="2024-08-05T22:24:51.690843680Z" level=info msg="Start streaming server" Aug 5 22:24:51.691252 containerd[1427]: time="2024-08-05T22:24:51.691232320Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 5 22:24:51.691365 containerd[1427]: time="2024-08-05T22:24:51.691350720Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 5 22:24:51.691529 systemd[1]: Started containerd.service - containerd container runtime. Aug 5 22:24:51.693442 containerd[1427]: time="2024-08-05T22:24:51.693420840Z" level=info msg="containerd successfully booted in 0.038909s" Aug 5 22:24:52.209817 sshd_keygen[1428]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 5 22:24:52.227389 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 5 22:24:52.241984 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 5 22:24:52.246811 systemd[1]: issuegen.service: Deactivated successfully. Aug 5 22:24:52.246993 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 5 22:24:52.249264 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 5 22:24:52.280189 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 5 22:24:52.292115 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 5 22:24:52.294249 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Aug 5 22:24:52.295483 systemd[1]: Reached target getty.target - Login Prompts. Aug 5 22:24:53.342995 systemd-networkd[1377]: eth0: Gained IPv6LL Aug 5 22:24:53.345452 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 5 22:24:53.347273 systemd[1]: Reached target network-online.target - Network is Online. Aug 5 22:24:53.362155 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 5 22:24:53.364606 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:24:53.366724 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 5 22:24:53.381182 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 5 22:24:53.381355 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 5 22:24:53.382863 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 5 22:24:53.384571 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 5 22:24:53.839086 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:24:53.840538 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 5 22:24:53.842699 (kubelet)[1509]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 5 22:24:53.846984 systemd[1]: Startup finished in 551ms (kernel) + 4.134s (initrd) + 4.040s (userspace) = 8.727s. Aug 5 22:24:54.295588 kubelet[1509]: E0805 22:24:54.295465 1509 run.go:74] "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" Aug 5 22:24:54.298370 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 5 22:24:54.298510 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 5 22:24:58.322441 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 5 22:24:58.323513 systemd[1]: Started sshd@0-10.0.0.157:22-10.0.0.1:53602.service - OpenSSH per-connection server daemon (10.0.0.1:53602). Aug 5 22:24:58.370033 sshd[1523]: Accepted publickey for core from 10.0.0.1 port 53602 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:24:58.371651 sshd[1523]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:24:58.388611 systemd-logind[1415]: New session 1 of user core. Aug 5 22:24:58.389574 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 5 22:24:58.398120 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 5 22:24:58.408275 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 5 22:24:58.411708 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 5 22:24:58.418748 (systemd)[1527]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:24:58.491836 systemd[1527]: Queued start job for default target default.target. Aug 5 22:24:58.501744 systemd[1527]: Created slice app.slice - User Application Slice. Aug 5 22:24:58.501765 systemd[1527]: Reached target paths.target - Paths. Aug 5 22:24:58.501801 systemd[1527]: Reached target timers.target - Timers. Aug 5 22:24:58.502979 systemd[1527]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 5 22:24:58.512302 systemd[1527]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 5 22:24:58.512371 systemd[1527]: Reached target sockets.target - Sockets. Aug 5 22:24:58.512383 systemd[1527]: Reached target basic.target - Basic System. Aug 5 22:24:58.512419 systemd[1527]: Reached target default.target - Main User Target. Aug 5 22:24:58.512444 systemd[1527]: Startup finished in 87ms. Aug 5 22:24:58.512743 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 5 22:24:58.514021 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 5 22:24:58.577854 systemd[1]: Started sshd@1-10.0.0.157:22-10.0.0.1:53612.service - OpenSSH per-connection server daemon (10.0.0.1:53612). Aug 5 22:24:58.609495 sshd[1538]: Accepted publickey for core from 10.0.0.1 port 53612 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:24:58.610859 sshd[1538]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:24:58.614693 systemd-logind[1415]: New session 2 of user core. Aug 5 22:24:58.626949 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 5 22:24:58.678134 sshd[1538]: pam_unix(sshd:session): session closed for user core Aug 5 22:24:58.687069 systemd[1]: sshd@1-10.0.0.157:22-10.0.0.1:53612.service: Deactivated successfully. Aug 5 22:24:58.688431 systemd[1]: session-2.scope: Deactivated successfully. Aug 5 22:24:58.690735 systemd-logind[1415]: Session 2 logged out. Waiting for processes to exit. Aug 5 22:24:58.691910 systemd[1]: Started sshd@2-10.0.0.157:22-10.0.0.1:53626.service - OpenSSH per-connection server daemon (10.0.0.1:53626). Aug 5 22:24:58.692637 systemd-logind[1415]: Removed session 2. Aug 5 22:24:58.723343 sshd[1545]: Accepted publickey for core from 10.0.0.1 port 53626 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:24:58.724425 sshd[1545]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:24:58.727743 systemd-logind[1415]: New session 3 of user core. Aug 5 22:24:58.739952 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 5 22:24:58.786372 sshd[1545]: pam_unix(sshd:session): session closed for user core Aug 5 22:24:58.801065 systemd[1]: sshd@2-10.0.0.157:22-10.0.0.1:53626.service: Deactivated successfully. Aug 5 22:24:58.802959 systemd[1]: session-3.scope: Deactivated successfully. Aug 5 22:24:58.804146 systemd-logind[1415]: Session 3 logged out. Waiting for processes to exit. Aug 5 22:24:58.805144 systemd[1]: Started sshd@3-10.0.0.157:22-10.0.0.1:53630.service - OpenSSH per-connection server daemon (10.0.0.1:53630). Aug 5 22:24:58.805856 systemd-logind[1415]: Removed session 3. Aug 5 22:24:58.837198 sshd[1552]: Accepted publickey for core from 10.0.0.1 port 53630 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:24:58.838280 sshd[1552]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:24:58.841864 systemd-logind[1415]: New session 4 of user core. Aug 5 22:24:58.853994 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 5 22:24:58.904862 sshd[1552]: pam_unix(sshd:session): session closed for user core Aug 5 22:24:58.916199 systemd[1]: sshd@3-10.0.0.157:22-10.0.0.1:53630.service: Deactivated successfully. Aug 5 22:24:58.919078 systemd[1]: session-4.scope: Deactivated successfully. Aug 5 22:24:58.920331 systemd-logind[1415]: Session 4 logged out. Waiting for processes to exit. Aug 5 22:24:58.930168 systemd[1]: Started sshd@4-10.0.0.157:22-10.0.0.1:53640.service - OpenSSH per-connection server daemon (10.0.0.1:53640). Aug 5 22:24:58.931008 systemd-logind[1415]: Removed session 4. Aug 5 22:24:58.958368 sshd[1559]: Accepted publickey for core from 10.0.0.1 port 53640 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:24:58.959497 sshd[1559]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:24:58.963679 systemd-logind[1415]: New session 5 of user core. Aug 5 22:24:58.974939 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 5 22:24:59.032529 sudo[1563]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 5 22:24:59.032760 sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 22:24:59.053085 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 5 22:24:59.068403 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 5 22:24:59.068600 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 5 22:24:59.534248 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:24:59.546007 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:24:59.561390 systemd[1]: Reloading requested from client PID 1611 ('systemctl') (unit session-5.scope)... Aug 5 22:24:59.561406 systemd[1]: Reloading... Aug 5 22:24:59.629004 zram_generator::config[1647]: No configuration found. Aug 5 22:24:59.810909 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. Aug 5 22:24:59.863821 systemd[1]: Reloading finished in 302 ms. Aug 5 22:24:59.901150 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:24:59.903420 systemd[1]: kubelet.service: Deactivated successfully. Aug 5 22:24:59.903592 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:24:59.904932 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:24:59.991053 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:24:59.994835 (kubelet)[1694]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 5 22:25:00.037040 kubelet[1694]: 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. Aug 5 22:25:00.037040 kubelet[1694]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Aug 5 22:25:00.037040 kubelet[1694]: 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. Aug 5 22:25:00.037482 kubelet[1694]: I0805 22:25:00.037139 1694 server.go:203] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 5 22:25:00.675443 kubelet[1694]: I0805 22:25:00.675274 1694 server.go:467] "Kubelet version" kubeletVersion="v1.28.7" Aug 5 22:25:00.675443 kubelet[1694]: I0805 22:25:00.675303 1694 server.go:469] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 5 22:25:00.675581 kubelet[1694]: I0805 22:25:00.675516 1694 server.go:895] "Client rotation is on, will bootstrap in background" Aug 5 22:25:00.697975 kubelet[1694]: I0805 22:25:00.697857 1694 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 5 22:25:00.705058 kubelet[1694]: W0805 22:25:00.705030 1694 machine.go:65] Cannot read vendor id correctly, set empty. Aug 5 22:25:00.705851 kubelet[1694]: I0805 22:25:00.705833 1694 server.go:725] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 5 22:25:00.706046 kubelet[1694]: I0805 22:25:00.706028 1694 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 5 22:25:00.706227 kubelet[1694]: I0805 22:25:00.706204 1694 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"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} Aug 5 22:25:00.706322 kubelet[1694]: I0805 22:25:00.706234 1694 topology_manager.go:138] "Creating topology manager with none policy" Aug 5 22:25:00.706322 kubelet[1694]: I0805 22:25:00.706243 1694 container_manager_linux.go:301] "Creating device plugin manager" Aug 5 22:25:00.706909 kubelet[1694]: I0805 22:25:00.706890 1694 state_mem.go:36] "Initialized new in-memory state store" Aug 5 22:25:00.710094 kubelet[1694]: I0805 22:25:00.710066 1694 kubelet.go:393] "Attempting to sync node with API server" Aug 5 22:25:00.710094 kubelet[1694]: I0805 22:25:00.710093 1694 kubelet.go:298] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 5 22:25:00.710187 kubelet[1694]: I0805 22:25:00.710183 1694 kubelet.go:309] "Adding apiserver pod source" Aug 5 22:25:00.710209 kubelet[1694]: I0805 22:25:00.710194 1694 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 5 22:25:00.710813 kubelet[1694]: E0805 22:25:00.710303 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:00.710813 kubelet[1694]: E0805 22:25:00.710345 1694 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:00.711407 kubelet[1694]: I0805 22:25:00.711378 1694 kuberuntime_manager.go:257] "Container runtime initialized" containerRuntime="containerd" version="v1.7.17" apiVersion="v1" Aug 5 22:25:00.713322 kubelet[1694]: W0805 22:25:00.713283 1694 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 5 22:25:00.713911 kubelet[1694]: I0805 22:25:00.713888 1694 server.go:1232] "Started kubelet" Aug 5 22:25:00.715300 kubelet[1694]: I0805 22:25:00.714019 1694 ratelimit.go:65] "Setting rate limiting for podresources endpoint" qps=100 burstTokens=10 Aug 5 22:25:00.715300 kubelet[1694]: I0805 22:25:00.714289 1694 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 5 22:25:00.715300 kubelet[1694]: I0805 22:25:00.714336 1694 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Aug 5 22:25:00.715300 kubelet[1694]: I0805 22:25:00.715136 1694 server.go:462] "Adding debug handlers to kubelet server" Aug 5 22:25:00.717751 kubelet[1694]: I0805 22:25:00.717714 1694 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 5 22:25:00.721568 kubelet[1694]: E0805 22:25:00.721112 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc6be024d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"Starting", Message:"Starting kubelet.", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 713861709, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 713861709, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.721568 kubelet[1694]: E0805 22:25:00.721436 1694 cri_stats_provider.go:448] "Failed to get the info of the filesystem with mountpoint" err="unable to find data in memory cache" mountpoint="/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs" Aug 5 22:25:00.721568 kubelet[1694]: E0805 22:25:00.721462 1694 kubelet.go:1431] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 5 22:25:00.721929 kubelet[1694]: W0805 22:25:00.721837 1694 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes "10.0.0.157" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 5 22:25:00.722391 kubelet[1694]: E0805 22:25:00.722368 1694 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.157" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 5 22:25:00.722447 kubelet[1694]: W0805 22:25:00.722436 1694 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Aug 5 22:25:00.722487 kubelet[1694]: E0805 22:25:00.722454 1694 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: 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 Aug 5 22:25:00.722717 kubelet[1694]: E0805 22:25:00.722643 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc731c672", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"InvalidDiskCapacity", Message:"invalid capacity 0 on image filesystem", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 721448562, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 721448562, time.Local), Count:1, Type:"Warning", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.722928 kubelet[1694]: I0805 22:25:00.722878 1694 volume_manager.go:291] "Starting Kubelet Volume Manager" Aug 5 22:25:00.723366 kubelet[1694]: I0805 22:25:00.723313 1694 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Aug 5 22:25:00.723896 kubelet[1694]: I0805 22:25:00.723465 1694 reconciler_new.go:29] "Reconciler: start to sync state" Aug 5 22:25:00.724112 kubelet[1694]: E0805 22:25:00.724088 1694 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.157\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Aug 5 22:25:00.724429 kubelet[1694]: W0805 22:25:00.724410 1694 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Aug 5 22:25:00.724474 kubelet[1694]: E0805 22:25:00.724432 1694 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Aug 5 22:25:00.740128 kubelet[1694]: I0805 22:25:00.740099 1694 cpu_manager.go:214] "Starting CPU manager" policy="none" Aug 5 22:25:00.740128 kubelet[1694]: I0805 22:25:00.740118 1694 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Aug 5 22:25:00.740128 kubelet[1694]: I0805 22:25:00.740133 1694 state_mem.go:36] "Initialized new in-memory state store" Aug 5 22:25:00.740796 kubelet[1694]: E0805 22:25:00.740475 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc8457c39", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.157 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739517497, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739517497, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.741394 kubelet[1694]: E0805 22:25:00.741330 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845add2", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.157 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739530194, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739530194, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.742227 kubelet[1694]: E0805 22:25:00.742171 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845bd7a", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.157 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739534202, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739534202, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.802833 kubelet[1694]: I0805 22:25:00.802720 1694 policy_none.go:49] "None policy: Start" Aug 5 22:25:00.803614 kubelet[1694]: I0805 22:25:00.803552 1694 memory_manager.go:169] "Starting memorymanager" policy="None" Aug 5 22:25:00.803672 kubelet[1694]: I0805 22:25:00.803630 1694 state_mem.go:35] "Initializing new in-memory state store" Aug 5 22:25:00.809321 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Aug 5 22:25:00.823348 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Aug 5 22:25:00.823704 kubelet[1694]: I0805 22:25:00.823666 1694 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.157" Aug 5 22:25:00.825165 kubelet[1694]: E0805 22:25:00.825125 1694 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.157" Aug 5 22:25:00.825230 kubelet[1694]: E0805 22:25:00.825135 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc8457c39", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.157 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739517497, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 823626831, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc8457c39" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.825645 kubelet[1694]: I0805 22:25:00.825607 1694 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 5 22:25:00.826458 kubelet[1694]: E0805 22:25:00.826376 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845add2", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.157 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739530194, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 823631473, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc845add2" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.827299 kubelet[1694]: I0805 22:25:00.826726 1694 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 5 22:25:00.827299 kubelet[1694]: I0805 22:25:00.826756 1694 status_manager.go:217] "Starting to sync pod status with apiserver" Aug 5 22:25:00.827299 kubelet[1694]: I0805 22:25:00.826781 1694 kubelet.go:2303] "Starting kubelet main sync loop" Aug 5 22:25:00.827299 kubelet[1694]: E0805 22:25:00.826860 1694 kubelet.go:2327] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Aug 5 22:25:00.827299 kubelet[1694]: E0805 22:25:00.827230 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845bd7a", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.157 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739534202, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 823634489, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc845bd7a" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.827356 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Aug 5 22:25:00.828179 kubelet[1694]: W0805 22:25:00.828156 1694 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Aug 5 22:25:00.828327 kubelet[1694]: E0805 22:25:00.828317 1694 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.RuntimeClass: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Aug 5 22:25:00.836654 kubelet[1694]: I0805 22:25:00.836621 1694 manager.go:471] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 5 22:25:00.836909 kubelet[1694]: I0805 22:25:00.836886 1694 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 5 22:25:00.837377 kubelet[1694]: E0805 22:25:00.837352 1694 eviction_manager.go:258] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.157\" not found" Aug 5 22:25:00.839201 kubelet[1694]: E0805 22:25:00.839137 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bce2a2f0c", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeAllocatableEnforced", Message:"Updated Node Allocatable limit across pods", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 838391564, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 838391564, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:00.926032 kubelet[1694]: E0805 22:25:00.925912 1694 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.157\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="400ms" Aug 5 22:25:01.025966 kubelet[1694]: I0805 22:25:01.025935 1694 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.157" Aug 5 22:25:01.027014 kubelet[1694]: E0805 22:25:01.026985 1694 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.157" Aug 5 22:25:01.027379 kubelet[1694]: E0805 22:25:01.027302 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc8457c39", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.157 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739517497, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 1, 25861693, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc8457c39" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:01.028170 kubelet[1694]: E0805 22:25:01.028099 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845add2", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.157 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739530194, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 1, 25900220, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc845add2" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:01.029116 kubelet[1694]: E0805 22:25:01.029033 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845bd7a", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.157 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739534202, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 1, 25905384, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc845bd7a" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:01.327267 kubelet[1694]: E0805 22:25:01.327239 1694 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.157\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="800ms" Aug 5 22:25:01.428242 kubelet[1694]: I0805 22:25:01.428158 1694 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.157" Aug 5 22:25:01.429341 kubelet[1694]: E0805 22:25:01.429313 1694 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.157" Aug 5 22:25:01.429395 kubelet[1694]: E0805 22:25:01.429316 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc8457c39", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.157 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739517497, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 1, 428119302, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc8457c39" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:01.430209 kubelet[1694]: E0805 22:25:01.430139 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845add2", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.157 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739530194, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 1, 428129787, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc845add2" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:01.431065 kubelet[1694]: E0805 22:25:01.430983 1694 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.157.17e8f56bc845bd7a", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.157", UID:"10.0.0.157", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.157 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.157"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 25, 0, 739534202, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 25, 1, 428133084, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.157"}': 'events "10.0.0.157.17e8f56bc845bd7a" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:25:01.670877 kubelet[1694]: W0805 22:25:01.670766 1694 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Aug 5 22:25:01.670877 kubelet[1694]: E0805 22:25:01.670813 1694 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: 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 Aug 5 22:25:01.677884 kubelet[1694]: I0805 22:25:01.677839 1694 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Aug 5 22:25:01.711075 kubelet[1694]: E0805 22:25:01.711030 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:02.074471 kubelet[1694]: E0805 22:25:02.074426 1694 csi_plugin.go:295] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.157" not found Aug 5 22:25:02.131304 kubelet[1694]: E0805 22:25:02.131263 1694 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.157\" not found" node="10.0.0.157" Aug 5 22:25:02.230295 kubelet[1694]: I0805 22:25:02.230265 1694 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.157" Aug 5 22:25:02.238620 kubelet[1694]: I0805 22:25:02.238590 1694 kubelet_node_status.go:73] "Successfully registered node" node="10.0.0.157" Aug 5 22:25:02.241409 kubelet[1694]: I0805 22:25:02.241363 1694 kuberuntime_manager.go:1528] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Aug 5 22:25:02.241734 containerd[1427]: time="2024-08-05T22:25:02.241698237Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Aug 5 22:25:02.242062 kubelet[1694]: I0805 22:25:02.241993 1694 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Aug 5 22:25:02.504116 sudo[1563]: pam_unix(sudo:session): session closed for user root Aug 5 22:25:02.506003 sshd[1559]: pam_unix(sshd:session): session closed for user core Aug 5 22:25:02.509853 systemd[1]: sshd@4-10.0.0.157:22-10.0.0.1:53640.service: Deactivated successfully. Aug 5 22:25:02.511395 systemd[1]: session-5.scope: Deactivated successfully. Aug 5 22:25:02.512672 systemd-logind[1415]: Session 5 logged out. Waiting for processes to exit. Aug 5 22:25:02.513443 systemd-logind[1415]: Removed session 5. Aug 5 22:25:02.711645 kubelet[1694]: E0805 22:25:02.711337 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:02.711645 kubelet[1694]: I0805 22:25:02.711404 1694 apiserver.go:52] "Watching apiserver" Aug 5 22:25:02.714672 kubelet[1694]: I0805 22:25:02.714637 1694 topology_manager.go:215] "Topology Admit Handler" podUID="c23900a7-a91c-436f-9c76-4af84953c9df" podNamespace="kube-flannel" podName="kube-flannel-ds-vr5lh" Aug 5 22:25:02.714751 kubelet[1694]: I0805 22:25:02.714743 1694 topology_manager.go:215] "Topology Admit Handler" podUID="b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a" podNamespace="kube-system" podName="kube-proxy-qvq5v" Aug 5 22:25:02.722600 systemd[1]: Created slice kubepods-besteffort-podb4c0acaa_bc81_4c6d_a023_f1ff6ecaea6a.slice - libcontainer container kubepods-besteffort-podb4c0acaa_bc81_4c6d_a023_f1ff6ecaea6a.slice. Aug 5 22:25:02.723600 kubelet[1694]: I0805 22:25:02.723569 1694 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Aug 5 22:25:02.732649 kubelet[1694]: I0805 22:25:02.732606 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/c23900a7-a91c-436f-9c76-4af84953c9df-cni-plugin\") pod \"kube-flannel-ds-vr5lh\" (UID: \"c23900a7-a91c-436f-9c76-4af84953c9df\") " pod="kube-flannel/kube-flannel-ds-vr5lh" Aug 5 22:25:02.732649 kubelet[1694]: I0805 22:25:02.732650 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/c23900a7-a91c-436f-9c76-4af84953c9df-flannel-cfg\") pod \"kube-flannel-ds-vr5lh\" (UID: \"c23900a7-a91c-436f-9c76-4af84953c9df\") " pod="kube-flannel/kube-flannel-ds-vr5lh" Aug 5 22:25:02.732751 kubelet[1694]: I0805 22:25:02.732673 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bmwwh\" (UniqueName: \"kubernetes.io/projected/c23900a7-a91c-436f-9c76-4af84953c9df-kube-api-access-bmwwh\") pod \"kube-flannel-ds-vr5lh\" (UID: \"c23900a7-a91c-436f-9c76-4af84953c9df\") " pod="kube-flannel/kube-flannel-ds-vr5lh" Aug 5 22:25:02.732751 kubelet[1694]: I0805 22:25:02.732692 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a-kube-proxy\") pod \"kube-proxy-qvq5v\" (UID: \"b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a\") " pod="kube-system/kube-proxy-qvq5v" Aug 5 22:25:02.732751 kubelet[1694]: I0805 22:25:02.732720 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a-xtables-lock\") pod \"kube-proxy-qvq5v\" (UID: \"b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a\") " pod="kube-system/kube-proxy-qvq5v" Aug 5 22:25:02.732751 kubelet[1694]: I0805 22:25:02.732737 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a-lib-modules\") pod \"kube-proxy-qvq5v\" (UID: \"b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a\") " pod="kube-system/kube-proxy-qvq5v" Aug 5 22:25:02.732865 kubelet[1694]: I0805 22:25:02.732755 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/c23900a7-a91c-436f-9c76-4af84953c9df-run\") pod \"kube-flannel-ds-vr5lh\" (UID: \"c23900a7-a91c-436f-9c76-4af84953c9df\") " pod="kube-flannel/kube-flannel-ds-vr5lh" Aug 5 22:25:02.732865 kubelet[1694]: I0805 22:25:02.732773 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c23900a7-a91c-436f-9c76-4af84953c9df-xtables-lock\") pod \"kube-flannel-ds-vr5lh\" (UID: \"c23900a7-a91c-436f-9c76-4af84953c9df\") " pod="kube-flannel/kube-flannel-ds-vr5lh" Aug 5 22:25:02.732865 kubelet[1694]: I0805 22:25:02.732819 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r8vtc\" (UniqueName: \"kubernetes.io/projected/b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a-kube-api-access-r8vtc\") pod \"kube-proxy-qvq5v\" (UID: \"b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a\") " pod="kube-system/kube-proxy-qvq5v" Aug 5 22:25:02.732865 kubelet[1694]: I0805 22:25:02.732837 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/c23900a7-a91c-436f-9c76-4af84953c9df-cni\") pod \"kube-flannel-ds-vr5lh\" (UID: \"c23900a7-a91c-436f-9c76-4af84953c9df\") " pod="kube-flannel/kube-flannel-ds-vr5lh" Aug 5 22:25:02.736932 systemd[1]: Created slice kubepods-burstable-podc23900a7_a91c_436f_9c76_4af84953c9df.slice - libcontainer container kubepods-burstable-podc23900a7_a91c_436f_9c76_4af84953c9df.slice. Aug 5 22:25:03.037530 kubelet[1694]: E0805 22:25:03.037482 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:03.038226 containerd[1427]: time="2024-08-05T22:25:03.038180644Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-qvq5v,Uid:b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a,Namespace:kube-system,Attempt:0,}" Aug 5 22:25:03.048610 kubelet[1694]: E0805 22:25:03.048571 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:03.049058 containerd[1427]: time="2024-08-05T22:25:03.048932755Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vr5lh,Uid:c23900a7-a91c-436f-9c76-4af84953c9df,Namespace:kube-flannel,Attempt:0,}" Aug 5 22:25:03.623112 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3503680055.mount: Deactivated successfully. Aug 5 22:25:03.628244 containerd[1427]: time="2024-08-05T22:25:03.628204290Z" 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\"}" Aug 5 22:25:03.630054 containerd[1427]: time="2024-08-05T22:25:03.629987019Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Aug 5 22:25:03.630840 containerd[1427]: time="2024-08-05T22:25:03.630729010Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 5 22:25:03.632193 containerd[1427]: time="2024-08-05T22:25:03.631935691Z" 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\"}" Aug 5 22:25:03.632193 containerd[1427]: time="2024-08-05T22:25:03.632136446Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 5 22:25:03.634673 containerd[1427]: time="2024-08-05T22:25:03.634610480Z" 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\"}" Aug 5 22:25:03.636274 containerd[1427]: time="2024-08-05T22:25:03.636241986Z" 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 587.057948ms" Aug 5 22:25:03.636820 containerd[1427]: time="2024-08-05T22:25:03.636477911Z" 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 598.078052ms" Aug 5 22:25:03.712498 kubelet[1694]: E0805 22:25:03.712446 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:03.755558 containerd[1427]: time="2024-08-05T22:25:03.755471562Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:25:03.755558 containerd[1427]: time="2024-08-05T22:25:03.755529767Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:03.755714 containerd[1427]: time="2024-08-05T22:25:03.755547869Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:25:03.755714 containerd[1427]: time="2024-08-05T22:25:03.755563147Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:03.756226 containerd[1427]: time="2024-08-05T22:25:03.756074663Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:25:03.756226 containerd[1427]: time="2024-08-05T22:25:03.756125031Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:03.756226 containerd[1427]: time="2024-08-05T22:25:03.756140627Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:25:03.756226 containerd[1427]: time="2024-08-05T22:25:03.756150215Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:03.855330 systemd[1]: run-containerd-runc-k8s.io-75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9-runc.4Kn0cb.mount: Deactivated successfully. Aug 5 22:25:03.855426 systemd[1]: run-containerd-runc-k8s.io-a4e98536316eb31418a56e5372f9fa70f131f51a0b00272bb1be16743c7e3a25-runc.IPxlmD.mount: Deactivated successfully. Aug 5 22:25:03.866961 systemd[1]: Started cri-containerd-75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9.scope - libcontainer container 75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9. Aug 5 22:25:03.868616 systemd[1]: Started cri-containerd-a4e98536316eb31418a56e5372f9fa70f131f51a0b00272bb1be16743c7e3a25.scope - libcontainer container a4e98536316eb31418a56e5372f9fa70f131f51a0b00272bb1be16743c7e3a25. Aug 5 22:25:03.888449 containerd[1427]: time="2024-08-05T22:25:03.886716902Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-qvq5v,Uid:b4c0acaa-bc81-4c6d-a023-f1ff6ecaea6a,Namespace:kube-system,Attempt:0,} returns sandbox id \"a4e98536316eb31418a56e5372f9fa70f131f51a0b00272bb1be16743c7e3a25\"" Aug 5 22:25:03.888541 kubelet[1694]: E0805 22:25:03.887697 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:03.889439 containerd[1427]: time="2024-08-05T22:25:03.888807686Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.12\"" Aug 5 22:25:03.902981 containerd[1427]: time="2024-08-05T22:25:03.902942999Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vr5lh,Uid:c23900a7-a91c-436f-9c76-4af84953c9df,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9\"" Aug 5 22:25:03.903509 kubelet[1694]: E0805 22:25:03.903490 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:04.712803 kubelet[1694]: E0805 22:25:04.712762 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:04.788199 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount604394470.mount: Deactivated successfully. Aug 5 22:25:04.972682 containerd[1427]: time="2024-08-05T22:25:04.972483696Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.28.12\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:04.973418 containerd[1427]: time="2024-08-05T22:25:04.973216594Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.28.12: active requests=0, bytes read=24977921" Aug 5 22:25:04.974139 containerd[1427]: time="2024-08-05T22:25:04.974093611Z" level=info msg="ImageCreate event name:\"sha256:d3c27a9ad523d0e17d8e5f3f587a49f9c4b611f30f1851fe0bc1240e53a2084b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:04.976598 containerd[1427]: time="2024-08-05T22:25:04.976559163Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:7dd7829fa889ac805a0b1047eba04599fa5006bdbcb5cb9c8d14e1dc8910488b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:04.977315 containerd[1427]: time="2024-08-05T22:25:04.977283701Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.28.12\" with image id \"sha256:d3c27a9ad523d0e17d8e5f3f587a49f9c4b611f30f1851fe0bc1240e53a2084b\", repo tag \"registry.k8s.io/kube-proxy:v1.28.12\", repo digest \"registry.k8s.io/kube-proxy@sha256:7dd7829fa889ac805a0b1047eba04599fa5006bdbcb5cb9c8d14e1dc8910488b\", size \"24976938\" in 1.088440322s" Aug 5 22:25:04.977405 containerd[1427]: time="2024-08-05T22:25:04.977388963Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.12\" returns image reference \"sha256:d3c27a9ad523d0e17d8e5f3f587a49f9c4b611f30f1851fe0bc1240e53a2084b\"" Aug 5 22:25:04.978059 containerd[1427]: time="2024-08-05T22:25:04.978019226Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Aug 5 22:25:04.979394 containerd[1427]: time="2024-08-05T22:25:04.979353523Z" level=info msg="CreateContainer within sandbox \"a4e98536316eb31418a56e5372f9fa70f131f51a0b00272bb1be16743c7e3a25\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Aug 5 22:25:04.989007 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1773447573.mount: Deactivated successfully. Aug 5 22:25:04.993456 containerd[1427]: time="2024-08-05T22:25:04.993409999Z" level=info msg="CreateContainer within sandbox \"a4e98536316eb31418a56e5372f9fa70f131f51a0b00272bb1be16743c7e3a25\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"a8623365540583546da67806e411eda5ccbaf8e503722bf1d1a8826be2267912\"" Aug 5 22:25:04.994129 containerd[1427]: time="2024-08-05T22:25:04.994025014Z" level=info msg="StartContainer for \"a8623365540583546da67806e411eda5ccbaf8e503722bf1d1a8826be2267912\"" Aug 5 22:25:05.020939 systemd[1]: Started cri-containerd-a8623365540583546da67806e411eda5ccbaf8e503722bf1d1a8826be2267912.scope - libcontainer container a8623365540583546da67806e411eda5ccbaf8e503722bf1d1a8826be2267912. Aug 5 22:25:05.042232 containerd[1427]: time="2024-08-05T22:25:05.042152385Z" level=info msg="StartContainer for \"a8623365540583546da67806e411eda5ccbaf8e503722bf1d1a8826be2267912\" returns successfully" Aug 5 22:25:05.713481 kubelet[1694]: E0805 22:25:05.713422 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:05.842688 kubelet[1694]: E0805 22:25:05.842666 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:05.850255 kubelet[1694]: I0805 22:25:05.850193 1694 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-qvq5v" podStartSLOduration=2.760631021 podCreationTimestamp="2024-08-05 22:25:02 +0000 UTC" firstStartedPulling="2024-08-05 22:25:03.888326486 +0000 UTC m=+3.890093290" lastFinishedPulling="2024-08-05 22:25:04.977835892 +0000 UTC m=+4.979602736" observedRunningTime="2024-08-05 22:25:05.849975628 +0000 UTC m=+5.851742472" watchObservedRunningTime="2024-08-05 22:25:05.850140467 +0000 UTC m=+5.851907311" Aug 5 22:25:06.016756 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1011346783.mount: Deactivated successfully. Aug 5 22:25:06.040305 containerd[1427]: time="2024-08-05T22:25:06.040263192Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:06.040837 containerd[1427]: time="2024-08-05T22:25:06.040799733Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Aug 5 22:25:06.041551 containerd[1427]: time="2024-08-05T22:25:06.041525750Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:06.043702 containerd[1427]: time="2024-08-05T22:25:06.043651749Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:06.044629 containerd[1427]: time="2024-08-05T22:25:06.044597572Z" 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 1.066542584s" Aug 5 22:25:06.044689 containerd[1427]: time="2024-08-05T22:25:06.044629457Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Aug 5 22:25:06.046259 containerd[1427]: time="2024-08-05T22:25:06.046229158Z" level=info msg="CreateContainer within sandbox \"75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Aug 5 22:25:06.055818 containerd[1427]: time="2024-08-05T22:25:06.055772798Z" level=info msg="CreateContainer within sandbox \"75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890\"" Aug 5 22:25:06.056213 containerd[1427]: time="2024-08-05T22:25:06.056149318Z" level=info msg="StartContainer for \"99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890\"" Aug 5 22:25:06.081995 systemd[1]: Started cri-containerd-99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890.scope - libcontainer container 99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890. Aug 5 22:25:06.101986 containerd[1427]: time="2024-08-05T22:25:06.101944148Z" level=info msg="StartContainer for \"99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890\" returns successfully" Aug 5 22:25:06.105907 systemd[1]: cri-containerd-99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890.scope: Deactivated successfully. Aug 5 22:25:06.255181 containerd[1427]: time="2024-08-05T22:25:06.255115324Z" level=info msg="shim disconnected" id=99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890 namespace=k8s.io Aug 5 22:25:06.255181 containerd[1427]: time="2024-08-05T22:25:06.255167017Z" level=warning msg="cleaning up after shim disconnected" id=99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890 namespace=k8s.io Aug 5 22:25:06.255181 containerd[1427]: time="2024-08-05T22:25:06.255178217Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 5 22:25:06.714526 kubelet[1694]: E0805 22:25:06.714494 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:06.845828 kubelet[1694]: E0805 22:25:06.845679 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:06.845828 kubelet[1694]: E0805 22:25:06.845804 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:06.846443 containerd[1427]: time="2024-08-05T22:25:06.846414332Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Aug 5 22:25:06.987212 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-99b51a303049239fd72a848bc5fe5f2d3af1af9d793cfd424d6be091ef67e890-rootfs.mount: Deactivated successfully. Aug 5 22:25:07.714772 kubelet[1694]: E0805 22:25:07.714725 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:07.894400 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1198507763.mount: Deactivated successfully. Aug 5 22:25:08.335886 containerd[1427]: time="2024-08-05T22:25:08.335760689Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:08.336684 containerd[1427]: time="2024-08-05T22:25:08.336262582Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874259" Aug 5 22:25:08.337383 containerd[1427]: time="2024-08-05T22:25:08.337340004Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:08.340577 containerd[1427]: time="2024-08-05T22:25:08.340533738Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:08.342503 containerd[1427]: time="2024-08-05T22:25:08.342368986Z" 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 1.495915706s" Aug 5 22:25:08.342503 containerd[1427]: time="2024-08-05T22:25:08.342404607Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Aug 5 22:25:08.343992 containerd[1427]: time="2024-08-05T22:25:08.343964097Z" level=info msg="CreateContainer within sandbox \"75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Aug 5 22:25:08.354273 containerd[1427]: time="2024-08-05T22:25:08.354222625Z" level=info msg="CreateContainer within sandbox \"75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0\"" Aug 5 22:25:08.354652 containerd[1427]: time="2024-08-05T22:25:08.354614462Z" level=info msg="StartContainer for \"f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0\"" Aug 5 22:25:08.379937 systemd[1]: Started cri-containerd-f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0.scope - libcontainer container f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0. Aug 5 22:25:08.398434 containerd[1427]: time="2024-08-05T22:25:08.398381102Z" level=info msg="StartContainer for \"f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0\" returns successfully" Aug 5 22:25:08.403131 systemd[1]: cri-containerd-f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0.scope: Deactivated successfully. Aug 5 22:25:08.456434 kubelet[1694]: I0805 22:25:08.456250 1694 kubelet_node_status.go:493] "Fast updating node status as it just became ready" Aug 5 22:25:08.512979 containerd[1427]: time="2024-08-05T22:25:08.512824171Z" level=info msg="shim disconnected" id=f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0 namespace=k8s.io Aug 5 22:25:08.512979 containerd[1427]: time="2024-08-05T22:25:08.512876626Z" level=warning msg="cleaning up after shim disconnected" id=f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0 namespace=k8s.io Aug 5 22:25:08.512979 containerd[1427]: time="2024-08-05T22:25:08.512894935Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 5 22:25:08.715384 kubelet[1694]: E0805 22:25:08.715331 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:08.850701 kubelet[1694]: E0805 22:25:08.850636 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:08.852478 containerd[1427]: time="2024-08-05T22:25:08.852411638Z" level=info msg="CreateContainer within sandbox \"75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Aug 5 22:25:08.863245 containerd[1427]: time="2024-08-05T22:25:08.863151436Z" level=info msg="CreateContainer within sandbox \"75ed6fc3c9af29761930f6e7639b8002f6be135c3b147c39fb659525ec7a97d9\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"e3d51c11e22630ea073d9d9c958585d7ac644896db371c3e9000291c7295fdc8\"" Aug 5 22:25:08.863626 containerd[1427]: time="2024-08-05T22:25:08.863568723Z" level=info msg="StartContainer for \"e3d51c11e22630ea073d9d9c958585d7ac644896db371c3e9000291c7295fdc8\"" Aug 5 22:25:08.886949 systemd[1]: Started cri-containerd-e3d51c11e22630ea073d9d9c958585d7ac644896db371c3e9000291c7295fdc8.scope - libcontainer container e3d51c11e22630ea073d9d9c958585d7ac644896db371c3e9000291c7295fdc8. Aug 5 22:25:08.909083 containerd[1427]: time="2024-08-05T22:25:08.909034547Z" level=info msg="StartContainer for \"e3d51c11e22630ea073d9d9c958585d7ac644896db371c3e9000291c7295fdc8\" returns successfully" Aug 5 22:25:09.350656 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-f7c9a3ea1466d25ae87e92bfd46ff9a4b48f91058541bacf448df037da8892b0-rootfs.mount: Deactivated successfully. Aug 5 22:25:09.716293 kubelet[1694]: E0805 22:25:09.716139 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:09.856649 kubelet[1694]: E0805 22:25:09.856351 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:09.979156 systemd-networkd[1377]: flannel.1: Link UP Aug 5 22:25:09.979160 systemd-networkd[1377]: flannel.1: Gained carrier Aug 5 22:25:10.716923 kubelet[1694]: E0805 22:25:10.716877 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:10.857926 kubelet[1694]: E0805 22:25:10.857875 1694 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:25:11.717523 kubelet[1694]: E0805 22:25:11.717462 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:11.774971 systemd-networkd[1377]: flannel.1: Gained IPv6LL Aug 5 22:25:12.717777 kubelet[1694]: E0805 22:25:12.717735 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:13.271244 kubelet[1694]: I0805 22:25:13.271164 1694 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-vr5lh" podStartSLOduration=6.832627038 podCreationTimestamp="2024-08-05 22:25:02 +0000 UTC" firstStartedPulling="2024-08-05 22:25:03.904139018 +0000 UTC m=+3.905905862" lastFinishedPulling="2024-08-05 22:25:08.342635768 +0000 UTC m=+8.344402612" observedRunningTime="2024-08-05 22:25:09.865202358 +0000 UTC m=+9.866969202" watchObservedRunningTime="2024-08-05 22:25:13.271123788 +0000 UTC m=+13.272890632" Aug 5 22:25:13.271436 kubelet[1694]: I0805 22:25:13.271313 1694 topology_manager.go:215] "Topology Admit Handler" podUID="b043deec-c6fe-4b10-9947-08da7d3f76e8" podNamespace="default" podName="nginx-deployment-6d5f899847-jrmfq" Aug 5 22:25:13.276333 systemd[1]: Created slice kubepods-besteffort-podb043deec_c6fe_4b10_9947_08da7d3f76e8.slice - libcontainer container kubepods-besteffort-podb043deec_c6fe_4b10_9947_08da7d3f76e8.slice. Aug 5 22:25:13.286865 kubelet[1694]: I0805 22:25:13.286833 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-f26hw\" (UniqueName: \"kubernetes.io/projected/b043deec-c6fe-4b10-9947-08da7d3f76e8-kube-api-access-f26hw\") pod \"nginx-deployment-6d5f899847-jrmfq\" (UID: \"b043deec-c6fe-4b10-9947-08da7d3f76e8\") " pod="default/nginx-deployment-6d5f899847-jrmfq" Aug 5 22:25:13.579034 containerd[1427]: time="2024-08-05T22:25:13.578912788Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-jrmfq,Uid:b043deec-c6fe-4b10-9947-08da7d3f76e8,Namespace:default,Attempt:0,}" Aug 5 22:25:13.613446 systemd-networkd[1377]: cni0: Link UP Aug 5 22:25:13.613452 systemd-networkd[1377]: cni0: Gained carrier Aug 5 22:25:13.615446 systemd-networkd[1377]: cni0: Lost carrier Aug 5 22:25:13.618669 systemd-networkd[1377]: veth9b6f475e: Link UP Aug 5 22:25:13.620193 kernel: cni0: port 1(veth9b6f475e) entered blocking state Aug 5 22:25:13.620257 kernel: cni0: port 1(veth9b6f475e) entered disabled state Aug 5 22:25:13.620273 kernel: veth9b6f475e: entered allmulticast mode Aug 5 22:25:13.620298 kernel: veth9b6f475e: entered promiscuous mode Aug 5 22:25:13.622171 kernel: cni0: port 1(veth9b6f475e) entered blocking state Aug 5 22:25:13.622226 kernel: cni0: port 1(veth9b6f475e) entered forwarding state Aug 5 22:25:13.623894 kernel: cni0: port 1(veth9b6f475e) entered disabled state Aug 5 22:25:13.633315 kernel: cni0: port 1(veth9b6f475e) entered blocking state Aug 5 22:25:13.633362 kernel: cni0: port 1(veth9b6f475e) entered forwarding state Aug 5 22:25:13.633504 systemd-networkd[1377]: veth9b6f475e: Gained carrier Aug 5 22:25:13.633716 systemd-networkd[1377]: cni0: Gained carrier Aug 5 22:25:13.634739 containerd[1427]: 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)(0x4000016938), "name":"cbr0", "type":"bridge"} Aug 5 22:25:13.634739 containerd[1427]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:25:13.655581 containerd[1427]: {"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="2024-08-05T22:25:13.655455431Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:25:13.655581 containerd[1427]: time="2024-08-05T22:25:13.655513509Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:13.655581 containerd[1427]: time="2024-08-05T22:25:13.655527529Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:25:13.655581 containerd[1427]: time="2024-08-05T22:25:13.655544105Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:13.676956 systemd[1]: Started cri-containerd-754047b9a3884f9ad408f4e7f6e127121c28cbacfd3b6fc53bc492d947135594.scope - libcontainer container 754047b9a3884f9ad408f4e7f6e127121c28cbacfd3b6fc53bc492d947135594. Aug 5 22:25:13.685536 systemd-resolved[1303]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 22:25:13.700964 containerd[1427]: time="2024-08-05T22:25:13.700930774Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-jrmfq,Uid:b043deec-c6fe-4b10-9947-08da7d3f76e8,Namespace:default,Attempt:0,} returns sandbox id \"754047b9a3884f9ad408f4e7f6e127121c28cbacfd3b6fc53bc492d947135594\"" Aug 5 22:25:13.702404 containerd[1427]: time="2024-08-05T22:25:13.702357235Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 5 22:25:13.718806 kubelet[1694]: E0805 22:25:13.718760 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:14.719876 kubelet[1694]: E0805 22:25:14.719841 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:15.039910 systemd-networkd[1377]: cni0: Gained IPv6LL Aug 5 22:25:15.422919 systemd-networkd[1377]: veth9b6f475e: Gained IPv6LL Aug 5 22:25:15.567211 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount528274900.mount: Deactivated successfully. Aug 5 22:25:15.720537 kubelet[1694]: E0805 22:25:15.720492 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:16.214295 containerd[1427]: time="2024-08-05T22:25:16.214237155Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:16.215439 containerd[1427]: time="2024-08-05T22:25:16.215385466Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67637073" Aug 5 22:25:16.216153 containerd[1427]: time="2024-08-05T22:25:16.216090318Z" level=info msg="ImageCreate event name:\"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:16.218811 containerd[1427]: time="2024-08-05T22:25:16.218749078Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:16.219767 containerd[1427]: time="2024-08-05T22:25:16.219731307Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"67636951\" in 2.517222918s" Aug 5 22:25:16.219862 containerd[1427]: time="2024-08-05T22:25:16.219766474Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\"" Aug 5 22:25:16.221382 containerd[1427]: time="2024-08-05T22:25:16.221320840Z" level=info msg="CreateContainer within sandbox \"754047b9a3884f9ad408f4e7f6e127121c28cbacfd3b6fc53bc492d947135594\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Aug 5 22:25:16.233852 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1984344712.mount: Deactivated successfully. Aug 5 22:25:16.234073 containerd[1427]: time="2024-08-05T22:25:16.233866788Z" level=info msg="CreateContainer within sandbox \"754047b9a3884f9ad408f4e7f6e127121c28cbacfd3b6fc53bc492d947135594\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"8ae335ce1d2dcca297b5c5610e2c15bec12c8dcc7f8f72e852e82fa8e1dc3943\"" Aug 5 22:25:16.234505 containerd[1427]: time="2024-08-05T22:25:16.234205986Z" level=info msg="StartContainer for \"8ae335ce1d2dcca297b5c5610e2c15bec12c8dcc7f8f72e852e82fa8e1dc3943\"" Aug 5 22:25:16.261017 systemd[1]: Started cri-containerd-8ae335ce1d2dcca297b5c5610e2c15bec12c8dcc7f8f72e852e82fa8e1dc3943.scope - libcontainer container 8ae335ce1d2dcca297b5c5610e2c15bec12c8dcc7f8f72e852e82fa8e1dc3943. Aug 5 22:25:16.284025 containerd[1427]: time="2024-08-05T22:25:16.283988316Z" level=info msg="StartContainer for \"8ae335ce1d2dcca297b5c5610e2c15bec12c8dcc7f8f72e852e82fa8e1dc3943\" returns successfully" Aug 5 22:25:16.721129 kubelet[1694]: E0805 22:25:16.721074 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:16.876243 kubelet[1694]: I0805 22:25:16.876201 1694 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-jrmfq" podStartSLOduration=1.358122402 podCreationTimestamp="2024-08-05 22:25:13 +0000 UTC" firstStartedPulling="2024-08-05 22:25:13.702045915 +0000 UTC m=+13.703812759" lastFinishedPulling="2024-08-05 22:25:16.220090686 +0000 UTC m=+16.221857530" observedRunningTime="2024-08-05 22:25:16.875950219 +0000 UTC m=+16.877717023" watchObservedRunningTime="2024-08-05 22:25:16.876167173 +0000 UTC m=+16.877934017" Aug 5 22:25:17.721494 kubelet[1694]: E0805 22:25:17.721453 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:18.721888 kubelet[1694]: E0805 22:25:18.721827 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:19.677241 kubelet[1694]: I0805 22:25:19.677108 1694 topology_manager.go:215] "Topology Admit Handler" podUID="f0a12249-1be2-4e58-b3c2-7aabc0389b82" podNamespace="default" podName="nfs-server-provisioner-0" Aug 5 22:25:19.681971 systemd[1]: Created slice kubepods-besteffort-podf0a12249_1be2_4e58_b3c2_7aabc0389b82.slice - libcontainer container kubepods-besteffort-podf0a12249_1be2_4e58_b3c2_7aabc0389b82.slice. Aug 5 22:25:19.713722 kubelet[1694]: I0805 22:25:19.713680 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sds92\" (UniqueName: \"kubernetes.io/projected/f0a12249-1be2-4e58-b3c2-7aabc0389b82-kube-api-access-sds92\") pod \"nfs-server-provisioner-0\" (UID: \"f0a12249-1be2-4e58-b3c2-7aabc0389b82\") " pod="default/nfs-server-provisioner-0" Aug 5 22:25:19.713722 kubelet[1694]: I0805 22:25:19.713727 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/f0a12249-1be2-4e58-b3c2-7aabc0389b82-data\") pod \"nfs-server-provisioner-0\" (UID: \"f0a12249-1be2-4e58-b3c2-7aabc0389b82\") " pod="default/nfs-server-provisioner-0" Aug 5 22:25:19.722829 kubelet[1694]: E0805 22:25:19.722766 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:19.984986 containerd[1427]: time="2024-08-05T22:25:19.984877029Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f0a12249-1be2-4e58-b3c2-7aabc0389b82,Namespace:default,Attempt:0,}" Aug 5 22:25:20.011056 systemd-networkd[1377]: veth9a384e8e: Link UP Aug 5 22:25:20.013330 kernel: cni0: port 2(veth9a384e8e) entered blocking state Aug 5 22:25:20.013395 kernel: cni0: port 2(veth9a384e8e) entered disabled state Aug 5 22:25:20.013413 kernel: veth9a384e8e: entered allmulticast mode Aug 5 22:25:20.013435 kernel: veth9a384e8e: entered promiscuous mode Aug 5 22:25:20.017889 kernel: cni0: port 2(veth9a384e8e) entered blocking state Aug 5 22:25:20.017954 kernel: cni0: port 2(veth9a384e8e) entered forwarding state Aug 5 22:25:20.017817 systemd-networkd[1377]: veth9a384e8e: Gained carrier Aug 5 22:25:20.020155 containerd[1427]: 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)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Aug 5 22:25:20.020155 containerd[1427]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:25:20.034930 containerd[1427]: {"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="2024-08-05T22:25:20.034855766Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:25:20.034930 containerd[1427]: time="2024-08-05T22:25:20.034901700Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:20.034930 containerd[1427]: time="2024-08-05T22:25:20.034921929Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:25:20.034930 containerd[1427]: time="2024-08-05T22:25:20.034932283Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:20.050923 systemd[1]: Started cri-containerd-745297ef190ab31c396c9e6ae5959de37096e66ebd0e2b63b18fe7e5ccc1b7ad.scope - libcontainer container 745297ef190ab31c396c9e6ae5959de37096e66ebd0e2b63b18fe7e5ccc1b7ad. Aug 5 22:25:20.059744 systemd-resolved[1303]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 22:25:20.076459 containerd[1427]: time="2024-08-05T22:25:20.076421799Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f0a12249-1be2-4e58-b3c2-7aabc0389b82,Namespace:default,Attempt:0,} returns sandbox id \"745297ef190ab31c396c9e6ae5959de37096e66ebd0e2b63b18fe7e5ccc1b7ad\"" Aug 5 22:25:20.078517 containerd[1427]: time="2024-08-05T22:25:20.078485452Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Aug 5 22:25:20.711069 kubelet[1694]: E0805 22:25:20.711015 1694 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:20.723360 kubelet[1694]: E0805 22:25:20.723323 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:21.503050 systemd-networkd[1377]: veth9a384e8e: Gained IPv6LL Aug 5 22:25:21.620433 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount711003919.mount: Deactivated successfully. Aug 5 22:25:21.723709 kubelet[1694]: E0805 22:25:21.723664 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:22.724350 kubelet[1694]: E0805 22:25:22.724306 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:22.936426 containerd[1427]: time="2024-08-05T22:25:22.936379256Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:22.937332 containerd[1427]: time="2024-08-05T22:25:22.937139933Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Aug 5 22:25:22.937921 containerd[1427]: time="2024-08-05T22:25:22.937891693Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:22.941262 containerd[1427]: time="2024-08-05T22:25:22.941205164Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:22.942200 containerd[1427]: time="2024-08-05T22:25:22.942114818Z" 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 2.863589906s" Aug 5 22:25:22.942200 containerd[1427]: time="2024-08-05T22:25:22.942155240Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Aug 5 22:25:22.944522 containerd[1427]: time="2024-08-05T22:25:22.944482291Z" level=info msg="CreateContainer within sandbox \"745297ef190ab31c396c9e6ae5959de37096e66ebd0e2b63b18fe7e5ccc1b7ad\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Aug 5 22:25:22.955183 containerd[1427]: time="2024-08-05T22:25:22.955135321Z" level=info msg="CreateContainer within sandbox \"745297ef190ab31c396c9e6ae5959de37096e66ebd0e2b63b18fe7e5ccc1b7ad\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ba64b98dd771d1a8241dd0704d1247d0becbeb1d5d3c0f39fdf184f371a761d9\"" Aug 5 22:25:22.955585 containerd[1427]: time="2024-08-05T22:25:22.955506084Z" level=info msg="StartContainer for \"ba64b98dd771d1a8241dd0704d1247d0becbeb1d5d3c0f39fdf184f371a761d9\"" Aug 5 22:25:22.984919 systemd[1]: Started cri-containerd-ba64b98dd771d1a8241dd0704d1247d0becbeb1d5d3c0f39fdf184f371a761d9.scope - libcontainer container ba64b98dd771d1a8241dd0704d1247d0becbeb1d5d3c0f39fdf184f371a761d9. Aug 5 22:25:23.004589 containerd[1427]: time="2024-08-05T22:25:23.004553509Z" level=info msg="StartContainer for \"ba64b98dd771d1a8241dd0704d1247d0becbeb1d5d3c0f39fdf184f371a761d9\" returns successfully" Aug 5 22:25:23.724946 kubelet[1694]: E0805 22:25:23.724888 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:24.725271 kubelet[1694]: E0805 22:25:24.725227 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:25.725500 kubelet[1694]: E0805 22:25:25.725458 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:26.726272 kubelet[1694]: E0805 22:25:26.726221 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:27.726818 kubelet[1694]: E0805 22:25:27.726765 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:28.727148 kubelet[1694]: E0805 22:25:28.727101 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:29.727728 kubelet[1694]: E0805 22:25:29.727687 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:30.728218 kubelet[1694]: E0805 22:25:30.728156 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:31.728508 kubelet[1694]: E0805 22:25:31.728466 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:32.728969 kubelet[1694]: E0805 22:25:32.728927 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:32.787456 kubelet[1694]: I0805 22:25:32.787426 1694 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.923032519 podCreationTimestamp="2024-08-05 22:25:19 +0000 UTC" firstStartedPulling="2024-08-05 22:25:20.078004359 +0000 UTC m=+20.079771203" lastFinishedPulling="2024-08-05 22:25:22.942362312 +0000 UTC m=+22.944129156" observedRunningTime="2024-08-05 22:25:23.891278039 +0000 UTC m=+23.893044883" watchObservedRunningTime="2024-08-05 22:25:32.787390472 +0000 UTC m=+32.789157316" Aug 5 22:25:32.787567 kubelet[1694]: I0805 22:25:32.787545 1694 topology_manager.go:215] "Topology Admit Handler" podUID="53a4b892-c94b-441f-9a77-16cd4b901bd4" podNamespace="default" podName="test-pod-1" Aug 5 22:25:32.793674 systemd[1]: Created slice kubepods-besteffort-pod53a4b892_c94b_441f_9a77_16cd4b901bd4.slice - libcontainer container kubepods-besteffort-pod53a4b892_c94b_441f_9a77_16cd4b901bd4.slice. Aug 5 22:25:32.874598 kubelet[1694]: I0805 22:25:32.874560 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c1bba2f4-4622-4f32-81bf-4b308180c6d2\" (UniqueName: \"kubernetes.io/nfs/53a4b892-c94b-441f-9a77-16cd4b901bd4-pvc-c1bba2f4-4622-4f32-81bf-4b308180c6d2\") pod \"test-pod-1\" (UID: \"53a4b892-c94b-441f-9a77-16cd4b901bd4\") " pod="default/test-pod-1" Aug 5 22:25:32.874598 kubelet[1694]: I0805 22:25:32.874601 1694 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cqhk7\" (UniqueName: \"kubernetes.io/projected/53a4b892-c94b-441f-9a77-16cd4b901bd4-kube-api-access-cqhk7\") pod \"test-pod-1\" (UID: \"53a4b892-c94b-441f-9a77-16cd4b901bd4\") " pod="default/test-pod-1" Aug 5 22:25:32.997272 kernel: FS-Cache: Loaded Aug 5 22:25:33.020852 kernel: RPC: Registered named UNIX socket transport module. Aug 5 22:25:33.020973 kernel: RPC: Registered udp transport module. Aug 5 22:25:33.020991 kernel: RPC: Registered tcp transport module. Aug 5 22:25:33.021007 kernel: RPC: Registered tcp-with-tls transport module. Aug 5 22:25:33.022170 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 5 22:25:33.199825 kernel: NFS: Registering the id_resolver key type Aug 5 22:25:33.199978 kernel: Key type id_resolver registered Aug 5 22:25:33.199999 kernel: Key type id_legacy registered Aug 5 22:25:33.222011 nfsidmap[2727]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 5 22:25:33.225354 nfsidmap[2730]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 5 22:25:33.396861 containerd[1427]: time="2024-08-05T22:25:33.396715661Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:53a4b892-c94b-441f-9a77-16cd4b901bd4,Namespace:default,Attempt:0,}" Aug 5 22:25:33.411472 systemd-networkd[1377]: vethe9ea9757: Link UP Aug 5 22:25:33.414495 kernel: cni0: port 3(vethe9ea9757) entered blocking state Aug 5 22:25:33.414526 kernel: cni0: port 3(vethe9ea9757) entered disabled state Aug 5 22:25:33.414541 kernel: vethe9ea9757: entered allmulticast mode Aug 5 22:25:33.414555 kernel: vethe9ea9757: entered promiscuous mode Aug 5 22:25:33.419648 systemd-networkd[1377]: vethe9ea9757: Gained carrier Aug 5 22:25:33.419836 kernel: cni0: port 3(vethe9ea9757) entered blocking state Aug 5 22:25:33.419877 kernel: cni0: port 3(vethe9ea9757) entered forwarding state Aug 5 22:25:33.421255 containerd[1427]: 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)(0x4000012938), "name":"cbr0", "type":"bridge"} Aug 5 22:25:33.421255 containerd[1427]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:25:33.435482 containerd[1427]: {"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="2024-08-05T22:25:33.435406666Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:25:33.435482 containerd[1427]: time="2024-08-05T22:25:33.435452467Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:33.435482 containerd[1427]: time="2024-08-05T22:25:33.435465587Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:25:33.435482 containerd[1427]: time="2024-08-05T22:25:33.435475027Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:25:33.455956 systemd[1]: Started cri-containerd-a6c67f3ef15677fcbc954176b645d1c04cc69fbfde18b5903aeaa8bac0de6ed9.scope - libcontainer container a6c67f3ef15677fcbc954176b645d1c04cc69fbfde18b5903aeaa8bac0de6ed9. Aug 5 22:25:33.465352 systemd-resolved[1303]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 22:25:33.481034 containerd[1427]: time="2024-08-05T22:25:33.480982818Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:53a4b892-c94b-441f-9a77-16cd4b901bd4,Namespace:default,Attempt:0,} returns sandbox id \"a6c67f3ef15677fcbc954176b645d1c04cc69fbfde18b5903aeaa8bac0de6ed9\"" Aug 5 22:25:33.482477 containerd[1427]: time="2024-08-05T22:25:33.482442561Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 5 22:25:33.712822 containerd[1427]: time="2024-08-05T22:25:33.712756322Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:25:33.713444 containerd[1427]: time="2024-08-05T22:25:33.713408772Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Aug 5 22:25:33.716180 containerd[1427]: time="2024-08-05T22:25:33.716108294Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"67636951\" in 233.619252ms" Aug 5 22:25:33.716180 containerd[1427]: time="2024-08-05T22:25:33.716175256Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\"" Aug 5 22:25:33.717643 containerd[1427]: time="2024-08-05T22:25:33.717619718Z" level=info msg="CreateContainer within sandbox \"a6c67f3ef15677fcbc954176b645d1c04cc69fbfde18b5903aeaa8bac0de6ed9\" for container &ContainerMetadata{Name:test,Attempt:0,}" Aug 5 22:25:33.727424 containerd[1427]: time="2024-08-05T22:25:33.727379911Z" level=info msg="CreateContainer within sandbox \"a6c67f3ef15677fcbc954176b645d1c04cc69fbfde18b5903aeaa8bac0de6ed9\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"cdacaf1621f3ce7f6c5e577ef88003a9e294734af02e62a6cd3b6e75add9ab36\"" Aug 5 22:25:33.727848 containerd[1427]: time="2024-08-05T22:25:33.727825638Z" level=info msg="StartContainer for \"cdacaf1621f3ce7f6c5e577ef88003a9e294734af02e62a6cd3b6e75add9ab36\"" Aug 5 22:25:33.729383 kubelet[1694]: E0805 22:25:33.729345 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:33.757969 systemd[1]: Started cri-containerd-cdacaf1621f3ce7f6c5e577ef88003a9e294734af02e62a6cd3b6e75add9ab36.scope - libcontainer container cdacaf1621f3ce7f6c5e577ef88003a9e294734af02e62a6cd3b6e75add9ab36. Aug 5 22:25:33.778161 containerd[1427]: time="2024-08-05T22:25:33.778115544Z" level=info msg="StartContainer for \"cdacaf1621f3ce7f6c5e577ef88003a9e294734af02e62a6cd3b6e75add9ab36\" returns successfully" Aug 5 22:25:33.905445 kubelet[1694]: I0805 22:25:33.905346 1694 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.67107167 podCreationTimestamp="2024-08-05 22:25:19 +0000 UTC" firstStartedPulling="2024-08-05 22:25:33.482144877 +0000 UTC m=+33.483911681" lastFinishedPulling="2024-08-05 22:25:33.716380379 +0000 UTC m=+33.718147223" observedRunningTime="2024-08-05 22:25:33.905005328 +0000 UTC m=+33.906772172" watchObservedRunningTime="2024-08-05 22:25:33.905307212 +0000 UTC m=+33.907074056" Aug 5 22:25:34.730498 kubelet[1694]: E0805 22:25:34.730448 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:35.006969 systemd-networkd[1377]: vethe9ea9757: Gained IPv6LL Aug 5 22:25:35.731028 kubelet[1694]: E0805 22:25:35.730986 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:36.731544 kubelet[1694]: E0805 22:25:36.731497 1694 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:25:37.124298 update_engine[1419]: I0805 22:25:37.124148 1419 update_attempter.cc:509] Updating boot flags... Aug 5 22:25:37.151822 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (2898) Aug 5 22:25:37.176818 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (2897)