Jul 2 00:16:33.899520 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 2 00:16:33.899543 kernel: Linux version 6.6.36-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 Jul 1 22:48:46 -00 2024 Jul 2 00:16:33.899553 kernel: KASLR enabled Jul 2 00:16:33.899559 kernel: efi: EFI v2.7 by EDK II Jul 2 00:16:33.899565 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb8fd018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Jul 2 00:16:33.899571 kernel: random: crng init done Jul 2 00:16:33.899578 kernel: ACPI: Early table checksum verification disabled Jul 2 00:16:33.899584 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Jul 2 00:16:33.899591 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 2 00:16:33.899598 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899605 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899611 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899617 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899623 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899631 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899639 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899645 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899652 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:16:33.899658 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 2 00:16:33.899665 kernel: NUMA: Failed to initialise from firmware Jul 2 00:16:33.899671 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:16:33.899678 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Jul 2 00:16:33.899684 kernel: Zone ranges: Jul 2 00:16:33.899690 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:16:33.899696 kernel: DMA32 empty Jul 2 00:16:33.899711 kernel: Normal empty Jul 2 00:16:33.899718 kernel: Movable zone start for each node Jul 2 00:16:33.899724 kernel: Early memory node ranges Jul 2 00:16:33.899731 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Jul 2 00:16:33.899737 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Jul 2 00:16:33.899744 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Jul 2 00:16:33.899750 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Jul 2 00:16:33.899756 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Jul 2 00:16:33.899763 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Jul 2 00:16:33.899769 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Jul 2 00:16:33.899775 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:16:33.899782 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 2 00:16:33.899790 kernel: psci: probing for conduit method from ACPI. Jul 2 00:16:33.899797 kernel: psci: PSCIv1.1 detected in firmware. Jul 2 00:16:33.899803 kernel: psci: Using standard PSCI v0.2 function IDs Jul 2 00:16:33.899812 kernel: psci: Trusted OS migration not required Jul 2 00:16:33.899819 kernel: psci: SMC Calling Convention v1.1 Jul 2 00:16:33.899826 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 2 00:16:33.899835 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Jul 2 00:16:33.899842 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Jul 2 00:16:33.899849 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 2 00:16:33.899855 kernel: Detected PIPT I-cache on CPU0 Jul 2 00:16:33.899862 kernel: CPU features: detected: GIC system register CPU interface Jul 2 00:16:33.899869 kernel: CPU features: detected: Hardware dirty bit management Jul 2 00:16:33.899876 kernel: CPU features: detected: Spectre-v4 Jul 2 00:16:33.899883 kernel: CPU features: detected: Spectre-BHB Jul 2 00:16:33.899889 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 2 00:16:33.899896 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 2 00:16:33.899905 kernel: CPU features: detected: ARM erratum 1418040 Jul 2 00:16:33.899911 kernel: alternatives: applying boot alternatives Jul 2 00:16:33.899919 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=894d8ea3debe01ca4faf80384c3adbf31dc72d8c1b6ccdad26befbaf28696295 Jul 2 00:16:33.899927 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 2 00:16:33.899933 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 2 00:16:33.899940 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 2 00:16:33.899947 kernel: Fallback order for Node 0: 0 Jul 2 00:16:33.899953 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Jul 2 00:16:33.899960 kernel: Policy zone: DMA Jul 2 00:16:33.899967 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 2 00:16:33.899973 kernel: software IO TLB: area num 4. Jul 2 00:16:33.899982 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Jul 2 00:16:33.899989 kernel: Memory: 2386852K/2572288K available (10240K kernel code, 2182K rwdata, 8072K rodata, 39040K init, 897K bss, 185436K reserved, 0K cma-reserved) Jul 2 00:16:33.899996 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 2 00:16:33.900003 kernel: trace event string verifier disabled Jul 2 00:16:33.900009 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 2 00:16:33.900016 kernel: rcu: RCU event tracing is enabled. Jul 2 00:16:33.900023 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 2 00:16:33.900041 kernel: Trampoline variant of Tasks RCU enabled. Jul 2 00:16:33.900048 kernel: Tracing variant of Tasks RCU enabled. Jul 2 00:16:33.900055 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 2 00:16:33.900062 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 2 00:16:33.900069 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 2 00:16:33.900079 kernel: GICv3: 256 SPIs implemented Jul 2 00:16:33.900086 kernel: GICv3: 0 Extended SPIs implemented Jul 2 00:16:33.900092 kernel: Root IRQ handler: gic_handle_irq Jul 2 00:16:33.900099 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Jul 2 00:16:33.900106 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 2 00:16:33.900112 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 2 00:16:33.900119 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Jul 2 00:16:33.900126 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Jul 2 00:16:33.900133 kernel: GICv3: using LPI property table @0x00000000400f0000 Jul 2 00:16:33.900140 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Jul 2 00:16:33.900147 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 2 00:16:33.900155 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:16:33.900162 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 2 00:16:33.900170 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 2 00:16:33.900177 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 2 00:16:33.900184 kernel: arm-pv: using stolen time PV Jul 2 00:16:33.900191 kernel: Console: colour dummy device 80x25 Jul 2 00:16:33.900198 kernel: ACPI: Core revision 20230628 Jul 2 00:16:33.900205 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 2 00:16:33.900212 kernel: pid_max: default: 32768 minimum: 301 Jul 2 00:16:33.900219 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Jul 2 00:16:33.900228 kernel: SELinux: Initializing. Jul 2 00:16:33.900235 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 2 00:16:33.900242 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 2 00:16:33.900249 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Jul 2 00:16:33.900256 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Jul 2 00:16:33.900263 kernel: rcu: Hierarchical SRCU implementation. Jul 2 00:16:33.900271 kernel: rcu: Max phase no-delay instances is 400. Jul 2 00:16:33.900278 kernel: Platform MSI: ITS@0x8080000 domain created Jul 2 00:16:33.900285 kernel: PCI/MSI: ITS@0x8080000 domain created Jul 2 00:16:33.900293 kernel: Remapping and enabling EFI services. Jul 2 00:16:33.900300 kernel: smp: Bringing up secondary CPUs ... Jul 2 00:16:33.900308 kernel: Detected PIPT I-cache on CPU1 Jul 2 00:16:33.900315 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 2 00:16:33.900322 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Jul 2 00:16:33.900329 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:16:33.900336 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 2 00:16:33.900343 kernel: Detected PIPT I-cache on CPU2 Jul 2 00:16:33.900350 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 2 00:16:33.900358 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Jul 2 00:16:33.900367 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:16:33.900374 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 2 00:16:33.900386 kernel: Detected PIPT I-cache on CPU3 Jul 2 00:16:33.900395 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 2 00:16:33.900403 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Jul 2 00:16:33.900410 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:16:33.900417 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 2 00:16:33.900425 kernel: smp: Brought up 1 node, 4 CPUs Jul 2 00:16:33.900432 kernel: SMP: Total of 4 processors activated. Jul 2 00:16:33.900441 kernel: CPU features: detected: 32-bit EL0 Support Jul 2 00:16:33.900449 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 2 00:16:33.900456 kernel: CPU features: detected: Common not Private translations Jul 2 00:16:33.900464 kernel: CPU features: detected: CRC32 instructions Jul 2 00:16:33.900471 kernel: CPU features: detected: Enhanced Virtualization Traps Jul 2 00:16:33.900479 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 2 00:16:33.900486 kernel: CPU features: detected: LSE atomic instructions Jul 2 00:16:33.900494 kernel: CPU features: detected: Privileged Access Never Jul 2 00:16:33.900503 kernel: CPU features: detected: RAS Extension Support Jul 2 00:16:33.900510 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 2 00:16:33.900518 kernel: CPU: All CPU(s) started at EL1 Jul 2 00:16:33.900525 kernel: alternatives: applying system-wide alternatives Jul 2 00:16:33.900533 kernel: devtmpfs: initialized Jul 2 00:16:33.900540 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 2 00:16:33.900548 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 2 00:16:33.900555 kernel: pinctrl core: initialized pinctrl subsystem Jul 2 00:16:33.900563 kernel: SMBIOS 3.0.0 present. Jul 2 00:16:33.900594 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Jul 2 00:16:33.900603 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 2 00:16:33.900611 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 2 00:16:33.900618 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 2 00:16:33.900626 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 2 00:16:33.900633 kernel: audit: initializing netlink subsys (disabled) Jul 2 00:16:33.900641 kernel: audit: type=2000 audit(0.023:1): state=initialized audit_enabled=0 res=1 Jul 2 00:16:33.900649 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 2 00:16:33.900656 kernel: cpuidle: using governor menu Jul 2 00:16:33.900666 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 2 00:16:33.900673 kernel: ASID allocator initialised with 32768 entries Jul 2 00:16:33.900681 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 2 00:16:33.900688 kernel: Serial: AMBA PL011 UART driver Jul 2 00:16:33.900696 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jul 2 00:16:33.900707 kernel: Modules: 0 pages in range for non-PLT usage Jul 2 00:16:33.900714 kernel: Modules: 509120 pages in range for PLT usage Jul 2 00:16:33.900722 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 2 00:16:33.900729 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jul 2 00:16:33.900739 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jul 2 00:16:33.900746 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jul 2 00:16:33.900754 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 2 00:16:33.900761 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jul 2 00:16:33.900769 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jul 2 00:16:33.900776 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jul 2 00:16:33.900783 kernel: ACPI: Added _OSI(Module Device) Jul 2 00:16:33.900791 kernel: ACPI: Added _OSI(Processor Device) Jul 2 00:16:33.900798 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jul 2 00:16:33.900807 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 2 00:16:33.900814 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 2 00:16:33.900822 kernel: ACPI: Interpreter enabled Jul 2 00:16:33.900829 kernel: ACPI: Using GIC for interrupt routing Jul 2 00:16:33.900837 kernel: ACPI: MCFG table detected, 1 entries Jul 2 00:16:33.900844 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 2 00:16:33.900852 kernel: printk: console [ttyAMA0] enabled Jul 2 00:16:33.900859 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 2 00:16:33.901004 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 2 00:16:33.901095 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 2 00:16:33.901166 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 2 00:16:33.901234 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 2 00:16:33.901302 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 2 00:16:33.901312 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 2 00:16:33.901320 kernel: PCI host bridge to bus 0000:00 Jul 2 00:16:33.901404 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 2 00:16:33.901475 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 2 00:16:33.901540 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 2 00:16:33.901604 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 2 00:16:33.901689 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Jul 2 00:16:33.901781 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Jul 2 00:16:33.901856 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Jul 2 00:16:33.901930 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Jul 2 00:16:33.902001 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Jul 2 00:16:33.902168 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Jul 2 00:16:33.902252 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Jul 2 00:16:33.902325 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Jul 2 00:16:33.902389 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 2 00:16:33.902452 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 2 00:16:33.902530 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 2 00:16:33.902540 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 2 00:16:33.902548 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 2 00:16:33.902556 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 2 00:16:33.902563 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 2 00:16:33.902571 kernel: iommu: Default domain type: Translated Jul 2 00:16:33.902578 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 2 00:16:33.902586 kernel: efivars: Registered efivars operations Jul 2 00:16:33.902593 kernel: vgaarb: loaded Jul 2 00:16:33.902603 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 2 00:16:33.902611 kernel: VFS: Disk quotas dquot_6.6.0 Jul 2 00:16:33.902619 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 2 00:16:33.902626 kernel: pnp: PnP ACPI init Jul 2 00:16:33.902718 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 2 00:16:33.902730 kernel: pnp: PnP ACPI: found 1 devices Jul 2 00:16:33.902738 kernel: NET: Registered PF_INET protocol family Jul 2 00:16:33.902746 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 2 00:16:33.902757 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 2 00:16:33.902765 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 2 00:16:33.902772 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 2 00:16:33.902780 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jul 2 00:16:33.902788 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 2 00:16:33.902795 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 2 00:16:33.902804 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 2 00:16:33.902811 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 2 00:16:33.902819 kernel: PCI: CLS 0 bytes, default 64 Jul 2 00:16:33.902830 kernel: kvm [1]: HYP mode not available Jul 2 00:16:33.902839 kernel: Initialise system trusted keyrings Jul 2 00:16:33.902846 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 2 00:16:33.902854 kernel: Key type asymmetric registered Jul 2 00:16:33.902861 kernel: Asymmetric key parser 'x509' registered Jul 2 00:16:33.902869 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 2 00:16:33.902881 kernel: io scheduler mq-deadline registered Jul 2 00:16:33.902889 kernel: io scheduler kyber registered Jul 2 00:16:33.902896 kernel: io scheduler bfq registered Jul 2 00:16:33.902907 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 2 00:16:33.902914 kernel: ACPI: button: Power Button [PWRB] Jul 2 00:16:33.902923 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 2 00:16:33.903005 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 2 00:16:33.903016 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 2 00:16:33.903024 kernel: thunder_xcv, ver 1.0 Jul 2 00:16:33.903042 kernel: thunder_bgx, ver 1.0 Jul 2 00:16:33.903049 kernel: nicpf, ver 1.0 Jul 2 00:16:33.903057 kernel: nicvf, ver 1.0 Jul 2 00:16:33.903154 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 2 00:16:33.903220 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-07-02T00:16:33 UTC (1719879393) Jul 2 00:16:33.903230 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 2 00:16:33.903239 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Jul 2 00:16:33.903246 kernel: watchdog: Delayed init of the lockup detector failed: -19 Jul 2 00:16:33.903254 kernel: watchdog: Hard watchdog permanently disabled Jul 2 00:16:33.903262 kernel: NET: Registered PF_INET6 protocol family Jul 2 00:16:33.903269 kernel: Segment Routing with IPv6 Jul 2 00:16:33.903279 kernel: In-situ OAM (IOAM) with IPv6 Jul 2 00:16:33.903287 kernel: NET: Registered PF_PACKET protocol family Jul 2 00:16:33.903295 kernel: Key type dns_resolver registered Jul 2 00:16:33.903302 kernel: registered taskstats version 1 Jul 2 00:16:33.903310 kernel: Loading compiled-in X.509 certificates Jul 2 00:16:33.903318 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.36-flatcar: 60660d9c77cbf90f55b5b3c47931cf5941193eaf' Jul 2 00:16:33.903325 kernel: Key type .fscrypt registered Jul 2 00:16:33.903333 kernel: Key type fscrypt-provisioning registered Jul 2 00:16:33.903341 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 2 00:16:33.903350 kernel: ima: Allocated hash algorithm: sha1 Jul 2 00:16:33.903358 kernel: ima: No architecture policies found Jul 2 00:16:33.903365 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 2 00:16:33.903373 kernel: clk: Disabling unused clocks Jul 2 00:16:33.903381 kernel: Freeing unused kernel memory: 39040K Jul 2 00:16:33.903388 kernel: Run /init as init process Jul 2 00:16:33.903396 kernel: with arguments: Jul 2 00:16:33.903403 kernel: /init Jul 2 00:16:33.903411 kernel: with environment: Jul 2 00:16:33.903419 kernel: HOME=/ Jul 2 00:16:33.903427 kernel: TERM=linux Jul 2 00:16:33.903434 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 2 00:16:33.903444 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) Jul 2 00:16:33.903454 systemd[1]: Detected virtualization kvm. Jul 2 00:16:33.903462 systemd[1]: Detected architecture arm64. Jul 2 00:16:33.903470 systemd[1]: Running in initrd. Jul 2 00:16:33.903478 systemd[1]: No hostname configured, using default hostname. Jul 2 00:16:33.903487 systemd[1]: Hostname set to . Jul 2 00:16:33.903495 systemd[1]: Initializing machine ID from VM UUID. Jul 2 00:16:33.903503 systemd[1]: Queued start job for default target initrd.target. Jul 2 00:16:33.903512 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 2 00:16:33.903520 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 2 00:16:33.903529 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 2 00:16:33.903538 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 2 00:16:33.903551 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 2 00:16:33.903562 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 2 00:16:33.903572 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 2 00:16:33.903580 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 2 00:16:33.903589 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 2 00:16:33.903598 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 2 00:16:33.903605 systemd[1]: Reached target paths.target - Path Units. Jul 2 00:16:33.903615 systemd[1]: Reached target slices.target - Slice Units. Jul 2 00:16:33.903624 systemd[1]: Reached target swap.target - Swaps. Jul 2 00:16:33.903632 systemd[1]: Reached target timers.target - Timer Units. Jul 2 00:16:33.903640 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 2 00:16:33.903648 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 2 00:16:33.903657 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 2 00:16:33.903665 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jul 2 00:16:33.903673 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 2 00:16:33.903681 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 2 00:16:33.903691 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 2 00:16:33.903699 systemd[1]: Reached target sockets.target - Socket Units. Jul 2 00:16:33.903716 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 2 00:16:33.903724 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 2 00:16:33.903733 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 2 00:16:33.903741 systemd[1]: Starting systemd-fsck-usr.service... Jul 2 00:16:33.903748 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 2 00:16:33.903756 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 2 00:16:33.903765 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 2 00:16:33.903776 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 2 00:16:33.903784 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 2 00:16:33.903792 systemd[1]: Finished systemd-fsck-usr.service. Jul 2 00:16:33.903823 systemd-journald[238]: Collecting audit messages is disabled. Jul 2 00:16:33.903847 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 2 00:16:33.903856 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 2 00:16:33.903865 systemd-journald[238]: Journal started Jul 2 00:16:33.903886 systemd-journald[238]: Runtime Journal (/run/log/journal/90d561c156b848d6abc31d8b68b20bcf) is 5.9M, max 47.3M, 41.4M free. Jul 2 00:16:33.890855 systemd-modules-load[239]: Inserted module 'overlay' Jul 2 00:16:33.907681 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 2 00:16:33.907722 systemd[1]: Started systemd-journald.service - Journal Service. Jul 2 00:16:33.907735 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 2 00:16:33.910138 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 2 00:16:33.913169 kernel: Bridge firewalling registered Jul 2 00:16:33.911658 systemd-modules-load[239]: Inserted module 'br_netfilter' Jul 2 00:16:33.913909 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 2 00:16:33.917450 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 2 00:16:33.918892 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 2 00:16:33.921234 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Jul 2 00:16:33.930362 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 2 00:16:33.931766 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 2 00:16:33.935792 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 2 00:16:33.937106 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Jul 2 00:16:33.948205 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 2 00:16:33.950265 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 2 00:16:33.959925 dracut-cmdline[275]: dracut-dracut-053 Jul 2 00:16:33.962775 dracut-cmdline[275]: 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=894d8ea3debe01ca4faf80384c3adbf31dc72d8c1b6ccdad26befbaf28696295 Jul 2 00:16:33.985404 systemd-resolved[280]: Positive Trust Anchors: Jul 2 00:16:33.985426 systemd-resolved[280]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 2 00:16:33.985459 systemd-resolved[280]: 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 Jul 2 00:16:33.991265 systemd-resolved[280]: Defaulting to hostname 'linux'. Jul 2 00:16:33.992302 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 2 00:16:33.994759 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 2 00:16:34.041059 kernel: SCSI subsystem initialized Jul 2 00:16:34.046052 kernel: Loading iSCSI transport class v2.0-870. Jul 2 00:16:34.055062 kernel: iscsi: registered transport (tcp) Jul 2 00:16:34.070058 kernel: iscsi: registered transport (qla4xxx) Jul 2 00:16:34.070078 kernel: QLogic iSCSI HBA Driver Jul 2 00:16:34.117821 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 2 00:16:34.125247 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 2 00:16:34.143382 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 2 00:16:34.143433 kernel: device-mapper: uevent: version 1.0.3 Jul 2 00:16:34.144668 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jul 2 00:16:34.194073 kernel: raid6: neonx8 gen() 15757 MB/s Jul 2 00:16:34.211058 kernel: raid6: neonx4 gen() 15603 MB/s Jul 2 00:16:34.228058 kernel: raid6: neonx2 gen() 13215 MB/s Jul 2 00:16:34.245067 kernel: raid6: neonx1 gen() 10447 MB/s Jul 2 00:16:34.262070 kernel: raid6: int64x8 gen() 6823 MB/s Jul 2 00:16:34.279066 kernel: raid6: int64x4 gen() 7318 MB/s Jul 2 00:16:34.296066 kernel: raid6: int64x2 gen() 6104 MB/s Jul 2 00:16:34.313069 kernel: raid6: int64x1 gen() 5002 MB/s Jul 2 00:16:34.313104 kernel: raid6: using algorithm neonx8 gen() 15757 MB/s Jul 2 00:16:34.330062 kernel: raid6: .... xor() 11740 MB/s, rmw enabled Jul 2 00:16:34.330083 kernel: raid6: using neon recovery algorithm Jul 2 00:16:34.340058 kernel: xor: measuring software checksum speed Jul 2 00:16:34.340086 kernel: 8regs : 19873 MB/sec Jul 2 00:16:34.341320 kernel: 32regs : 19692 MB/sec Jul 2 00:16:34.342059 kernel: arm64_neon : 27215 MB/sec Jul 2 00:16:34.342072 kernel: xor: using function: arm64_neon (27215 MB/sec) Jul 2 00:16:34.402729 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 2 00:16:34.415954 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 2 00:16:34.426233 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 2 00:16:34.438092 systemd-udevd[463]: Using default interface naming scheme 'v255'. Jul 2 00:16:34.441435 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 2 00:16:34.444870 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 2 00:16:34.460157 dracut-pre-trigger[469]: rd.md=0: removing MD RAID activation Jul 2 00:16:34.490942 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 2 00:16:34.499202 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 2 00:16:34.544216 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 2 00:16:34.554480 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 2 00:16:34.566059 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 2 00:16:34.567524 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 2 00:16:34.568952 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 2 00:16:34.570595 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 2 00:16:34.582219 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 2 00:16:34.592270 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Jul 2 00:16:34.606152 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 2 00:16:34.606272 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 2 00:16:34.606285 kernel: GPT:9289727 != 19775487 Jul 2 00:16:34.606295 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 2 00:16:34.606306 kernel: GPT:9289727 != 19775487 Jul 2 00:16:34.606316 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 2 00:16:34.606326 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:16:34.596178 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 2 00:16:34.606474 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 2 00:16:34.606592 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 2 00:16:34.609079 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 2 00:16:34.610024 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 2 00:16:34.610492 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 2 00:16:34.611844 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 2 00:16:34.623879 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 2 00:16:34.625893 kernel: BTRFS: device fsid 2e7aff7f-b51e-4094-8f16-54690a62fb17 devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (522) Jul 2 00:16:34.628066 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (513) Jul 2 00:16:34.638437 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jul 2 00:16:34.640221 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 2 00:16:34.645051 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jul 2 00:16:34.652521 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 2 00:16:34.656169 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jul 2 00:16:34.657047 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jul 2 00:16:34.666223 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 2 00:16:34.668262 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 2 00:16:34.672872 disk-uuid[553]: Primary Header is updated. Jul 2 00:16:34.672872 disk-uuid[553]: Secondary Entries is updated. Jul 2 00:16:34.672872 disk-uuid[553]: Secondary Header is updated. Jul 2 00:16:34.678266 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:16:34.705475 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 2 00:16:35.689045 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:16:35.690083 disk-uuid[554]: The operation has completed successfully. Jul 2 00:16:35.723043 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 2 00:16:35.723139 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 2 00:16:35.734240 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 2 00:16:35.737150 sh[578]: Success Jul 2 00:16:35.754052 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jul 2 00:16:35.782172 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 2 00:16:35.794438 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 2 00:16:35.796601 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 2 00:16:35.806192 kernel: BTRFS info (device dm-0): first mount of filesystem 2e7aff7f-b51e-4094-8f16-54690a62fb17 Jul 2 00:16:35.806240 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:16:35.806252 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jul 2 00:16:35.807553 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jul 2 00:16:35.807575 kernel: BTRFS info (device dm-0): using free space tree Jul 2 00:16:35.811652 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 2 00:16:35.812835 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 2 00:16:35.823202 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 2 00:16:35.824600 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 2 00:16:35.832410 kernel: BTRFS info (device vda6): first mount of filesystem f333e8f9-4cd9-418a-86af-1531564c69c1 Jul 2 00:16:35.832459 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:16:35.832471 kernel: BTRFS info (device vda6): using free space tree Jul 2 00:16:35.835062 kernel: BTRFS info (device vda6): auto enabling async discard Jul 2 00:16:35.844227 systemd[1]: mnt-oem.mount: Deactivated successfully. Jul 2 00:16:35.846044 kernel: BTRFS info (device vda6): last unmount of filesystem f333e8f9-4cd9-418a-86af-1531564c69c1 Jul 2 00:16:35.851817 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 2 00:16:35.862241 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 2 00:16:35.927096 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 2 00:16:35.936203 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 2 00:16:35.955352 systemd-networkd[769]: lo: Link UP Jul 2 00:16:35.955365 systemd-networkd[769]: lo: Gained carrier Jul 2 00:16:35.955588 ignition[671]: Ignition 2.18.0 Jul 2 00:16:35.956076 systemd-networkd[769]: Enumeration completed Jul 2 00:16:35.955594 ignition[671]: Stage: fetch-offline Jul 2 00:16:35.956193 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 2 00:16:35.955627 ignition[671]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:16:35.956677 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 2 00:16:35.955634 ignition[671]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:16:35.956681 systemd-networkd[769]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 00:16:35.955721 ignition[671]: parsed url from cmdline: "" Jul 2 00:16:35.957291 systemd[1]: Reached target network.target - Network. Jul 2 00:16:35.955724 ignition[671]: no config URL provided Jul 2 00:16:35.957736 systemd-networkd[769]: eth0: Link UP Jul 2 00:16:35.955729 ignition[671]: reading system config file "/usr/lib/ignition/user.ign" Jul 2 00:16:35.957740 systemd-networkd[769]: eth0: Gained carrier Jul 2 00:16:35.955738 ignition[671]: no config at "/usr/lib/ignition/user.ign" Jul 2 00:16:35.957747 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 2 00:16:35.955764 ignition[671]: op(1): [started] loading QEMU firmware config module Jul 2 00:16:35.955769 ignition[671]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 2 00:16:35.968110 ignition[671]: op(1): [finished] loading QEMU firmware config module Jul 2 00:16:35.979778 ignition[671]: parsing config with SHA512: d15487639d8e88748044036028823ab69052ac6e4e81ae108c52c3283b83f458ee807eb91544378648e3ca6925ac42039106823df88346788362bc8e1b611834 Jul 2 00:16:35.981108 systemd-networkd[769]: eth0: DHCPv4 address 10.0.0.100/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 2 00:16:35.983578 unknown[671]: fetched base config from "system" Jul 2 00:16:35.983893 ignition[671]: fetch-offline: fetch-offline passed Jul 2 00:16:35.983588 unknown[671]: fetched user config from "qemu" Jul 2 00:16:35.983959 ignition[671]: Ignition finished successfully Jul 2 00:16:35.985440 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 2 00:16:35.986658 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 2 00:16:35.991214 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 2 00:16:36.003366 ignition[776]: Ignition 2.18.0 Jul 2 00:16:36.003377 ignition[776]: Stage: kargs Jul 2 00:16:36.003536 ignition[776]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:16:36.003545 ignition[776]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:16:36.004265 ignition[776]: kargs: kargs passed Jul 2 00:16:36.004310 ignition[776]: Ignition finished successfully Jul 2 00:16:36.009066 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 2 00:16:36.030243 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 2 00:16:36.041080 ignition[784]: Ignition 2.18.0 Jul 2 00:16:36.041092 ignition[784]: Stage: disks Jul 2 00:16:36.041262 ignition[784]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:16:36.041272 ignition[784]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:16:36.043916 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 2 00:16:36.042026 ignition[784]: disks: disks passed Jul 2 00:16:36.042091 ignition[784]: Ignition finished successfully Jul 2 00:16:36.046796 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 2 00:16:36.047918 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 2 00:16:36.049380 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 2 00:16:36.050936 systemd[1]: Reached target sysinit.target - System Initialization. Jul 2 00:16:36.052578 systemd[1]: Reached target basic.target - Basic System. Jul 2 00:16:36.065228 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 2 00:16:36.079104 systemd-fsck[794]: ROOT: clean, 14/553520 files, 52654/553472 blocks Jul 2 00:16:36.082748 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 2 00:16:36.095180 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 2 00:16:36.136866 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 2 00:16:36.138090 kernel: EXT4-fs (vda9): mounted filesystem 95038baa-e9f1-4207-86a5-38a4ce3cff7d r/w with ordered data mode. Quota mode: none. Jul 2 00:16:36.137975 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 2 00:16:36.151189 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 2 00:16:36.153092 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 2 00:16:36.154237 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jul 2 00:16:36.154281 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 2 00:16:36.161485 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (802) Jul 2 00:16:36.161507 kernel: BTRFS info (device vda6): first mount of filesystem f333e8f9-4cd9-418a-86af-1531564c69c1 Jul 2 00:16:36.161527 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:16:36.154304 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 2 00:16:36.163434 kernel: BTRFS info (device vda6): using free space tree Jul 2 00:16:36.162018 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 2 00:16:36.165470 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 2 00:16:36.167374 kernel: BTRFS info (device vda6): auto enabling async discard Jul 2 00:16:36.168113 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 2 00:16:36.221360 initrd-setup-root[826]: cut: /sysroot/etc/passwd: No such file or directory Jul 2 00:16:36.225813 initrd-setup-root[833]: cut: /sysroot/etc/group: No such file or directory Jul 2 00:16:36.230071 initrd-setup-root[840]: cut: /sysroot/etc/shadow: No such file or directory Jul 2 00:16:36.234777 initrd-setup-root[847]: cut: /sysroot/etc/gshadow: No such file or directory Jul 2 00:16:36.319107 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 2 00:16:36.338183 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 2 00:16:36.340705 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 2 00:16:36.347043 kernel: BTRFS info (device vda6): last unmount of filesystem f333e8f9-4cd9-418a-86af-1531564c69c1 Jul 2 00:16:36.363784 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 2 00:16:36.369839 ignition[916]: INFO : Ignition 2.18.0 Jul 2 00:16:36.369839 ignition[916]: INFO : Stage: mount Jul 2 00:16:36.371455 ignition[916]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:16:36.371455 ignition[916]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:16:36.371455 ignition[916]: INFO : mount: mount passed Jul 2 00:16:36.371455 ignition[916]: INFO : Ignition finished successfully Jul 2 00:16:36.374414 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 2 00:16:36.385161 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 2 00:16:36.805720 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 2 00:16:36.815219 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 2 00:16:36.821045 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (929) Jul 2 00:16:36.822608 kernel: BTRFS info (device vda6): first mount of filesystem f333e8f9-4cd9-418a-86af-1531564c69c1 Jul 2 00:16:36.822625 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:16:36.822635 kernel: BTRFS info (device vda6): using free space tree Jul 2 00:16:36.827049 kernel: BTRFS info (device vda6): auto enabling async discard Jul 2 00:16:36.828196 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 2 00:16:36.844982 ignition[947]: INFO : Ignition 2.18.0 Jul 2 00:16:36.844982 ignition[947]: INFO : Stage: files Jul 2 00:16:36.846336 ignition[947]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:16:36.846336 ignition[947]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:16:36.846336 ignition[947]: DEBUG : files: compiled without relabeling support, skipping Jul 2 00:16:36.849192 ignition[947]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 2 00:16:36.849192 ignition[947]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 2 00:16:36.851054 ignition[947]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 2 00:16:36.851054 ignition[947]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 2 00:16:36.851054 ignition[947]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 2 00:16:36.849927 unknown[947]: wrote ssh authorized keys file for user: core Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:16:36.855764 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.28.7-arm64.raw: attempt #1 Jul 2 00:16:37.145686 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Jul 2 00:16:37.422155 ignition[947]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:16:37.422155 ignition[947]: INFO : files: op(8): [started] processing unit "containerd.service" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(8): [finished] processing unit "containerd.service" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(a): [started] processing unit "coreos-metadata.service" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(a): op(b): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(a): op(b): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(a): [finished] processing unit "coreos-metadata.service" Jul 2 00:16:37.424761 ignition[947]: INFO : files: op(c): [started] setting preset to disabled for "coreos-metadata.service" Jul 2 00:16:37.453526 ignition[947]: INFO : files: op(c): op(d): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 2 00:16:37.457655 ignition[947]: INFO : files: op(c): op(d): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 2 00:16:37.458833 ignition[947]: INFO : files: op(c): [finished] setting preset to disabled for "coreos-metadata.service" Jul 2 00:16:37.458833 ignition[947]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 2 00:16:37.458833 ignition[947]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 2 00:16:37.458833 ignition[947]: INFO : files: files passed Jul 2 00:16:37.458833 ignition[947]: INFO : Ignition finished successfully Jul 2 00:16:37.462653 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 2 00:16:37.471231 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 2 00:16:37.472863 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 2 00:16:37.476135 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 2 00:16:37.476248 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 2 00:16:37.480355 systemd-networkd[769]: eth0: Gained IPv6LL Jul 2 00:16:37.481315 initrd-setup-root-after-ignition[975]: grep: /sysroot/oem/oem-release: No such file or directory Jul 2 00:16:37.484163 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 2 00:16:37.484163 initrd-setup-root-after-ignition[977]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 2 00:16:37.487066 initrd-setup-root-after-ignition[981]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 2 00:16:37.487923 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 2 00:16:37.489169 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 2 00:16:37.505251 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 2 00:16:37.527076 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 2 00:16:37.527190 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 2 00:16:37.528814 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 2 00:16:37.530118 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 2 00:16:37.531441 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 2 00:16:37.532193 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 2 00:16:37.547441 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 2 00:16:37.560222 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 2 00:16:37.568176 systemd[1]: Stopped target network.target - Network. Jul 2 00:16:37.568903 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 2 00:16:37.570141 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 2 00:16:37.571578 systemd[1]: Stopped target timers.target - Timer Units. Jul 2 00:16:37.572811 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 2 00:16:37.572933 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 2 00:16:37.574701 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 2 00:16:37.576133 systemd[1]: Stopped target basic.target - Basic System. Jul 2 00:16:37.577335 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 2 00:16:37.578593 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 2 00:16:37.580003 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 2 00:16:37.581473 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 2 00:16:37.582826 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 2 00:16:37.584288 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 2 00:16:37.585707 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 2 00:16:37.586918 systemd[1]: Stopped target swap.target - Swaps. Jul 2 00:16:37.587998 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 2 00:16:37.588138 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 2 00:16:37.589799 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 2 00:16:37.591120 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 2 00:16:37.592509 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 2 00:16:37.592628 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 2 00:16:37.593982 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 2 00:16:37.594110 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 2 00:16:37.596172 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 2 00:16:37.596284 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 2 00:16:37.597627 systemd[1]: Stopped target paths.target - Path Units. Jul 2 00:16:37.598752 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 2 00:16:37.598851 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 2 00:16:37.600211 systemd[1]: Stopped target slices.target - Slice Units. Jul 2 00:16:37.601489 systemd[1]: Stopped target sockets.target - Socket Units. Jul 2 00:16:37.602627 systemd[1]: iscsid.socket: Deactivated successfully. Jul 2 00:16:37.602727 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 2 00:16:37.603902 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 2 00:16:37.603980 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 2 00:16:37.605507 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 2 00:16:37.605613 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 2 00:16:37.606839 systemd[1]: ignition-files.service: Deactivated successfully. Jul 2 00:16:37.606936 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 2 00:16:37.618238 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 2 00:16:37.618908 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 2 00:16:37.619047 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 2 00:16:37.621703 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 2 00:16:37.622614 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 2 00:16:37.623946 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 2 00:16:37.625125 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 2 00:16:37.625348 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 2 00:16:37.627756 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 2 00:16:37.631011 ignition[1002]: INFO : Ignition 2.18.0 Jul 2 00:16:37.631011 ignition[1002]: INFO : Stage: umount Jul 2 00:16:37.631011 ignition[1002]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:16:37.631011 ignition[1002]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:16:37.627957 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 2 00:16:37.636192 ignition[1002]: INFO : umount: umount passed Jul 2 00:16:37.636192 ignition[1002]: INFO : Ignition finished successfully Jul 2 00:16:37.632095 systemd-networkd[769]: eth0: DHCPv6 lease lost Jul 2 00:16:37.634156 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 2 00:16:37.634257 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 2 00:16:37.635715 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 2 00:16:37.635814 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 2 00:16:37.637570 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 2 00:16:37.637671 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 2 00:16:37.641645 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 2 00:16:37.642177 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 2 00:16:37.642271 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 2 00:16:37.645756 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 2 00:16:37.645821 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 2 00:16:37.647136 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 2 00:16:37.647190 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 2 00:16:37.648648 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 2 00:16:37.648701 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 2 00:16:37.649898 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 2 00:16:37.649937 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 2 00:16:37.651498 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 2 00:16:37.651544 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 2 00:16:37.660284 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 2 00:16:37.662243 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 2 00:16:37.662323 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 2 00:16:37.663952 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 2 00:16:37.663999 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 2 00:16:37.666472 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 2 00:16:37.666527 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 2 00:16:37.668347 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 2 00:16:37.668396 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Jul 2 00:16:37.672259 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 2 00:16:37.685767 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 2 00:16:37.685902 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 2 00:16:37.691561 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 2 00:16:37.691680 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 2 00:16:37.695543 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 2 00:16:37.695703 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 2 00:16:37.698369 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 2 00:16:37.698429 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 2 00:16:37.699920 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 2 00:16:37.699951 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 2 00:16:37.701554 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 2 00:16:37.701607 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 2 00:16:37.704448 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 2 00:16:37.704642 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 2 00:16:37.706705 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 2 00:16:37.706757 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 2 00:16:37.709272 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 2 00:16:37.709324 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 2 00:16:37.720492 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 2 00:16:37.721840 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 2 00:16:37.721913 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 2 00:16:37.723662 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 2 00:16:37.723724 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 2 00:16:37.729597 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 2 00:16:37.731109 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 2 00:16:37.732202 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 2 00:16:37.734584 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 2 00:16:37.746281 systemd[1]: Switching root. Jul 2 00:16:37.777651 systemd-journald[238]: Journal stopped Jul 2 00:16:38.538820 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Jul 2 00:16:38.538874 kernel: SELinux: policy capability network_peer_controls=1 Jul 2 00:16:38.538887 kernel: SELinux: policy capability open_perms=1 Jul 2 00:16:38.538897 kernel: SELinux: policy capability extended_socket_class=1 Jul 2 00:16:38.538907 kernel: SELinux: policy capability always_check_network=0 Jul 2 00:16:38.538917 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 2 00:16:38.538927 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 2 00:16:38.538940 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 2 00:16:38.538950 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 2 00:16:38.538960 kernel: audit: type=1403 audit(1719879398.004:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 2 00:16:38.538971 systemd[1]: Successfully loaded SELinux policy in 34.824ms. Jul 2 00:16:38.538991 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 8.799ms. Jul 2 00:16:38.539002 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) Jul 2 00:16:38.539015 systemd[1]: Detected virtualization kvm. Jul 2 00:16:38.539026 systemd[1]: Detected architecture arm64. Jul 2 00:16:38.539052 systemd[1]: Detected first boot. Jul 2 00:16:38.539064 systemd[1]: Initializing machine ID from VM UUID. Jul 2 00:16:38.539075 zram_generator::config[1063]: No configuration found. Jul 2 00:16:38.539086 systemd[1]: Populated /etc with preset unit settings. Jul 2 00:16:38.539097 systemd[1]: Queued start job for default target multi-user.target. Jul 2 00:16:38.539109 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jul 2 00:16:38.539120 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 2 00:16:38.539131 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 2 00:16:38.539142 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 2 00:16:38.539152 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 2 00:16:38.539162 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 2 00:16:38.539173 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 2 00:16:38.539183 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 2 00:16:38.539194 systemd[1]: Created slice user.slice - User and Session Slice. Jul 2 00:16:38.539206 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 2 00:16:38.539220 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 2 00:16:38.539231 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 2 00:16:38.539241 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 2 00:16:38.539251 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 2 00:16:38.539262 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 2 00:16:38.539273 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jul 2 00:16:38.539283 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 2 00:16:38.539294 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 2 00:16:38.539306 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 2 00:16:38.539316 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 2 00:16:38.539326 systemd[1]: Reached target slices.target - Slice Units. Jul 2 00:16:38.539337 systemd[1]: Reached target swap.target - Swaps. Jul 2 00:16:38.539347 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 2 00:16:38.539358 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 2 00:16:38.539368 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 2 00:16:38.539378 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jul 2 00:16:38.539391 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 2 00:16:38.539401 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 2 00:16:38.539411 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 2 00:16:38.539422 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 2 00:16:38.539432 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 2 00:16:38.539442 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 2 00:16:38.539452 systemd[1]: Mounting media.mount - External Media Directory... Jul 2 00:16:38.539462 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 2 00:16:38.539473 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 2 00:16:38.539484 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 2 00:16:38.539496 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 2 00:16:38.539507 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 2 00:16:38.539518 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 2 00:16:38.539528 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 2 00:16:38.539538 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 2 00:16:38.539549 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 2 00:16:38.539559 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 2 00:16:38.539570 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 2 00:16:38.539582 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 2 00:16:38.539593 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 2 00:16:38.539603 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Jul 2 00:16:38.539614 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Jul 2 00:16:38.539624 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 2 00:16:38.539634 kernel: fuse: init (API version 7.39) Jul 2 00:16:38.539644 kernel: loop: module loaded Jul 2 00:16:38.539654 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 2 00:16:38.539665 kernel: ACPI: bus type drm_connector registered Jul 2 00:16:38.539676 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 2 00:16:38.539712 systemd-journald[1141]: Collecting audit messages is disabled. Jul 2 00:16:38.539740 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 2 00:16:38.539752 systemd-journald[1141]: Journal started Jul 2 00:16:38.539773 systemd-journald[1141]: Runtime Journal (/run/log/journal/90d561c156b848d6abc31d8b68b20bcf) is 5.9M, max 47.3M, 41.4M free. Jul 2 00:16:38.543094 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 2 00:16:38.546074 systemd[1]: Started systemd-journald.service - Journal Service. Jul 2 00:16:38.547139 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 2 00:16:38.548068 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 2 00:16:38.549142 systemd[1]: Mounted media.mount - External Media Directory. Jul 2 00:16:38.550271 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 2 00:16:38.551338 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 2 00:16:38.552395 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 2 00:16:38.553604 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 2 00:16:38.555023 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 2 00:16:38.555281 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 2 00:16:38.556643 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:16:38.556811 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 2 00:16:38.557921 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 2 00:16:38.558240 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 2 00:16:38.559420 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 2 00:16:38.560523 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:16:38.560682 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 2 00:16:38.562225 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 2 00:16:38.562382 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 2 00:16:38.563488 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:16:38.563723 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 2 00:16:38.565111 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 2 00:16:38.566365 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 2 00:16:38.567826 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 2 00:16:38.579047 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 2 00:16:38.585111 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 2 00:16:38.587061 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 2 00:16:38.587998 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 2 00:16:38.592207 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 2 00:16:38.597210 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 2 00:16:38.598100 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 00:16:38.600311 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 2 00:16:38.602189 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 2 00:16:38.604101 systemd-journald[1141]: Time spent on flushing to /var/log/journal/90d561c156b848d6abc31d8b68b20bcf is 13.013ms for 824 entries. Jul 2 00:16:38.604101 systemd-journald[1141]: System Journal (/var/log/journal/90d561c156b848d6abc31d8b68b20bcf) is 8.0M, max 195.6M, 187.6M free. Jul 2 00:16:38.633489 systemd-journald[1141]: Received client request to flush runtime journal. Jul 2 00:16:38.604243 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 2 00:16:38.608211 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 2 00:16:38.610555 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 2 00:16:38.611907 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 2 00:16:38.612981 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 2 00:16:38.616803 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 2 00:16:38.618241 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 2 00:16:38.621107 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jul 2 00:16:38.636202 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 2 00:16:38.637352 systemd-tmpfiles[1197]: ACLs are not supported, ignoring. Jul 2 00:16:38.637600 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 2 00:16:38.637673 systemd-tmpfiles[1197]: ACLs are not supported, ignoring. Jul 2 00:16:38.644333 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 2 00:16:38.646922 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 2 00:16:38.650570 udevadm[1204]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Jul 2 00:16:38.669707 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 2 00:16:38.681200 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 2 00:16:38.693645 systemd-tmpfiles[1218]: ACLs are not supported, ignoring. Jul 2 00:16:38.693993 systemd-tmpfiles[1218]: ACLs are not supported, ignoring. Jul 2 00:16:38.698015 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 2 00:16:39.035408 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 2 00:16:39.042310 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 2 00:16:39.068947 systemd-udevd[1228]: Using default interface naming scheme 'v255'. Jul 2 00:16:39.083506 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 2 00:16:39.095621 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 2 00:16:39.102197 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 2 00:16:39.117579 systemd[1]: Found device dev-ttyAMA0.device - /dev/ttyAMA0. Jul 2 00:16:39.125153 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1234) Jul 2 00:16:39.125238 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1229) Jul 2 00:16:39.166926 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 2 00:16:39.181349 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 2 00:16:39.208275 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 2 00:16:39.222110 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jul 2 00:16:39.224468 systemd-networkd[1235]: lo: Link UP Jul 2 00:16:39.224481 systemd-networkd[1235]: lo: Gained carrier Jul 2 00:16:39.225163 systemd-networkd[1235]: Enumeration completed Jul 2 00:16:39.227636 systemd-networkd[1235]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 2 00:16:39.227646 systemd-networkd[1235]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 00:16:39.228263 systemd-networkd[1235]: eth0: Link UP Jul 2 00:16:39.228274 systemd-networkd[1235]: eth0: Gained carrier Jul 2 00:16:39.228287 systemd-networkd[1235]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 2 00:16:39.234274 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jul 2 00:16:39.235524 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 2 00:16:39.238104 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 2 00:16:39.247836 lvm[1265]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 00:16:39.247695 systemd-networkd[1235]: eth0: DHCPv4 address 10.0.0.100/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 2 00:16:39.259600 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 2 00:16:39.275493 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jul 2 00:16:39.276655 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 2 00:16:39.287264 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jul 2 00:16:39.290833 lvm[1278]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 00:16:39.334617 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jul 2 00:16:39.335788 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 2 00:16:39.336749 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). Jul 2 00:16:39.336778 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 2 00:16:39.337516 systemd[1]: Reached target machines.target - Containers. Jul 2 00:16:39.339253 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Jul 2 00:16:39.358187 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 2 00:16:39.360267 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 2 00:16:39.361131 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 2 00:16:39.362138 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 2 00:16:39.364054 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Jul 2 00:16:39.368455 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 2 00:16:39.370027 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 2 00:16:39.379285 kernel: loop0: detected capacity change from 0 to 113672 Jul 2 00:16:39.379384 kernel: block loop0: the capability attribute has been deprecated. Jul 2 00:16:39.379879 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 2 00:16:39.390649 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 2 00:16:39.391279 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 2 00:16:39.392128 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Jul 2 00:16:39.416062 kernel: loop1: detected capacity change from 0 to 193208 Jul 2 00:16:39.444064 kernel: loop2: detected capacity change from 0 to 59672 Jul 2 00:16:39.492064 kernel: loop3: detected capacity change from 0 to 113672 Jul 2 00:16:39.497048 kernel: loop4: detected capacity change from 0 to 193208 Jul 2 00:16:39.507049 kernel: loop5: detected capacity change from 0 to 59672 Jul 2 00:16:39.510016 (sd-merge)[1301]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Jul 2 00:16:39.510415 (sd-merge)[1301]: Merged extensions into '/usr'. Jul 2 00:16:39.516766 systemd[1]: Reloading requested from client PID 1286 ('systemd-sysext') (unit systemd-sysext.service)... Jul 2 00:16:39.516783 systemd[1]: Reloading... Jul 2 00:16:39.556071 zram_generator::config[1324]: No configuration found. Jul 2 00:16:39.608589 ldconfig[1282]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 2 00:16:39.652361 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. Jul 2 00:16:39.696931 systemd[1]: Reloading finished in 179 ms. Jul 2 00:16:39.711858 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 2 00:16:39.713051 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 2 00:16:39.727198 systemd[1]: Starting ensure-sysext.service... Jul 2 00:16:39.728877 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Jul 2 00:16:39.734798 systemd[1]: Reloading requested from client PID 1368 ('systemctl') (unit ensure-sysext.service)... Jul 2 00:16:39.734812 systemd[1]: Reloading... Jul 2 00:16:39.745922 systemd-tmpfiles[1369]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 2 00:16:39.746195 systemd-tmpfiles[1369]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 2 00:16:39.746830 systemd-tmpfiles[1369]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 2 00:16:39.747060 systemd-tmpfiles[1369]: ACLs are not supported, ignoring. Jul 2 00:16:39.747106 systemd-tmpfiles[1369]: ACLs are not supported, ignoring. Jul 2 00:16:39.749384 systemd-tmpfiles[1369]: Detected autofs mount point /boot during canonicalization of boot. Jul 2 00:16:39.749390 systemd-tmpfiles[1369]: Skipping /boot Jul 2 00:16:39.755821 systemd-tmpfiles[1369]: Detected autofs mount point /boot during canonicalization of boot. Jul 2 00:16:39.755835 systemd-tmpfiles[1369]: Skipping /boot Jul 2 00:16:39.773066 zram_generator::config[1399]: No configuration found. Jul 2 00:16:39.862884 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. Jul 2 00:16:39.907906 systemd[1]: Reloading finished in 172 ms. Jul 2 00:16:39.924892 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Jul 2 00:16:39.947263 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Jul 2 00:16:39.949541 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 2 00:16:39.951525 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 2 00:16:39.956220 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 2 00:16:39.958265 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 2 00:16:39.964994 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 2 00:16:39.973894 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 2 00:16:39.980057 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 2 00:16:39.988379 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 2 00:16:39.990212 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 2 00:16:39.991536 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 2 00:16:39.993158 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:16:39.993310 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 2 00:16:39.994749 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:16:39.994913 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 2 00:16:39.996507 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:16:39.996723 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 2 00:16:40.003912 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 2 00:16:40.011970 augenrules[1472]: No rules Jul 2 00:16:40.015548 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 2 00:16:40.020947 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 2 00:16:40.024363 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 2 00:16:40.025311 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 2 00:16:40.027949 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 2 00:16:40.030466 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Jul 2 00:16:40.032572 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 2 00:16:40.034363 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:16:40.034650 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 2 00:16:40.036314 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:16:40.036550 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 2 00:16:40.038376 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:16:40.038602 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 2 00:16:40.041196 systemd-resolved[1442]: Positive Trust Anchors: Jul 2 00:16:40.041213 systemd-resolved[1442]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 2 00:16:40.041244 systemd-resolved[1442]: 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 Jul 2 00:16:40.044227 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 2 00:16:40.046173 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 2 00:16:40.048323 systemd-resolved[1442]: Defaulting to hostname 'linux'. Jul 2 00:16:40.051430 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 2 00:16:40.063435 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 2 00:16:40.065355 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 2 00:16:40.067143 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 2 00:16:40.068931 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 2 00:16:40.069904 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 2 00:16:40.070054 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). Jul 2 00:16:40.070626 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 2 00:16:40.071976 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:16:40.072127 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 2 00:16:40.073500 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:16:40.073677 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 2 00:16:40.075212 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 2 00:16:40.075348 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 2 00:16:40.076749 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:16:40.076949 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 2 00:16:40.080819 systemd[1]: Finished ensure-sysext.service. Jul 2 00:16:40.083585 systemd[1]: Reached target network.target - Network. Jul 2 00:16:40.084520 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 2 00:16:40.085720 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 00:16:40.085788 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 2 00:16:40.105252 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jul 2 00:16:40.150829 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jul 2 00:16:40.152188 systemd-timesyncd[1515]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 2 00:16:40.152238 systemd-timesyncd[1515]: Initial clock synchronization to Tue 2024-07-02 00:16:40.515601 UTC. Jul 2 00:16:40.152367 systemd[1]: Reached target sysinit.target - System Initialization. Jul 2 00:16:40.153244 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 2 00:16:40.154313 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 2 00:16:40.155200 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 2 00:16:40.156233 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 2 00:16:40.156271 systemd[1]: Reached target paths.target - Path Units. Jul 2 00:16:40.156910 systemd[1]: Reached target time-set.target - System Time Set. Jul 2 00:16:40.157832 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 2 00:16:40.158854 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 2 00:16:40.159816 systemd[1]: Reached target timers.target - Timer Units. Jul 2 00:16:40.161264 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 2 00:16:40.163632 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 2 00:16:40.165767 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 2 00:16:40.171087 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 2 00:16:40.171980 systemd[1]: Reached target sockets.target - Socket Units. Jul 2 00:16:40.172742 systemd[1]: Reached target basic.target - Basic System. Jul 2 00:16:40.173781 systemd[1]: System is tainted: cgroupsv1 Jul 2 00:16:40.173828 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 2 00:16:40.173849 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 2 00:16:40.175118 systemd[1]: Starting containerd.service - containerd container runtime... Jul 2 00:16:40.176991 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 2 00:16:40.178749 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 2 00:16:40.183279 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 2 00:16:40.184240 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). Jul 2 00:16:40.185405 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 2 00:16:40.192636 jq[1521]: false Jul 2 00:16:40.195256 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 2 00:16:40.199532 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 2 00:16:40.203217 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 2 00:16:40.206136 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 2 00:16:40.208950 systemd[1]: Starting update-engine.service - Update Engine... Jul 2 00:16:40.210742 dbus-daemon[1520]: [system] SELinux support is enabled Jul 2 00:16:40.215123 extend-filesystems[1523]: Found loop3 Jul 2 00:16:40.215123 extend-filesystems[1523]: Found loop4 Jul 2 00:16:40.215123 extend-filesystems[1523]: Found loop5 Jul 2 00:16:40.215123 extend-filesystems[1523]: Found vda Jul 2 00:16:40.215123 extend-filesystems[1523]: Found vda1 Jul 2 00:16:40.215123 extend-filesystems[1523]: Found vda2 Jul 2 00:16:40.215123 extend-filesystems[1523]: Found vda3 Jul 2 00:16:40.215123 extend-filesystems[1523]: Found usr Jul 2 00:16:40.215123 extend-filesystems[1523]: Found vda4 Jul 2 00:16:40.215123 extend-filesystems[1523]: Found vda6 Jul 2 00:16:40.236117 extend-filesystems[1523]: Found vda7 Jul 2 00:16:40.236117 extend-filesystems[1523]: Found vda9 Jul 2 00:16:40.236117 extend-filesystems[1523]: Checking size of /dev/vda9 Jul 2 00:16:40.236117 extend-filesystems[1523]: Resized partition /dev/vda9 Jul 2 00:16:40.216827 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 2 00:16:40.218478 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 2 00:16:40.224533 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 2 00:16:40.224783 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 2 00:16:40.242205 jq[1538]: true Jul 2 00:16:40.225059 systemd[1]: motdgen.service: Deactivated successfully. Jul 2 00:16:40.225275 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 2 00:16:40.228230 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 2 00:16:40.229338 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 2 00:16:40.247758 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). Jul 2 00:16:40.247820 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 2 00:16:40.250087 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1248) Jul 2 00:16:40.251596 jq[1548]: true Jul 2 00:16:40.252322 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). Jul 2 00:16:40.252358 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 2 00:16:40.257153 extend-filesystems[1554]: resize2fs 1.47.0 (5-Feb-2023) Jul 2 00:16:40.261792 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 2 00:16:40.272469 (ntainerd)[1558]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 2 00:16:40.277196 update_engine[1536]: I0702 00:16:40.276563 1536 main.cc:92] Flatcar Update Engine starting Jul 2 00:16:40.278615 systemd[1]: Started update-engine.service - Update Engine. Jul 2 00:16:40.279189 update_engine[1536]: I0702 00:16:40.278666 1536 update_check_scheduler.cc:74] Next update check in 10m33s Jul 2 00:16:40.283258 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 2 00:16:40.292838 systemd-logind[1535]: Watching system buttons on /dev/input/event0 (Power Button) Jul 2 00:16:40.293213 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 2 00:16:40.293258 systemd-logind[1535]: New seat seat0. Jul 2 00:16:40.296061 systemd[1]: Started systemd-logind.service - User Login Management. Jul 2 00:16:40.314864 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 2 00:16:40.336907 extend-filesystems[1554]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 2 00:16:40.336907 extend-filesystems[1554]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 2 00:16:40.336907 extend-filesystems[1554]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 2 00:16:40.340951 extend-filesystems[1523]: Resized filesystem in /dev/vda9 Jul 2 00:16:40.338170 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 2 00:16:40.338420 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 2 00:16:40.345712 locksmithd[1564]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 2 00:16:40.346871 bash[1576]: Updated "/home/core/.ssh/authorized_keys" Jul 2 00:16:40.348380 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 2 00:16:40.350357 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 2 00:16:40.468905 containerd[1558]: time="2024-07-02T00:16:40.468747120Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Jul 2 00:16:40.488169 systemd-networkd[1235]: eth0: Gained IPv6LL Jul 2 00:16:40.493699 containerd[1558]: time="2024-07-02T00:16:40.493397240Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jul 2 00:16:40.493699 containerd[1558]: time="2024-07-02T00:16:40.493442240Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:16:40.494413 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 2 00:16:40.494815 containerd[1558]: time="2024-07-02T00:16:40.494766040Z" 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.36-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 2 00:16:40.494815 containerd[1558]: time="2024-07-02T00:16:40.494804600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:16:40.495090 containerd[1558]: time="2024-07-02T00:16:40.495066360Z" 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 Jul 2 00:16:40.495116 containerd[1558]: time="2024-07-02T00:16:40.495090160Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 2 00:16:40.495178 containerd[1558]: time="2024-07-02T00:16:40.495163560Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jul 2 00:16:40.495229 containerd[1558]: time="2024-07-02T00:16:40.495214440Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jul 2 00:16:40.495251 containerd[1558]: time="2024-07-02T00:16:40.495230520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 2 00:16:40.495301 containerd[1558]: time="2024-07-02T00:16:40.495287960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:16:40.495709 containerd[1558]: time="2024-07-02T00:16:40.495493400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 2 00:16:40.495709 containerd[1558]: time="2024-07-02T00:16:40.495519600Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 2 00:16:40.495709 containerd[1558]: time="2024-07-02T00:16:40.495531960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:16:40.495709 containerd[1558]: time="2024-07-02T00:16:40.495655560Z" 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 Jul 2 00:16:40.495709 containerd[1558]: time="2024-07-02T00:16:40.495669680Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 2 00:16:40.495838 containerd[1558]: time="2024-07-02T00:16:40.495732760Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 2 00:16:40.495838 containerd[1558]: time="2024-07-02T00:16:40.495747680Z" level=info msg="metadata content store policy set" policy=shared Jul 2 00:16:40.495800 systemd[1]: Reached target network-online.target - Network is Online. Jul 2 00:16:40.502273 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 2 00:16:40.504772 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 2 00:16:40.507926 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 2 00:16:40.514626 containerd[1558]: time="2024-07-02T00:16:40.514576680Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 2 00:16:40.514626 containerd[1558]: time="2024-07-02T00:16:40.514628200Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 2 00:16:40.514763 containerd[1558]: time="2024-07-02T00:16:40.514643800Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 2 00:16:40.514763 containerd[1558]: time="2024-07-02T00:16:40.514677640Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jul 2 00:16:40.514763 containerd[1558]: time="2024-07-02T00:16:40.514744080Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jul 2 00:16:40.514763 containerd[1558]: time="2024-07-02T00:16:40.514757400Z" level=info msg="NRI interface is disabled by configuration." Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.514770640Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.514915960Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.514933680Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.514955520Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.514969280Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.514983600Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515001000Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515015480Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515028400Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515059600Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515074440Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515087440Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515101000Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 2 00:16:40.515727 containerd[1558]: time="2024-07-02T00:16:40.515203000Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515502160Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515527760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515542360Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515568280Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515738400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515760200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515772040Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515784200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515798360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515810800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515822800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515834640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.515962 containerd[1558]: time="2024-07-02T00:16:40.515848320Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.515968280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.515986200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.515998840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.516011680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.516024920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.516063840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.516076920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.516205 containerd[1558]: time="2024-07-02T00:16:40.516089400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 2 00:16:40.518263 containerd[1558]: time="2024-07-02T00:16:40.516560600Z" 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:false] 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:false 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}" Jul 2 00:16:40.518263 containerd[1558]: time="2024-07-02T00:16:40.516626040Z" level=info msg="Connect containerd service" Jul 2 00:16:40.518263 containerd[1558]: time="2024-07-02T00:16:40.516774640Z" level=info msg="using legacy CRI server" Jul 2 00:16:40.518263 containerd[1558]: time="2024-07-02T00:16:40.516790720Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 2 00:16:40.519480 containerd[1558]: time="2024-07-02T00:16:40.519442920Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 2 00:16:40.520465 containerd[1558]: time="2024-07-02T00:16:40.520088560Z" 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" Jul 2 00:16:40.520465 containerd[1558]: time="2024-07-02T00:16:40.520137920Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 2 00:16:40.520465 containerd[1558]: time="2024-07-02T00:16:40.520156920Z" 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 Jul 2 00:16:40.520465 containerd[1558]: time="2024-07-02T00:16:40.520167360Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 2 00:16:40.520465 containerd[1558]: time="2024-07-02T00:16:40.520180360Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520696480Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520702880Z" level=info msg="Start subscribing containerd event" Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520836160Z" level=info msg="Start recovering state" Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520904840Z" level=info msg="Start event monitor" Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520918280Z" level=info msg="Start snapshots syncer" Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520927120Z" level=info msg="Start cni network conf syncer for default" Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520935360Z" level=info msg="Start streaming server" Jul 2 00:16:40.522129 containerd[1558]: time="2024-07-02T00:16:40.520734760Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 2 00:16:40.522584 systemd[1]: Started containerd.service - containerd container runtime. Jul 2 00:16:40.523704 containerd[1558]: time="2024-07-02T00:16:40.523665120Z" level=info msg="containerd successfully booted in 0.056596s" Jul 2 00:16:40.539380 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 2 00:16:40.542305 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 2 00:16:40.542537 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 2 00:16:40.545560 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 2 00:16:40.989747 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 2 00:16:40.993728 (kubelet)[1627]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 2 00:16:41.151322 sshd_keygen[1547]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 2 00:16:41.172002 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 2 00:16:41.184380 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 2 00:16:41.190262 systemd[1]: issuegen.service: Deactivated successfully. Jul 2 00:16:41.190516 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 2 00:16:41.202560 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 2 00:16:41.212057 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 2 00:16:41.215155 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 2 00:16:41.217548 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jul 2 00:16:41.218912 systemd[1]: Reached target getty.target - Login Prompts. Jul 2 00:16:41.220153 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 2 00:16:41.221199 systemd[1]: Startup finished in 4.870s (kernel) + 3.253s (userspace) = 8.124s. Jul 2 00:16:41.504559 kubelet[1627]: E0702 00:16:41.504470 1627 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" Jul 2 00:16:41.507546 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 2 00:16:41.507750 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 2 00:16:46.877068 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 2 00:16:46.889360 systemd[1]: Started sshd@0-10.0.0.100:22-10.0.0.1:57240.service - OpenSSH per-connection server daemon (10.0.0.1:57240). Jul 2 00:16:46.993623 sshd[1660]: Accepted publickey for core from 10.0.0.1 port 57240 ssh2: RSA SHA256:Et/UiMXmFMbY2cyXsriYvaFlh38PhzkKrD1eNEeM82U Jul 2 00:16:46.996849 sshd[1660]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:16:47.006148 systemd-logind[1535]: New session 1 of user core. Jul 2 00:16:47.007080 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 2 00:16:47.016315 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 2 00:16:47.030238 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 2 00:16:47.038391 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 2 00:16:47.041156 (systemd)[1666]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:16:47.113342 systemd[1666]: Queued start job for default target default.target. Jul 2 00:16:47.113716 systemd[1666]: Created slice app.slice - User Application Slice. Jul 2 00:16:47.113752 systemd[1666]: Reached target paths.target - Paths. Jul 2 00:16:47.113763 systemd[1666]: Reached target timers.target - Timers. Jul 2 00:16:47.120183 systemd[1666]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 2 00:16:47.127001 systemd[1666]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 2 00:16:47.127084 systemd[1666]: Reached target sockets.target - Sockets. Jul 2 00:16:47.127098 systemd[1666]: Reached target basic.target - Basic System. Jul 2 00:16:47.127136 systemd[1666]: Reached target default.target - Main User Target. Jul 2 00:16:47.127162 systemd[1666]: Startup finished in 80ms. Jul 2 00:16:47.127714 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 2 00:16:47.129779 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 2 00:16:47.187671 systemd[1]: Started sshd@1-10.0.0.100:22-10.0.0.1:57244.service - OpenSSH per-connection server daemon (10.0.0.1:57244). Jul 2 00:16:47.229339 sshd[1678]: Accepted publickey for core from 10.0.0.1 port 57244 ssh2: RSA SHA256:Et/UiMXmFMbY2cyXsriYvaFlh38PhzkKrD1eNEeM82U Jul 2 00:16:47.230641 sshd[1678]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:16:47.235138 systemd-logind[1535]: New session 2 of user core. Jul 2 00:16:47.244404 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 2 00:16:47.299105 sshd[1678]: pam_unix(sshd:session): session closed for user core Jul 2 00:16:47.312335 systemd[1]: Started sshd@2-10.0.0.100:22-10.0.0.1:57252.service - OpenSSH per-connection server daemon (10.0.0.1:57252). Jul 2 00:16:47.312735 systemd[1]: sshd@1-10.0.0.100:22-10.0.0.1:57244.service: Deactivated successfully. Jul 2 00:16:47.314725 systemd-logind[1535]: Session 2 logged out. Waiting for processes to exit. Jul 2 00:16:47.315253 systemd[1]: session-2.scope: Deactivated successfully. Jul 2 00:16:47.316793 systemd-logind[1535]: Removed session 2. Jul 2 00:16:47.351805 sshd[1683]: Accepted publickey for core from 10.0.0.1 port 57252 ssh2: RSA SHA256:Et/UiMXmFMbY2cyXsriYvaFlh38PhzkKrD1eNEeM82U Jul 2 00:16:47.353300 sshd[1683]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:16:47.357657 systemd-logind[1535]: New session 3 of user core. Jul 2 00:16:47.372006 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 2 00:16:47.426510 sshd[1683]: pam_unix(sshd:session): session closed for user core Jul 2 00:16:47.438342 systemd[1]: Started sshd@3-10.0.0.100:22-10.0.0.1:57254.service - OpenSSH per-connection server daemon (10.0.0.1:57254). Jul 2 00:16:47.438768 systemd[1]: sshd@2-10.0.0.100:22-10.0.0.1:57252.service: Deactivated successfully. Jul 2 00:16:47.441261 systemd[1]: session-3.scope: Deactivated successfully. Jul 2 00:16:47.442898 systemd-logind[1535]: Session 3 logged out. Waiting for processes to exit. Jul 2 00:16:47.443931 systemd-logind[1535]: Removed session 3. Jul 2 00:16:47.472209 sshd[1691]: Accepted publickey for core from 10.0.0.1 port 57254 ssh2: RSA SHA256:Et/UiMXmFMbY2cyXsriYvaFlh38PhzkKrD1eNEeM82U Jul 2 00:16:47.471847 sshd[1691]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:16:47.477237 systemd-logind[1535]: New session 4 of user core. Jul 2 00:16:47.485327 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 2 00:16:47.540887 sshd[1691]: pam_unix(sshd:session): session closed for user core Jul 2 00:16:47.553367 systemd[1]: Started sshd@4-10.0.0.100:22-10.0.0.1:57256.service - OpenSSH per-connection server daemon (10.0.0.1:57256). Jul 2 00:16:47.553795 systemd[1]: sshd@3-10.0.0.100:22-10.0.0.1:57254.service: Deactivated successfully. Jul 2 00:16:47.556425 systemd-logind[1535]: Session 4 logged out. Waiting for processes to exit. Jul 2 00:16:47.557034 systemd[1]: session-4.scope: Deactivated successfully. Jul 2 00:16:47.558809 systemd-logind[1535]: Removed session 4. Jul 2 00:16:47.588491 sshd[1699]: Accepted publickey for core from 10.0.0.1 port 57256 ssh2: RSA SHA256:Et/UiMXmFMbY2cyXsriYvaFlh38PhzkKrD1eNEeM82U Jul 2 00:16:47.589867 sshd[1699]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:16:47.597479 systemd-logind[1535]: New session 5 of user core. Jul 2 00:16:47.608379 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 2 00:16:47.669898 sudo[1706]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 2 00:16:47.670182 sudo[1706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 2 00:16:47.699130 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 2 00:16:47.715164 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 2 00:16:47.715429 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 2 00:16:48.250711 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 2 00:16:48.262321 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 2 00:16:48.282559 systemd[1]: Reloading requested from client PID 1761 ('systemctl') (unit session-5.scope)... Jul 2 00:16:48.282575 systemd[1]: Reloading... Jul 2 00:16:48.339146 zram_generator::config[1794]: No configuration found. Jul 2 00:16:48.473146 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. Jul 2 00:16:48.524971 systemd[1]: Reloading finished in 242 ms. Jul 2 00:16:48.563687 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Jul 2 00:16:48.563752 systemd[1]: kubelet.service: Failed with result 'signal'. Jul 2 00:16:48.564034 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 2 00:16:48.565666 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 2 00:16:48.657515 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 2 00:16:48.662715 (kubelet)[1854]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 2 00:16:48.708426 kubelet[1854]: 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. Jul 2 00:16:48.708426 kubelet[1854]: 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. Jul 2 00:16:48.708426 kubelet[1854]: 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. Jul 2 00:16:48.709339 kubelet[1854]: I0702 00:16:48.709280 1854 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" Jul 2 00:16:49.475398 kubelet[1854]: I0702 00:16:49.475344 1854 server.go:467] "Kubelet version" kubeletVersion="v1.28.7" Jul 2 00:16:49.475398 kubelet[1854]: I0702 00:16:49.475378 1854 server.go:469] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 2 00:16:49.475606 kubelet[1854]: I0702 00:16:49.475578 1854 server.go:895] "Client rotation is on, will bootstrap in background" Jul 2 00:16:49.499222 kubelet[1854]: I0702 00:16:49.499185 1854 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 2 00:16:49.507274 kubelet[1854]: W0702 00:16:49.507233 1854 machine.go:65] Cannot read vendor id correctly, set empty. Jul 2 00:16:49.508352 kubelet[1854]: I0702 00:16:49.508321 1854 server.go:725] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 2 00:16:49.509424 kubelet[1854]: I0702 00:16:49.508683 1854 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 2 00:16:49.509424 kubelet[1854]: I0702 00:16:49.508972 1854 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","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} Jul 2 00:16:49.509424 kubelet[1854]: I0702 00:16:49.508999 1854 topology_manager.go:138] "Creating topology manager with none policy" Jul 2 00:16:49.509424 kubelet[1854]: I0702 00:16:49.509008 1854 container_manager_linux.go:301] "Creating device plugin manager" Jul 2 00:16:49.509424 kubelet[1854]: I0702 00:16:49.509208 1854 state_mem.go:36] "Initialized new in-memory state store" Jul 2 00:16:49.511939 kubelet[1854]: I0702 00:16:49.511590 1854 kubelet.go:393] "Attempting to sync node with API server" Jul 2 00:16:49.511939 kubelet[1854]: I0702 00:16:49.511623 1854 kubelet.go:298] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 2 00:16:49.511939 kubelet[1854]: I0702 00:16:49.511712 1854 kubelet.go:309] "Adding apiserver pod source" Jul 2 00:16:49.511939 kubelet[1854]: I0702 00:16:49.511725 1854 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 2 00:16:49.511939 kubelet[1854]: E0702 00:16:49.511867 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:49.511939 kubelet[1854]: E0702 00:16:49.511910 1854 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:49.513358 kubelet[1854]: I0702 00:16:49.513291 1854 kuberuntime_manager.go:257] "Container runtime initialized" containerRuntime="containerd" version="v1.7.17" apiVersion="v1" Jul 2 00:16:49.514707 kubelet[1854]: W0702 00:16:49.514672 1854 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 2 00:16:49.518102 kubelet[1854]: I0702 00:16:49.518077 1854 server.go:1232] "Started kubelet" Jul 2 00:16:49.519104 kubelet[1854]: I0702 00:16:49.518790 1854 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Jul 2 00:16:49.520335 kubelet[1854]: W0702 00:16:49.520308 1854 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes "10.0.0.100" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 2 00:16:49.520484 kubelet[1854]: E0702 00:16:49.520434 1854 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.100" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 2 00:16:49.520634 kubelet[1854]: W0702 00:16:49.520612 1854 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 Jul 2 00:16:49.520698 kubelet[1854]: E0702 00:16:49.520689 1854 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 Jul 2 00:16:49.521056 kubelet[1854]: I0702 00:16:49.521021 1854 server.go:462] "Adding debug handlers to kubelet server" Jul 2 00:16:49.521420 kubelet[1854]: I0702 00:16:49.521389 1854 ratelimit.go:65] "Setting rate limiting for podresources endpoint" qps=100 burstTokens=10 Jul 2 00:16:49.523450 kubelet[1854]: I0702 00:16:49.521785 1854 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 2 00:16:49.523450 kubelet[1854]: E0702 00:16:49.522159 1854 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" Jul 2 00:16:49.523450 kubelet[1854]: E0702 00:16:49.522187 1854 kubelet.go:1431] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 2 00:16:49.523450 kubelet[1854]: I0702 00:16:49.522627 1854 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 2 00:16:49.523450 kubelet[1854]: I0702 00:16:49.522862 1854 volume_manager.go:291] "Starting Kubelet Volume Manager" Jul 2 00:16:49.523450 kubelet[1854]: E0702 00:16:49.522918 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:49.523450 kubelet[1854]: I0702 00:16:49.523342 1854 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Jul 2 00:16:49.523450 kubelet[1854]: I0702 00:16:49.523416 1854 reconciler_new.go:29] "Reconciler: start to sync state" Jul 2 00:16:49.524496 kubelet[1854]: E0702 00:16:49.523924 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ecd4e7e8", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"Starting", Message:"Starting kubelet.", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 518045160, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 518045160, 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.100"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.524496 kubelet[1854]: E0702 00:16:49.524283 1854 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.100\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Jul 2 00:16:49.524496 kubelet[1854]: W0702 00:16:49.524324 1854 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 Jul 2 00:16:49.524674 kubelet[1854]: E0702 00:16:49.524340 1854 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 Jul 2 00:16:49.524872 kubelet[1854]: E0702 00:16:49.524809 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ed13ee06", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"InvalidDiskCapacity", Message:"invalid capacity 0 on image filesystem", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 522175494, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 522175494, 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.100"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.562345 kubelet[1854]: I0702 00:16:49.562318 1854 cpu_manager.go:214] "Starting CPU manager" policy="none" Jul 2 00:16:49.562345 kubelet[1854]: I0702 00:16:49.562341 1854 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jul 2 00:16:49.562487 kubelet[1854]: I0702 00:16:49.562398 1854 state_mem.go:36] "Initialized new in-memory state store" Jul 2 00:16:49.562865 kubelet[1854]: E0702 00:16:49.562666 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef69db08", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.100 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561361160, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561361160, 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.100"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.563530 kubelet[1854]: E0702 00:16:49.563444 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a0725", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.100 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561372453, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561372453, 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.100"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.564382 kubelet[1854]: E0702 00:16:49.564312 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a1400", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.100 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561375744, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561375744, 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.100"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.621434 kubelet[1854]: I0702 00:16:49.621367 1854 policy_none.go:49] "None policy: Start" Jul 2 00:16:49.622669 kubelet[1854]: I0702 00:16:49.622199 1854 memory_manager.go:169] "Starting memorymanager" policy="None" Jul 2 00:16:49.622669 kubelet[1854]: I0702 00:16:49.622239 1854 state_mem.go:35] "Initializing new in-memory state store" Jul 2 00:16:49.626057 kubelet[1854]: I0702 00:16:49.625514 1854 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.100" Jul 2 00:16:49.626884 kubelet[1854]: E0702 00:16:49.626592 1854 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.100" Jul 2 00:16:49.626884 kubelet[1854]: E0702 00:16:49.626635 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef69db08", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.100 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561361160, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 624887648, 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.100"}': 'events "10.0.0.100.17de3d35ef69db08" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.627523 kubelet[1854]: I0702 00:16:49.627498 1854 manager.go:471] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 2 00:16:49.628067 kubelet[1854]: I0702 00:16:49.627754 1854 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 2 00:16:49.628767 kubelet[1854]: E0702 00:16:49.628733 1854 eviction_manager.go:258] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.100\" not found" Jul 2 00:16:49.628767 kubelet[1854]: E0702 00:16:49.628700 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a0725", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.100 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561372453, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 624901460, 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.100"}': 'events "10.0.0.100.17de3d35ef6a0725" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.629850 kubelet[1854]: E0702 00:16:49.629775 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a1400", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.100 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561375744, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 624906742, 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.100"}': 'events "10.0.0.100.17de3d35ef6a1400" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.630967 kubelet[1854]: E0702 00:16:49.630896 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35f3642211", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeAllocatableEnforced", Message:"Updated Node Allocatable limit across pods", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 628094993, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 628094993, 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.100"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.638358 kubelet[1854]: I0702 00:16:49.638296 1854 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 2 00:16:49.639302 kubelet[1854]: I0702 00:16:49.639283 1854 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 2 00:16:49.639345 kubelet[1854]: I0702 00:16:49.639309 1854 status_manager.go:217] "Starting to sync pod status with apiserver" Jul 2 00:16:49.639345 kubelet[1854]: I0702 00:16:49.639326 1854 kubelet.go:2303] "Starting kubelet main sync loop" Jul 2 00:16:49.639412 kubelet[1854]: E0702 00:16:49.639369 1854 kubelet.go:2327] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Jul 2 00:16:49.643325 kubelet[1854]: W0702 00:16:49.643290 1854 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 Jul 2 00:16:49.643325 kubelet[1854]: E0702 00:16:49.643324 1854 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 Jul 2 00:16:49.725573 kubelet[1854]: E0702 00:16:49.725460 1854 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.100\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="400ms" Jul 2 00:16:49.828052 kubelet[1854]: I0702 00:16:49.827995 1854 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.100" Jul 2 00:16:49.829375 kubelet[1854]: E0702 00:16:49.829288 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef69db08", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.100 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561361160, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 827948505, 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.100"}': 'events "10.0.0.100.17de3d35ef69db08" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.829482 kubelet[1854]: E0702 00:16:49.829404 1854 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.100" Jul 2 00:16:49.830211 kubelet[1854]: E0702 00:16:49.830129 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a0725", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.100 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561372453, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 827963211, 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.100"}': 'events "10.0.0.100.17de3d35ef6a0725" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:49.831120 kubelet[1854]: E0702 00:16:49.831033 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a1400", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.100 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561375744, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 827966136, 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.100"}': 'events "10.0.0.100.17de3d35ef6a1400" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:50.128134 kubelet[1854]: E0702 00:16:50.127970 1854 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.100\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="800ms" Jul 2 00:16:50.230400 kubelet[1854]: I0702 00:16:50.230351 1854 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.100" Jul 2 00:16:50.231766 kubelet[1854]: E0702 00:16:50.231727 1854 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.100" Jul 2 00:16:50.231802 kubelet[1854]: E0702 00:16:50.231717 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef69db08", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.100 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561361160, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 50, 230311252, 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.100"}': 'events "10.0.0.100.17de3d35ef69db08" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:50.232706 kubelet[1854]: E0702 00:16:50.232631 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a0725", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.100 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561372453, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 50, 230318145, 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.100"}': 'events "10.0.0.100.17de3d35ef6a0725" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:50.233562 kubelet[1854]: E0702 00:16:50.233501 1854 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.100.17de3d35ef6a1400", 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.100", UID:"10.0.0.100", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.100 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.100"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 16, 49, 561375744, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 16, 50, 230321348, 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.100"}': 'events "10.0.0.100.17de3d35ef6a1400" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:16:50.395335 kubelet[1854]: W0702 00:16:50.395220 1854 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes "10.0.0.100" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 2 00:16:50.395335 kubelet[1854]: E0702 00:16:50.395252 1854 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.100" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 2 00:16:50.396145 kubelet[1854]: W0702 00:16:50.396102 1854 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 Jul 2 00:16:50.396145 kubelet[1854]: E0702 00:16:50.396130 1854 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 Jul 2 00:16:50.446820 kubelet[1854]: W0702 00:16:50.446748 1854 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 Jul 2 00:16:50.446820 kubelet[1854]: E0702 00:16:50.446786 1854 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 Jul 2 00:16:50.478262 kubelet[1854]: I0702 00:16:50.478185 1854 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 2 00:16:50.512081 kubelet[1854]: E0702 00:16:50.512021 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:50.867701 kubelet[1854]: E0702 00:16:50.867557 1854 csi_plugin.go:295] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.100" not found Jul 2 00:16:50.935541 kubelet[1854]: E0702 00:16:50.935470 1854 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.100\" not found" node="10.0.0.100" Jul 2 00:16:51.033438 kubelet[1854]: I0702 00:16:51.033407 1854 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.100" Jul 2 00:16:51.040685 kubelet[1854]: I0702 00:16:51.040574 1854 kubelet_node_status.go:73] "Successfully registered node" node="10.0.0.100" Jul 2 00:16:51.042429 kubelet[1854]: I0702 00:16:51.042401 1854 kuberuntime_manager.go:1528] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 2 00:16:51.042856 containerd[1558]: time="2024-07-02T00:16:51.042808293Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 2 00:16:51.043248 kubelet[1854]: I0702 00:16:51.043019 1854 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 2 00:16:51.055528 kubelet[1854]: E0702 00:16:51.055495 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.156807 kubelet[1854]: E0702 00:16:51.156551 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.177485 sudo[1706]: pam_unix(sudo:session): session closed for user root Jul 2 00:16:51.180743 sshd[1699]: pam_unix(sshd:session): session closed for user core Jul 2 00:16:51.184616 systemd[1]: sshd@4-10.0.0.100:22-10.0.0.1:57256.service: Deactivated successfully. Jul 2 00:16:51.190505 systemd-logind[1535]: Session 5 logged out. Waiting for processes to exit. Jul 2 00:16:51.190776 systemd[1]: session-5.scope: Deactivated successfully. Jul 2 00:16:51.192313 systemd-logind[1535]: Removed session 5. Jul 2 00:16:51.257321 kubelet[1854]: E0702 00:16:51.257258 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.357850 kubelet[1854]: E0702 00:16:51.357788 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.458806 kubelet[1854]: E0702 00:16:51.458646 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.513870 kubelet[1854]: E0702 00:16:51.513823 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:51.558883 kubelet[1854]: E0702 00:16:51.558821 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.659157 kubelet[1854]: E0702 00:16:51.659099 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.759870 kubelet[1854]: E0702 00:16:51.759729 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.860791 kubelet[1854]: E0702 00:16:51.860713 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:51.963229 kubelet[1854]: E0702 00:16:51.963162 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.063545 kubelet[1854]: E0702 00:16:52.063385 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.164055 kubelet[1854]: E0702 00:16:52.163995 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.265002 kubelet[1854]: E0702 00:16:52.264951 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.365173 kubelet[1854]: E0702 00:16:52.365026 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.465903 kubelet[1854]: E0702 00:16:52.465852 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.514333 kubelet[1854]: E0702 00:16:52.514282 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:52.566132 kubelet[1854]: E0702 00:16:52.566075 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.666436 kubelet[1854]: E0702 00:16:52.666309 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.766470 kubelet[1854]: E0702 00:16:52.766397 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.867061 kubelet[1854]: E0702 00:16:52.867013 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:52.967457 kubelet[1854]: E0702 00:16:52.967319 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:53.067830 kubelet[1854]: E0702 00:16:53.067753 1854 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.100\" not found" Jul 2 00:16:53.515085 kubelet[1854]: I0702 00:16:53.515000 1854 apiserver.go:52] "Watching apiserver" Jul 2 00:16:53.515085 kubelet[1854]: E0702 00:16:53.515027 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:53.537571 kubelet[1854]: I0702 00:16:53.527411 1854 topology_manager.go:215] "Topology Admit Handler" podUID="fc34be18-c72b-4c2d-9a62-5080545d1577" podNamespace="kube-flannel" podName="kube-flannel-ds-frr8z" Jul 2 00:16:53.537571 kubelet[1854]: I0702 00:16:53.527518 1854 topology_manager.go:215] "Topology Admit Handler" podUID="e617a015-7ea6-4865-b98e-ca8294408d10" podNamespace="kube-system" podName="kube-proxy-s2rqv" Jul 2 00:16:53.624332 kubelet[1854]: I0702 00:16:53.624245 1854 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Jul 2 00:16:53.648418 kubelet[1854]: I0702 00:16:53.648046 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/fc34be18-c72b-4c2d-9a62-5080545d1577-run\") pod \"kube-flannel-ds-frr8z\" (UID: \"fc34be18-c72b-4c2d-9a62-5080545d1577\") " pod="kube-flannel/kube-flannel-ds-frr8z" Jul 2 00:16:53.648418 kubelet[1854]: I0702 00:16:53.648087 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/e617a015-7ea6-4865-b98e-ca8294408d10-xtables-lock\") pod \"kube-proxy-s2rqv\" (UID: \"e617a015-7ea6-4865-b98e-ca8294408d10\") " pod="kube-system/kube-proxy-s2rqv" Jul 2 00:16:53.648418 kubelet[1854]: I0702 00:16:53.648110 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-k5g4j\" (UniqueName: \"kubernetes.io/projected/e617a015-7ea6-4865-b98e-ca8294408d10-kube-api-access-k5g4j\") pod \"kube-proxy-s2rqv\" (UID: \"e617a015-7ea6-4865-b98e-ca8294408d10\") " pod="kube-system/kube-proxy-s2rqv" Jul 2 00:16:53.648418 kubelet[1854]: I0702 00:16:53.648131 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/e617a015-7ea6-4865-b98e-ca8294408d10-lib-modules\") pod \"kube-proxy-s2rqv\" (UID: \"e617a015-7ea6-4865-b98e-ca8294408d10\") " pod="kube-system/kube-proxy-s2rqv" Jul 2 00:16:53.648418 kubelet[1854]: I0702 00:16:53.648150 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/fc34be18-c72b-4c2d-9a62-5080545d1577-cni-plugin\") pod \"kube-flannel-ds-frr8z\" (UID: \"fc34be18-c72b-4c2d-9a62-5080545d1577\") " pod="kube-flannel/kube-flannel-ds-frr8z" Jul 2 00:16:53.648634 kubelet[1854]: I0702 00:16:53.648172 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/fc34be18-c72b-4c2d-9a62-5080545d1577-cni\") pod \"kube-flannel-ds-frr8z\" (UID: \"fc34be18-c72b-4c2d-9a62-5080545d1577\") " pod="kube-flannel/kube-flannel-ds-frr8z" Jul 2 00:16:53.648634 kubelet[1854]: I0702 00:16:53.648191 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/fc34be18-c72b-4c2d-9a62-5080545d1577-flannel-cfg\") pod \"kube-flannel-ds-frr8z\" (UID: \"fc34be18-c72b-4c2d-9a62-5080545d1577\") " pod="kube-flannel/kube-flannel-ds-frr8z" Jul 2 00:16:53.648634 kubelet[1854]: I0702 00:16:53.648210 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/fc34be18-c72b-4c2d-9a62-5080545d1577-xtables-lock\") pod \"kube-flannel-ds-frr8z\" (UID: \"fc34be18-c72b-4c2d-9a62-5080545d1577\") " pod="kube-flannel/kube-flannel-ds-frr8z" Jul 2 00:16:53.648634 kubelet[1854]: I0702 00:16:53.648230 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2sc7h\" (UniqueName: \"kubernetes.io/projected/fc34be18-c72b-4c2d-9a62-5080545d1577-kube-api-access-2sc7h\") pod \"kube-flannel-ds-frr8z\" (UID: \"fc34be18-c72b-4c2d-9a62-5080545d1577\") " pod="kube-flannel/kube-flannel-ds-frr8z" Jul 2 00:16:53.648634 kubelet[1854]: I0702 00:16:53.648250 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/e617a015-7ea6-4865-b98e-ca8294408d10-kube-proxy\") pod \"kube-proxy-s2rqv\" (UID: \"e617a015-7ea6-4865-b98e-ca8294408d10\") " pod="kube-system/kube-proxy-s2rqv" Jul 2 00:16:53.836979 kubelet[1854]: E0702 00:16:53.835868 1854 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" Jul 2 00:16:53.836979 kubelet[1854]: E0702 00:16:53.835871 1854 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" Jul 2 00:16:53.839163 containerd[1558]: time="2024-07-02T00:16:53.838155104Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-s2rqv,Uid:e617a015-7ea6-4865-b98e-ca8294408d10,Namespace:kube-system,Attempt:0,}" Jul 2 00:16:53.839163 containerd[1558]: time="2024-07-02T00:16:53.839138906Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-frr8z,Uid:fc34be18-c72b-4c2d-9a62-5080545d1577,Namespace:kube-flannel,Attempt:0,}" Jul 2 00:16:54.341814 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3357782240.mount: Deactivated successfully. Jul 2 00:16:54.349548 containerd[1558]: time="2024-07-02T00:16:54.349450677Z" 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\"}" Jul 2 00:16:54.351540 containerd[1558]: time="2024-07-02T00:16:54.350670608Z" 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\"}" Jul 2 00:16:54.351540 containerd[1558]: time="2024-07-02T00:16:54.351485320Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Jul 2 00:16:54.352311 containerd[1558]: time="2024-07-02T00:16:54.352225480Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 2 00:16:54.353410 containerd[1558]: time="2024-07-02T00:16:54.353350780Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Jul 2 00:16:54.357471 containerd[1558]: time="2024-07-02T00:16:54.354925248Z" 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\"}" Jul 2 00:16:54.359811 containerd[1558]: time="2024-07-02T00:16:54.357413777Z" 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 518.203999ms" Jul 2 00:16:54.360245 containerd[1558]: time="2024-07-02T00:16:54.357812747Z" 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 519.559804ms" Jul 2 00:16:54.482066 containerd[1558]: time="2024-07-02T00:16:54.481474102Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:16:54.482066 containerd[1558]: time="2024-07-02T00:16:54.481581435Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:16:54.482066 containerd[1558]: time="2024-07-02T00:16:54.481604982Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:16:54.482066 containerd[1558]: time="2024-07-02T00:16:54.481619094Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:16:54.483535 containerd[1558]: time="2024-07-02T00:16:54.483200336Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:16:54.483535 containerd[1558]: time="2024-07-02T00:16:54.483258437Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:16:54.483535 containerd[1558]: time="2024-07-02T00:16:54.483272025Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:16:54.483535 containerd[1558]: time="2024-07-02T00:16:54.483281662Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:16:54.515937 kubelet[1854]: E0702 00:16:54.515793 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:54.615281 containerd[1558]: time="2024-07-02T00:16:54.614172127Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-s2rqv,Uid:e617a015-7ea6-4865-b98e-ca8294408d10,Namespace:kube-system,Attempt:0,} returns sandbox id \"876d561bcbe20d522b9ba0fc7fd67542c79532792b03415bd07a80cb8b8ae654\"" Jul 2 00:16:54.616765 kubelet[1854]: E0702 00:16:54.616737 1854 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" Jul 2 00:16:54.617814 containerd[1558]: time="2024-07-02T00:16:54.617601571Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.11\"" Jul 2 00:16:54.618703 containerd[1558]: time="2024-07-02T00:16:54.618670785Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-frr8z,Uid:fc34be18-c72b-4c2d-9a62-5080545d1577,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"356af9b916c11faea29ffd91001f29511d6af714cffce06792cf23fa0f792c86\"" Jul 2 00:16:54.620074 kubelet[1854]: E0702 00:16:54.620010 1854 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" Jul 2 00:16:55.516390 kubelet[1854]: E0702 00:16:55.516344 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:55.627541 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4110282161.mount: Deactivated successfully. Jul 2 00:16:55.812201 containerd[1558]: time="2024-07-02T00:16:55.812068689Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.28.11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:55.812934 containerd[1558]: time="2024-07-02T00:16:55.812888312Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.28.11: active requests=0, bytes read=24772463" Jul 2 00:16:55.813906 containerd[1558]: time="2024-07-02T00:16:55.813864142Z" level=info msg="ImageCreate event name:\"sha256:e195d3cf134bc9d64104f5e82e95fce811d55b1cdc9cb26fb8f52c8d107d1661\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:55.815881 containerd[1558]: time="2024-07-02T00:16:55.815827564Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:ae4b671d4cfc23dd75030bb4490207cd939b3b11a799bcb4119698cd712eb5b4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:55.816569 containerd[1558]: time="2024-07-02T00:16:55.816542337Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.28.11\" with image id \"sha256:e195d3cf134bc9d64104f5e82e95fce811d55b1cdc9cb26fb8f52c8d107d1661\", repo tag \"registry.k8s.io/kube-proxy:v1.28.11\", repo digest \"registry.k8s.io/kube-proxy@sha256:ae4b671d4cfc23dd75030bb4490207cd939b3b11a799bcb4119698cd712eb5b4\", size \"24771480\" in 1.198906099s" Jul 2 00:16:55.816614 containerd[1558]: time="2024-07-02T00:16:55.816569365Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.11\" returns image reference \"sha256:e195d3cf134bc9d64104f5e82e95fce811d55b1cdc9cb26fb8f52c8d107d1661\"" Jul 2 00:16:55.817463 containerd[1558]: time="2024-07-02T00:16:55.817412310Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 2 00:16:55.818932 containerd[1558]: time="2024-07-02T00:16:55.818892691Z" level=info msg="CreateContainer within sandbox \"876d561bcbe20d522b9ba0fc7fd67542c79532792b03415bd07a80cb8b8ae654\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 2 00:16:55.835459 containerd[1558]: time="2024-07-02T00:16:55.835407966Z" level=info msg="CreateContainer within sandbox \"876d561bcbe20d522b9ba0fc7fd67542c79532792b03415bd07a80cb8b8ae654\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"d4d5f2835a1658adc8f5a6dd5e5d5c552d69cc5ae50333cc40b933a45bd817df\"" Jul 2 00:16:55.836267 containerd[1558]: time="2024-07-02T00:16:55.836228355Z" level=info msg="StartContainer for \"d4d5f2835a1658adc8f5a6dd5e5d5c552d69cc5ae50333cc40b933a45bd817df\"" Jul 2 00:16:55.894133 containerd[1558]: time="2024-07-02T00:16:55.893932587Z" level=info msg="StartContainer for \"d4d5f2835a1658adc8f5a6dd5e5d5c552d69cc5ae50333cc40b933a45bd817df\" returns successfully" Jul 2 00:16:56.517683 kubelet[1854]: E0702 00:16:56.517410 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:56.659341 kubelet[1854]: E0702 00:16:56.659300 1854 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" Jul 2 00:16:56.668328 kubelet[1854]: I0702 00:16:56.668281 1854 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-s2rqv" podStartSLOduration=4.468336373 podCreationTimestamp="2024-07-02 00:16:51 +0000 UTC" firstStartedPulling="2024-07-02 00:16:54.617119905 +0000 UTC m=+5.951189056" lastFinishedPulling="2024-07-02 00:16:55.817013133 +0000 UTC m=+7.151082324" observedRunningTime="2024-07-02 00:16:56.66766017 +0000 UTC m=+8.001729281" watchObservedRunningTime="2024-07-02 00:16:56.668229641 +0000 UTC m=+8.002298792" Jul 2 00:16:56.860509 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2934287151.mount: Deactivated successfully. Jul 2 00:16:56.902739 containerd[1558]: time="2024-07-02T00:16:56.902687465Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:56.903582 containerd[1558]: time="2024-07-02T00:16:56.903131692Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Jul 2 00:16:56.905553 containerd[1558]: time="2024-07-02T00:16:56.904529369Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:56.909583 containerd[1558]: time="2024-07-02T00:16:56.909522797Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:56.910514 containerd[1558]: time="2024-07-02T00:16:56.910274095Z" 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.092816639s" Jul 2 00:16:56.910514 containerd[1558]: time="2024-07-02T00:16:56.910308626Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 2 00:16:56.911745 containerd[1558]: time="2024-07-02T00:16:56.911701312Z" level=info msg="CreateContainer within sandbox \"356af9b916c11faea29ffd91001f29511d6af714cffce06792cf23fa0f792c86\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 2 00:16:56.928841 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2536692882.mount: Deactivated successfully. Jul 2 00:16:56.931253 containerd[1558]: time="2024-07-02T00:16:56.931201017Z" level=info msg="CreateContainer within sandbox \"356af9b916c11faea29ffd91001f29511d6af714cffce06792cf23fa0f792c86\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"6fe679ed8c8d4aae62bd92c5115492254bbfcd4d77db44cba966f657e2734af8\"" Jul 2 00:16:56.931792 containerd[1558]: time="2024-07-02T00:16:56.931707181Z" level=info msg="StartContainer for \"6fe679ed8c8d4aae62bd92c5115492254bbfcd4d77db44cba966f657e2734af8\"" Jul 2 00:16:56.983933 containerd[1558]: time="2024-07-02T00:16:56.982159983Z" level=info msg="StartContainer for \"6fe679ed8c8d4aae62bd92c5115492254bbfcd4d77db44cba966f657e2734af8\" returns successfully" Jul 2 00:16:57.126880 containerd[1558]: time="2024-07-02T00:16:57.126737147Z" level=info msg="shim disconnected" id=6fe679ed8c8d4aae62bd92c5115492254bbfcd4d77db44cba966f657e2734af8 namespace=k8s.io Jul 2 00:16:57.126880 containerd[1558]: time="2024-07-02T00:16:57.126790793Z" level=warning msg="cleaning up after shim disconnected" id=6fe679ed8c8d4aae62bd92c5115492254bbfcd4d77db44cba966f657e2734af8 namespace=k8s.io Jul 2 00:16:57.126880 containerd[1558]: time="2024-07-02T00:16:57.126798836Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jul 2 00:16:57.518624 kubelet[1854]: E0702 00:16:57.518508 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:57.662537 kubelet[1854]: E0702 00:16:57.662492 1854 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" Jul 2 00:16:57.662754 kubelet[1854]: E0702 00:16:57.662733 1854 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" Jul 2 00:16:57.663715 containerd[1558]: time="2024-07-02T00:16:57.663676420Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 2 00:16:57.860594 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6fe679ed8c8d4aae62bd92c5115492254bbfcd4d77db44cba966f657e2734af8-rootfs.mount: Deactivated successfully. Jul 2 00:16:58.519527 kubelet[1854]: E0702 00:16:58.519467 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:58.769976 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2487247446.mount: Deactivated successfully. Jul 2 00:16:59.262954 containerd[1558]: time="2024-07-02T00:16:59.262831766Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:59.263623 containerd[1558]: time="2024-07-02T00:16:59.263580790Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874260" Jul 2 00:16:59.264589 containerd[1558]: time="2024-07-02T00:16:59.264551641Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:59.268164 containerd[1558]: time="2024-07-02T00:16:59.268125058Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:16:59.269424 containerd[1558]: time="2024-07-02T00:16:59.269283959Z" 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.605561947s" Jul 2 00:16:59.269424 containerd[1558]: time="2024-07-02T00:16:59.269323641Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 2 00:16:59.271085 containerd[1558]: time="2024-07-02T00:16:59.271051629Z" level=info msg="CreateContainer within sandbox \"356af9b916c11faea29ffd91001f29511d6af714cffce06792cf23fa0f792c86\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 2 00:16:59.285337 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3914753510.mount: Deactivated successfully. Jul 2 00:16:59.288304 containerd[1558]: time="2024-07-02T00:16:59.288263996Z" level=info msg="CreateContainer within sandbox \"356af9b916c11faea29ffd91001f29511d6af714cffce06792cf23fa0f792c86\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"cf76cb9d8248e18d7e7e61a3d3be2aee4014e636d89ad7185f665025a9a020b5\"" Jul 2 00:16:59.288983 containerd[1558]: time="2024-07-02T00:16:59.288934017Z" level=info msg="StartContainer for \"cf76cb9d8248e18d7e7e61a3d3be2aee4014e636d89ad7185f665025a9a020b5\"" Jul 2 00:16:59.337821 containerd[1558]: time="2024-07-02T00:16:59.337783476Z" level=info msg="StartContainer for \"cf76cb9d8248e18d7e7e61a3d3be2aee4014e636d89ad7185f665025a9a020b5\" returns successfully" Jul 2 00:16:59.399057 kubelet[1854]: I0702 00:16:59.398845 1854 kubelet_node_status.go:493] "Fast updating node status as it just became ready" Jul 2 00:16:59.451216 containerd[1558]: time="2024-07-02T00:16:59.451158516Z" level=info msg="shim disconnected" id=cf76cb9d8248e18d7e7e61a3d3be2aee4014e636d89ad7185f665025a9a020b5 namespace=k8s.io Jul 2 00:16:59.451216 containerd[1558]: time="2024-07-02T00:16:59.451213059Z" level=warning msg="cleaning up after shim disconnected" id=cf76cb9d8248e18d7e7e61a3d3be2aee4014e636d89ad7185f665025a9a020b5 namespace=k8s.io Jul 2 00:16:59.451216 containerd[1558]: time="2024-07-02T00:16:59.451221333Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jul 2 00:16:59.520083 kubelet[1854]: E0702 00:16:59.519960 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:16:59.667979 kubelet[1854]: E0702 00:16:59.667950 1854 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" Jul 2 00:16:59.669837 containerd[1558]: time="2024-07-02T00:16:59.669803403Z" level=info msg="CreateContainer within sandbox \"356af9b916c11faea29ffd91001f29511d6af714cffce06792cf23fa0f792c86\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 2 00:16:59.681746 containerd[1558]: time="2024-07-02T00:16:59.681694082Z" level=info msg="CreateContainer within sandbox \"356af9b916c11faea29ffd91001f29511d6af714cffce06792cf23fa0f792c86\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"64e0ecd38f7c30c39434b173b7ddeaed4f4646e68d8bc485a4100deb1098b709\"" Jul 2 00:16:59.682199 containerd[1558]: time="2024-07-02T00:16:59.682177499Z" level=info msg="StartContainer for \"64e0ecd38f7c30c39434b173b7ddeaed4f4646e68d8bc485a4100deb1098b709\"" Jul 2 00:16:59.731373 containerd[1558]: time="2024-07-02T00:16:59.731318430Z" level=info msg="StartContainer for \"64e0ecd38f7c30c39434b173b7ddeaed4f4646e68d8bc485a4100deb1098b709\" returns successfully" Jul 2 00:17:00.282831 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-cf76cb9d8248e18d7e7e61a3d3be2aee4014e636d89ad7185f665025a9a020b5-rootfs.mount: Deactivated successfully. Jul 2 00:17:00.520995 kubelet[1854]: E0702 00:17:00.520946 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:00.671951 kubelet[1854]: E0702 00:17:00.671472 1854 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" Jul 2 00:17:00.686992 kubelet[1854]: I0702 00:17:00.686937 1854 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-frr8z" podStartSLOduration=5.037582696 podCreationTimestamp="2024-07-02 00:16:51 +0000 UTC" firstStartedPulling="2024-07-02 00:16:54.620457822 +0000 UTC m=+5.954526933" lastFinishedPulling="2024-07-02 00:16:59.269768902 +0000 UTC m=+10.603838053" observedRunningTime="2024-07-02 00:17:00.686741069 +0000 UTC m=+12.020810220" watchObservedRunningTime="2024-07-02 00:17:00.686893816 +0000 UTC m=+12.020962927" Jul 2 00:17:00.802847 systemd-networkd[1235]: flannel.1: Link UP Jul 2 00:17:00.802853 systemd-networkd[1235]: flannel.1: Gained carrier Jul 2 00:17:01.521466 kubelet[1854]: E0702 00:17:01.521425 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:01.672667 kubelet[1854]: E0702 00:17:01.672578 1854 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" Jul 2 00:17:01.864616 systemd-networkd[1235]: flannel.1: Gained IPv6LL Jul 2 00:17:02.157947 kubelet[1854]: I0702 00:17:02.157803 1854 topology_manager.go:215] "Topology Admit Handler" podUID="2d928005-db0d-4d78-ad4d-e17adac695ef" podNamespace="default" podName="nginx-deployment-6d5f899847-pv7kd" Jul 2 00:17:02.203068 kubelet[1854]: I0702 00:17:02.203022 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6ff4c\" (UniqueName: \"kubernetes.io/projected/2d928005-db0d-4d78-ad4d-e17adac695ef-kube-api-access-6ff4c\") pod \"nginx-deployment-6d5f899847-pv7kd\" (UID: \"2d928005-db0d-4d78-ad4d-e17adac695ef\") " pod="default/nginx-deployment-6d5f899847-pv7kd" Jul 2 00:17:02.460632 containerd[1558]: time="2024-07-02T00:17:02.460481984Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-pv7kd,Uid:2d928005-db0d-4d78-ad4d-e17adac695ef,Namespace:default,Attempt:0,}" Jul 2 00:17:02.500771 systemd-networkd[1235]: cni0: Link UP Jul 2 00:17:02.500785 systemd-networkd[1235]: cni0: Gained carrier Jul 2 00:17:02.501389 systemd-networkd[1235]: cni0: Lost carrier Jul 2 00:17:02.507630 systemd-networkd[1235]: vethe77995eb: Link UP Jul 2 00:17:02.509532 kernel: cni0: port 1(vethe77995eb) entered blocking state Jul 2 00:17:02.509605 kernel: cni0: port 1(vethe77995eb) entered disabled state Jul 2 00:17:02.509623 kernel: vethe77995eb: entered allmulticast mode Jul 2 00:17:02.509640 kernel: vethe77995eb: entered promiscuous mode Jul 2 00:17:02.510746 kernel: cni0: port 1(vethe77995eb) entered blocking state Jul 2 00:17:02.510823 kernel: cni0: port 1(vethe77995eb) entered forwarding state Jul 2 00:17:02.517176 kernel: cni0: port 1(vethe77995eb) entered disabled state Jul 2 00:17:02.522359 kubelet[1854]: E0702 00:17:02.522324 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:02.530796 systemd-networkd[1235]: vethe77995eb: Gained carrier Jul 2 00:17:02.531056 kernel: cni0: port 1(vethe77995eb) entered blocking state Jul 2 00:17:02.531084 kernel: cni0: port 1(vethe77995eb) entered forwarding state Jul 2 00:17:02.531058 systemd-networkd[1235]: cni0: Gained carrier Jul 2 00:17:02.532311 containerd[1558]: 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"} Jul 2 00:17:02.532311 containerd[1558]: delegateAdd: netconf sent to delegate plugin: Jul 2 00:17:02.560095 containerd[1558]: {"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-07-02T00:17:02.559879613Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:17:02.560095 containerd[1558]: time="2024-07-02T00:17:02.559963603Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:17:02.560095 containerd[1558]: time="2024-07-02T00:17:02.559983016Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:17:02.560095 containerd[1558]: time="2024-07-02T00:17:02.559996934Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:17:02.583241 systemd-resolved[1442]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 2 00:17:02.599529 containerd[1558]: time="2024-07-02T00:17:02.599473379Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-pv7kd,Uid:2d928005-db0d-4d78-ad4d-e17adac695ef,Namespace:default,Attempt:0,} returns sandbox id \"bacf30f8fb76da310235a0bd3758be343551d80b5252883db61018d6aaf55487\"" Jul 2 00:17:02.600980 containerd[1558]: time="2024-07-02T00:17:02.600805514Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 2 00:17:03.522760 kubelet[1854]: E0702 00:17:03.522710 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:04.233224 systemd-networkd[1235]: cni0: Gained IPv6LL Jul 2 00:17:04.264749 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3250975201.mount: Deactivated successfully. Jul 2 00:17:04.523788 kubelet[1854]: E0702 00:17:04.523669 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:04.553175 systemd-networkd[1235]: vethe77995eb: Gained IPv6LL Jul 2 00:17:05.524571 kubelet[1854]: E0702 00:17:05.524530 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:06.525396 kubelet[1854]: E0702 00:17:06.524786 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:07.525163 kubelet[1854]: E0702 00:17:07.525119 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:08.526064 kubelet[1854]: E0702 00:17:08.526007 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:09.511979 kubelet[1854]: E0702 00:17:09.511932 1854 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:09.526184 kubelet[1854]: E0702 00:17:09.526141 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:09.549394 containerd[1558]: time="2024-07-02T00:17:09.549334838Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:17:09.550434 containerd[1558]: time="2024-07-02T00:17:09.550393421Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67659691" Jul 2 00:17:09.551364 containerd[1558]: time="2024-07-02T00:17:09.551278376Z" level=info msg="ImageCreate event name:\"sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:17:09.555123 containerd[1558]: time="2024-07-02T00:17:09.554792970Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:bf28ef5d86aca0cd30a8ef19032ccadc1eada35dc9f14f42f3ccb73974f013de\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:17:09.556260 containerd[1558]: time="2024-07-02T00:17:09.556216907Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:bf28ef5d86aca0cd30a8ef19032ccadc1eada35dc9f14f42f3ccb73974f013de\", size \"67659569\" in 6.955299345s" Jul 2 00:17:09.556424 containerd[1558]: time="2024-07-02T00:17:09.556404309Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653\"" Jul 2 00:17:09.558217 containerd[1558]: time="2024-07-02T00:17:09.558180026Z" level=info msg="CreateContainer within sandbox \"bacf30f8fb76da310235a0bd3758be343551d80b5252883db61018d6aaf55487\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 2 00:17:09.571047 containerd[1558]: time="2024-07-02T00:17:09.570949649Z" level=info msg="CreateContainer within sandbox \"bacf30f8fb76da310235a0bd3758be343551d80b5252883db61018d6aaf55487\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"0759809222da29db6204e32fa292a75224681c1e26b29010ee0fdb973d608ecc\"" Jul 2 00:17:09.571730 containerd[1558]: time="2024-07-02T00:17:09.571468609Z" level=info msg="StartContainer for \"0759809222da29db6204e32fa292a75224681c1e26b29010ee0fdb973d608ecc\"" Jul 2 00:17:09.619639 containerd[1558]: time="2024-07-02T00:17:09.619591311Z" level=info msg="StartContainer for \"0759809222da29db6204e32fa292a75224681c1e26b29010ee0fdb973d608ecc\" returns successfully" Jul 2 00:17:10.527002 kubelet[1854]: E0702 00:17:10.526951 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:11.528137 kubelet[1854]: E0702 00:17:11.528097 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:12.528606 kubelet[1854]: E0702 00:17:12.528563 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:13.529000 kubelet[1854]: E0702 00:17:13.528951 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:13.774019 kubelet[1854]: I0702 00:17:13.773966 1854 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-pv7kd" podStartSLOduration=4.817742599 podCreationTimestamp="2024-07-02 00:17:02 +0000 UTC" firstStartedPulling="2024-07-02 00:17:02.600544237 +0000 UTC m=+13.934613348" lastFinishedPulling="2024-07-02 00:17:09.556730781 +0000 UTC m=+20.890799932" observedRunningTime="2024-07-02 00:17:09.704236914 +0000 UTC m=+21.038306065" watchObservedRunningTime="2024-07-02 00:17:13.773929183 +0000 UTC m=+25.107998334" Jul 2 00:17:13.774200 kubelet[1854]: I0702 00:17:13.774075 1854 topology_manager.go:215] "Topology Admit Handler" podUID="8dcfde82-dc5c-40f8-8a9b-50a4e62fa8f3" podNamespace="default" podName="nfs-server-provisioner-0" Jul 2 00:17:13.871516 kubelet[1854]: I0702 00:17:13.871395 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/8dcfde82-dc5c-40f8-8a9b-50a4e62fa8f3-data\") pod \"nfs-server-provisioner-0\" (UID: \"8dcfde82-dc5c-40f8-8a9b-50a4e62fa8f3\") " pod="default/nfs-server-provisioner-0" Jul 2 00:17:13.871516 kubelet[1854]: I0702 00:17:13.871441 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6r4fm\" (UniqueName: \"kubernetes.io/projected/8dcfde82-dc5c-40f8-8a9b-50a4e62fa8f3-kube-api-access-6r4fm\") pod \"nfs-server-provisioner-0\" (UID: \"8dcfde82-dc5c-40f8-8a9b-50a4e62fa8f3\") " pod="default/nfs-server-provisioner-0" Jul 2 00:17:14.077576 containerd[1558]: time="2024-07-02T00:17:14.077524303Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:8dcfde82-dc5c-40f8-8a9b-50a4e62fa8f3,Namespace:default,Attempt:0,}" Jul 2 00:17:14.103729 systemd-networkd[1235]: veth8384be1d: Link UP Jul 2 00:17:14.105569 kernel: cni0: port 2(veth8384be1d) entered blocking state Jul 2 00:17:14.105637 kernel: cni0: port 2(veth8384be1d) entered disabled state Jul 2 00:17:14.105652 kernel: veth8384be1d: entered allmulticast mode Jul 2 00:17:14.105676 kernel: veth8384be1d: entered promiscuous mode Jul 2 00:17:14.111968 kernel: cni0: port 2(veth8384be1d) entered blocking state Jul 2 00:17:14.112025 kernel: cni0: port 2(veth8384be1d) entered forwarding state Jul 2 00:17:14.112553 systemd-networkd[1235]: veth8384be1d: Gained carrier Jul 2 00:17:14.115399 containerd[1558]: 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"} Jul 2 00:17:14.115399 containerd[1558]: delegateAdd: netconf sent to delegate plugin: Jul 2 00:17:14.134490 containerd[1558]: {"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-07-02T00:17:14.134315802Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:17:14.134490 containerd[1558]: time="2024-07-02T00:17:14.134379537Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:17:14.134490 containerd[1558]: time="2024-07-02T00:17:14.134399715Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:17:14.134490 containerd[1558]: time="2024-07-02T00:17:14.134422334Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:17:14.163615 systemd-resolved[1442]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 2 00:17:14.188447 containerd[1558]: time="2024-07-02T00:17:14.188391853Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:8dcfde82-dc5c-40f8-8a9b-50a4e62fa8f3,Namespace:default,Attempt:0,} returns sandbox id \"7c6d513174cb42ba020167e07222c7975318b0a5ac31d72fb4083fa8edd874e8\"" Jul 2 00:17:14.189886 containerd[1558]: time="2024-07-02T00:17:14.189842958Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 2 00:17:14.529876 kubelet[1854]: E0702 00:17:14.529814 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:15.176211 systemd-networkd[1235]: veth8384be1d: Gained IPv6LL Jul 2 00:17:15.530353 kubelet[1854]: E0702 00:17:15.530318 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:15.836508 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2802541380.mount: Deactivated successfully. Jul 2 00:17:16.534060 kubelet[1854]: E0702 00:17:16.531903 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:17.532817 kubelet[1854]: E0702 00:17:17.532768 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:17.691727 containerd[1558]: time="2024-07-02T00:17:17.691676209Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:17:17.692681 containerd[1558]: time="2024-07-02T00:17:17.692434527Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Jul 2 00:17:17.696390 containerd[1558]: time="2024-07-02T00:17:17.696343966Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:17:17.699350 containerd[1558]: time="2024-07-02T00:17:17.699288054Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:17:17.700615 containerd[1558]: time="2024-07-02T00:17:17.700484494Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.510598901s" Jul 2 00:17:17.700615 containerd[1558]: time="2024-07-02T00:17:17.700526726Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 2 00:17:17.702270 containerd[1558]: time="2024-07-02T00:17:17.702232381Z" level=info msg="CreateContainer within sandbox \"7c6d513174cb42ba020167e07222c7975318b0a5ac31d72fb4083fa8edd874e8\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 2 00:17:17.712857 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3381401740.mount: Deactivated successfully. Jul 2 00:17:17.714196 containerd[1558]: time="2024-07-02T00:17:17.714120014Z" level=info msg="CreateContainer within sandbox \"7c6d513174cb42ba020167e07222c7975318b0a5ac31d72fb4083fa8edd874e8\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"8f9b80e4f53c6540cc83514bd90357ea8e3ed87fdfcebb8f2bacd8e0100b383b\"" Jul 2 00:17:17.715377 containerd[1558]: time="2024-07-02T00:17:17.714459705Z" level=info msg="StartContainer for \"8f9b80e4f53c6540cc83514bd90357ea8e3ed87fdfcebb8f2bacd8e0100b383b\"" Jul 2 00:17:17.733912 systemd[1]: run-containerd-runc-k8s.io-8f9b80e4f53c6540cc83514bd90357ea8e3ed87fdfcebb8f2bacd8e0100b383b-runc.cGggwC.mount: Deactivated successfully. Jul 2 00:17:17.759266 containerd[1558]: time="2024-07-02T00:17:17.759217940Z" level=info msg="StartContainer for \"8f9b80e4f53c6540cc83514bd90357ea8e3ed87fdfcebb8f2bacd8e0100b383b\" returns successfully" Jul 2 00:17:18.533420 kubelet[1854]: E0702 00:17:18.533371 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:19.534560 kubelet[1854]: E0702 00:17:19.534511 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:20.535619 kubelet[1854]: E0702 00:17:20.535567 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:21.536783 kubelet[1854]: E0702 00:17:21.536729 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:22.537011 kubelet[1854]: E0702 00:17:22.536967 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:23.537913 kubelet[1854]: E0702 00:17:23.537839 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:24.538349 kubelet[1854]: E0702 00:17:24.538302 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:25.119750 update_engine[1536]: I0702 00:17:25.119462 1536 update_attempter.cc:509] Updating boot flags... Jul 2 00:17:25.145119 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2866) Jul 2 00:17:25.168300 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2870) Jul 2 00:17:25.538624 kubelet[1854]: E0702 00:17:25.538586 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:26.539205 kubelet[1854]: E0702 00:17:26.539149 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:27.308074 kubelet[1854]: I0702 00:17:27.308004 1854 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.796872607 podCreationTimestamp="2024-07-02 00:17:13 +0000 UTC" firstStartedPulling="2024-07-02 00:17:14.189633575 +0000 UTC m=+25.523702726" lastFinishedPulling="2024-07-02 00:17:17.700720428 +0000 UTC m=+29.034789579" observedRunningTime="2024-07-02 00:17:18.726586355 +0000 UTC m=+30.060655507" watchObservedRunningTime="2024-07-02 00:17:27.30795946 +0000 UTC m=+38.642028571" Jul 2 00:17:27.308243 kubelet[1854]: I0702 00:17:27.308158 1854 topology_manager.go:215] "Topology Admit Handler" podUID="5d30b3bf-1b64-4607-b18d-a3b828fc3333" podNamespace="default" podName="test-pod-1" Jul 2 00:17:27.350309 kubelet[1854]: I0702 00:17:27.350261 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4a5cd2ba-b1da-4c8b-ac59-7c9b1d83c484\" (UniqueName: \"kubernetes.io/nfs/5d30b3bf-1b64-4607-b18d-a3b828fc3333-pvc-4a5cd2ba-b1da-4c8b-ac59-7c9b1d83c484\") pod \"test-pod-1\" (UID: \"5d30b3bf-1b64-4607-b18d-a3b828fc3333\") " pod="default/test-pod-1" Jul 2 00:17:27.350309 kubelet[1854]: I0702 00:17:27.350312 1854 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qx5gf\" (UniqueName: \"kubernetes.io/projected/5d30b3bf-1b64-4607-b18d-a3b828fc3333-kube-api-access-qx5gf\") pod \"test-pod-1\" (UID: \"5d30b3bf-1b64-4607-b18d-a3b828fc3333\") " pod="default/test-pod-1" Jul 2 00:17:27.469290 kernel: FS-Cache: Loaded Jul 2 00:17:27.494288 kernel: RPC: Registered named UNIX socket transport module. Jul 2 00:17:27.494396 kernel: RPC: Registered udp transport module. Jul 2 00:17:27.494417 kernel: RPC: Registered tcp transport module. Jul 2 00:17:27.495051 kernel: RPC: Registered tcp-with-tls transport module. Jul 2 00:17:27.495077 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 2 00:17:27.539635 kubelet[1854]: E0702 00:17:27.539599 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:27.673156 kernel: NFS: Registering the id_resolver key type Jul 2 00:17:27.673267 kernel: Key type id_resolver registered Jul 2 00:17:27.673283 kernel: Key type id_legacy registered Jul 2 00:17:27.699586 nfsidmap[2910]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 2 00:17:27.702811 nfsidmap[2913]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 2 00:17:27.911270 containerd[1558]: time="2024-07-02T00:17:27.911182940Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:5d30b3bf-1b64-4607-b18d-a3b828fc3333,Namespace:default,Attempt:0,}" Jul 2 00:17:27.945573 systemd-networkd[1235]: vethdfb4eb1c: Link UP Jul 2 00:17:27.947270 kernel: cni0: port 3(vethdfb4eb1c) entered blocking state Jul 2 00:17:27.947352 kernel: cni0: port 3(vethdfb4eb1c) entered disabled state Jul 2 00:17:27.947366 kernel: vethdfb4eb1c: entered allmulticast mode Jul 2 00:17:27.948142 kernel: vethdfb4eb1c: entered promiscuous mode Jul 2 00:17:27.953516 kernel: cni0: port 3(vethdfb4eb1c) entered blocking state Jul 2 00:17:27.953638 kernel: cni0: port 3(vethdfb4eb1c) entered forwarding state Jul 2 00:17:27.953719 systemd-networkd[1235]: vethdfb4eb1c: Gained carrier Jul 2 00:17:27.954999 containerd[1558]: 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"} Jul 2 00:17:27.954999 containerd[1558]: delegateAdd: netconf sent to delegate plugin: Jul 2 00:17:27.970849 containerd[1558]: {"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-07-02T00:17:27.970444042Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:17:27.971010 containerd[1558]: time="2024-07-02T00:17:27.970862144Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:17:27.971010 containerd[1558]: time="2024-07-02T00:17:27.970896119Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:17:27.971010 containerd[1558]: time="2024-07-02T00:17:27.970920090Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:17:27.991117 systemd-resolved[1442]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 2 00:17:28.007111 containerd[1558]: time="2024-07-02T00:17:28.007073733Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:5d30b3bf-1b64-4607-b18d-a3b828fc3333,Namespace:default,Attempt:0,} returns sandbox id \"057a9a97ebc46225273f515282d5da05b8437d5b89b280aab04d18ce56721d1b\"" Jul 2 00:17:28.008320 containerd[1558]: time="2024-07-02T00:17:28.008289757Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 2 00:17:28.220512 containerd[1558]: time="2024-07-02T00:17:28.220393750Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 2 00:17:28.220912 containerd[1558]: time="2024-07-02T00:17:28.220865986Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jul 2 00:17:28.223916 containerd[1558]: time="2024-07-02T00:17:28.223863587Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:bf28ef5d86aca0cd30a8ef19032ccadc1eada35dc9f14f42f3ccb73974f013de\", size \"67659569\" in 215.539496ms" Jul 2 00:17:28.223916 containerd[1558]: time="2024-07-02T00:17:28.223899802Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653\"" Jul 2 00:17:28.228477 containerd[1558]: time="2024-07-02T00:17:28.228447366Z" level=info msg="CreateContainer within sandbox \"057a9a97ebc46225273f515282d5da05b8437d5b89b280aab04d18ce56721d1b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 2 00:17:28.238763 containerd[1558]: time="2024-07-02T00:17:28.238681086Z" level=info msg="CreateContainer within sandbox \"057a9a97ebc46225273f515282d5da05b8437d5b89b280aab04d18ce56721d1b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"1a69d0f8b1388b702f2ddebb990db34646eee3a7517a187de764769625e9b875\"" Jul 2 00:17:28.239367 containerd[1558]: time="2024-07-02T00:17:28.239339199Z" level=info msg="StartContainer for \"1a69d0f8b1388b702f2ddebb990db34646eee3a7517a187de764769625e9b875\"" Jul 2 00:17:28.284992 containerd[1558]: time="2024-07-02T00:17:28.284951456Z" level=info msg="StartContainer for \"1a69d0f8b1388b702f2ddebb990db34646eee3a7517a187de764769625e9b875\" returns successfully" Jul 2 00:17:28.540551 kubelet[1854]: E0702 00:17:28.540497 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:28.741490 kubelet[1854]: I0702 00:17:28.741444 1854 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.52532416 podCreationTimestamp="2024-07-02 00:17:14 +0000 UTC" firstStartedPulling="2024-07-02 00:17:28.008076349 +0000 UTC m=+39.342145460" lastFinishedPulling="2024-07-02 00:17:28.224161631 +0000 UTC m=+39.558230782" observedRunningTime="2024-07-02 00:17:28.7407757 +0000 UTC m=+40.074844851" watchObservedRunningTime="2024-07-02 00:17:28.741409482 +0000 UTC m=+40.075478633" Jul 2 00:17:29.128185 systemd-networkd[1235]: vethdfb4eb1c: Gained IPv6LL Jul 2 00:17:29.512209 kubelet[1854]: E0702 00:17:29.512168 1854 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:29.541478 kubelet[1854]: E0702 00:17:29.541436 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:30.542144 kubelet[1854]: E0702 00:17:30.542068 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:17:31.543142 kubelet[1854]: E0702 00:17:31.543088 1854 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"