Aug 5 22:14:19.889458 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Aug 5 22:14:19.889478 kernel: Linux version 6.6.43-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT Mon Aug 5 20:37:57 -00 2024 Aug 5 22:14:19.889487 kernel: KASLR enabled Aug 5 22:14:19.889493 kernel: efi: EFI v2.7 by EDK II Aug 5 22:14:19.889499 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb8fd018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Aug 5 22:14:19.889505 kernel: random: crng init done Aug 5 22:14:19.889511 kernel: ACPI: Early table checksum verification disabled Aug 5 22:14:19.889517 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Aug 5 22:14:19.889524 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Aug 5 22:14:19.889531 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889537 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889543 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889549 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889555 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889563 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889570 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889577 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889583 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:14:19.889589 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Aug 5 22:14:19.889665 kernel: NUMA: Failed to initialise from firmware Aug 5 22:14:19.889675 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 22:14:19.889681 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Aug 5 22:14:19.889690 kernel: Zone ranges: Aug 5 22:14:19.889697 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 22:14:19.889705 kernel: DMA32 empty Aug 5 22:14:19.889715 kernel: Normal empty Aug 5 22:14:19.889721 kernel: Movable zone start for each node Aug 5 22:14:19.889728 kernel: Early memory node ranges Aug 5 22:14:19.889734 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Aug 5 22:14:19.889741 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Aug 5 22:14:19.889747 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Aug 5 22:14:19.889753 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Aug 5 22:14:19.889759 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Aug 5 22:14:19.889766 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Aug 5 22:14:19.889772 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Aug 5 22:14:19.889779 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 22:14:19.889785 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Aug 5 22:14:19.889792 kernel: psci: probing for conduit method from ACPI. Aug 5 22:14:19.889799 kernel: psci: PSCIv1.1 detected in firmware. Aug 5 22:14:19.889805 kernel: psci: Using standard PSCI v0.2 function IDs Aug 5 22:14:19.889814 kernel: psci: Trusted OS migration not required Aug 5 22:14:19.889821 kernel: psci: SMC Calling Convention v1.1 Aug 5 22:14:19.889828 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Aug 5 22:14:19.889836 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Aug 5 22:14:19.889843 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Aug 5 22:14:19.889849 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Aug 5 22:14:19.889856 kernel: Detected PIPT I-cache on CPU0 Aug 5 22:14:19.889863 kernel: CPU features: detected: GIC system register CPU interface Aug 5 22:14:19.889870 kernel: CPU features: detected: Hardware dirty bit management Aug 5 22:14:19.889876 kernel: CPU features: detected: Spectre-v4 Aug 5 22:14:19.889883 kernel: CPU features: detected: Spectre-BHB Aug 5 22:14:19.889890 kernel: CPU features: kernel page table isolation forced ON by KASLR Aug 5 22:14:19.889897 kernel: CPU features: detected: Kernel page table isolation (KPTI) Aug 5 22:14:19.889905 kernel: CPU features: detected: ARM erratum 1418040 Aug 5 22:14:19.889911 kernel: alternatives: applying boot alternatives Aug 5 22:14:19.889919 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=4052403b8e39e55d48e6afcca927358798017aa0d33c868bc3038260a8d9be90 Aug 5 22:14:19.889926 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 5 22:14:19.889933 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 5 22:14:19.889940 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 5 22:14:19.889947 kernel: Fallback order for Node 0: 0 Aug 5 22:14:19.889953 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Aug 5 22:14:19.889960 kernel: Policy zone: DMA Aug 5 22:14:19.889967 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 5 22:14:19.889973 kernel: software IO TLB: area num 4. Aug 5 22:14:19.889982 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Aug 5 22:14:19.889989 kernel: Memory: 2386852K/2572288K available (10240K kernel code, 2182K rwdata, 8072K rodata, 39040K init, 897K bss, 185436K reserved, 0K cma-reserved) Aug 5 22:14:19.889996 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 5 22:14:19.890002 kernel: trace event string verifier disabled Aug 5 22:14:19.890012 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 5 22:14:19.890021 kernel: rcu: RCU event tracing is enabled. Aug 5 22:14:19.890031 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Aug 5 22:14:19.890038 kernel: Trampoline variant of Tasks RCU enabled. Aug 5 22:14:19.890045 kernel: Tracing variant of Tasks RCU enabled. Aug 5 22:14:19.890052 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 5 22:14:19.890059 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 5 22:14:19.890066 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Aug 5 22:14:19.890074 kernel: GICv3: 256 SPIs implemented Aug 5 22:14:19.890081 kernel: GICv3: 0 Extended SPIs implemented Aug 5 22:14:19.890087 kernel: Root IRQ handler: gic_handle_irq Aug 5 22:14:19.890094 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Aug 5 22:14:19.890101 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Aug 5 22:14:19.890108 kernel: ITS [mem 0x08080000-0x0809ffff] Aug 5 22:14:19.890115 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Aug 5 22:14:19.890122 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Aug 5 22:14:19.890128 kernel: GICv3: using LPI property table @0x00000000400f0000 Aug 5 22:14:19.890135 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Aug 5 22:14:19.890142 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 5 22:14:19.890150 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:14:19.890157 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Aug 5 22:14:19.890164 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Aug 5 22:14:19.890171 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Aug 5 22:14:19.890178 kernel: arm-pv: using stolen time PV Aug 5 22:14:19.890185 kernel: Console: colour dummy device 80x25 Aug 5 22:14:19.890192 kernel: ACPI: Core revision 20230628 Aug 5 22:14:19.890199 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Aug 5 22:14:19.890206 kernel: pid_max: default: 32768 minimum: 301 Aug 5 22:14:19.890213 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Aug 5 22:14:19.890220 kernel: SELinux: Initializing. Aug 5 22:14:19.890227 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 22:14:19.890234 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 22:14:19.890241 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:14:19.890249 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:14:19.890255 kernel: rcu: Hierarchical SRCU implementation. Aug 5 22:14:19.890262 kernel: rcu: Max phase no-delay instances is 400. Aug 5 22:14:19.890269 kernel: Platform MSI: ITS@0x8080000 domain created Aug 5 22:14:19.890276 kernel: PCI/MSI: ITS@0x8080000 domain created Aug 5 22:14:19.890284 kernel: Remapping and enabling EFI services. Aug 5 22:14:19.890291 kernel: smp: Bringing up secondary CPUs ... Aug 5 22:14:19.890298 kernel: Detected PIPT I-cache on CPU1 Aug 5 22:14:19.890305 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Aug 5 22:14:19.890312 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Aug 5 22:14:19.890319 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:14:19.890326 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Aug 5 22:14:19.890332 kernel: Detected PIPT I-cache on CPU2 Aug 5 22:14:19.890339 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Aug 5 22:14:19.890346 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Aug 5 22:14:19.890354 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:14:19.890361 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Aug 5 22:14:19.890373 kernel: Detected PIPT I-cache on CPU3 Aug 5 22:14:19.890381 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Aug 5 22:14:19.890389 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Aug 5 22:14:19.890396 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 22:14:19.890410 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Aug 5 22:14:19.890418 kernel: smp: Brought up 1 node, 4 CPUs Aug 5 22:14:19.890425 kernel: SMP: Total of 4 processors activated. Aug 5 22:14:19.890435 kernel: CPU features: detected: 32-bit EL0 Support Aug 5 22:14:19.890442 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Aug 5 22:14:19.890449 kernel: CPU features: detected: Common not Private translations Aug 5 22:14:19.890457 kernel: CPU features: detected: CRC32 instructions Aug 5 22:14:19.890464 kernel: CPU features: detected: Enhanced Virtualization Traps Aug 5 22:14:19.890472 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Aug 5 22:14:19.890479 kernel: CPU features: detected: LSE atomic instructions Aug 5 22:14:19.890486 kernel: CPU features: detected: Privileged Access Never Aug 5 22:14:19.890495 kernel: CPU features: detected: RAS Extension Support Aug 5 22:14:19.890502 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Aug 5 22:14:19.890510 kernel: CPU: All CPU(s) started at EL1 Aug 5 22:14:19.890517 kernel: alternatives: applying system-wide alternatives Aug 5 22:14:19.890524 kernel: devtmpfs: initialized Aug 5 22:14:19.890531 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 5 22:14:19.890539 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 5 22:14:19.890546 kernel: pinctrl core: initialized pinctrl subsystem Aug 5 22:14:19.890554 kernel: SMBIOS 3.0.0 present. Aug 5 22:14:19.890562 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Aug 5 22:14:19.890569 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 5 22:14:19.890577 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Aug 5 22:14:19.890584 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Aug 5 22:14:19.890592 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Aug 5 22:14:19.890605 kernel: audit: initializing netlink subsys (disabled) Aug 5 22:14:19.890622 kernel: audit: type=2000 audit(0.026:1): state=initialized audit_enabled=0 res=1 Aug 5 22:14:19.890631 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 5 22:14:19.890638 kernel: cpuidle: using governor menu Aug 5 22:14:19.890648 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Aug 5 22:14:19.890655 kernel: ASID allocator initialised with 32768 entries Aug 5 22:14:19.890663 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 5 22:14:19.890670 kernel: Serial: AMBA PL011 UART driver Aug 5 22:14:19.890677 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Aug 5 22:14:19.890684 kernel: Modules: 0 pages in range for non-PLT usage Aug 5 22:14:19.890692 kernel: Modules: 509120 pages in range for PLT usage Aug 5 22:14:19.890699 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 5 22:14:19.890706 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Aug 5 22:14:19.890715 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Aug 5 22:14:19.890722 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Aug 5 22:14:19.890729 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 5 22:14:19.890737 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Aug 5 22:14:19.890744 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Aug 5 22:14:19.890752 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Aug 5 22:14:19.890759 kernel: ACPI: Added _OSI(Module Device) Aug 5 22:14:19.890766 kernel: ACPI: Added _OSI(Processor Device) Aug 5 22:14:19.890773 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 5 22:14:19.890782 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 5 22:14:19.890789 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 5 22:14:19.890796 kernel: ACPI: Interpreter enabled Aug 5 22:14:19.890804 kernel: ACPI: Using GIC for interrupt routing Aug 5 22:14:19.890811 kernel: ACPI: MCFG table detected, 1 entries Aug 5 22:14:19.890818 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Aug 5 22:14:19.890825 kernel: printk: console [ttyAMA0] enabled Aug 5 22:14:19.890833 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 5 22:14:19.890968 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 5 22:14:19.891047 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Aug 5 22:14:19.891113 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Aug 5 22:14:19.891177 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Aug 5 22:14:19.891241 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Aug 5 22:14:19.891250 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Aug 5 22:14:19.891258 kernel: PCI host bridge to bus 0000:00 Aug 5 22:14:19.891327 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Aug 5 22:14:19.891389 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Aug 5 22:14:19.891457 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Aug 5 22:14:19.891516 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 5 22:14:19.891630 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Aug 5 22:14:19.891710 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Aug 5 22:14:19.891778 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Aug 5 22:14:19.891855 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Aug 5 22:14:19.891922 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Aug 5 22:14:19.891987 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Aug 5 22:14:19.892053 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Aug 5 22:14:19.892119 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Aug 5 22:14:19.892178 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Aug 5 22:14:19.892238 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Aug 5 22:14:19.892298 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Aug 5 22:14:19.892308 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Aug 5 22:14:19.892316 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Aug 5 22:14:19.892323 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Aug 5 22:14:19.892330 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Aug 5 22:14:19.892337 kernel: iommu: Default domain type: Translated Aug 5 22:14:19.892345 kernel: iommu: DMA domain TLB invalidation policy: strict mode Aug 5 22:14:19.892352 kernel: efivars: Registered efivars operations Aug 5 22:14:19.892363 kernel: vgaarb: loaded Aug 5 22:14:19.892373 kernel: clocksource: Switched to clocksource arch_sys_counter Aug 5 22:14:19.892380 kernel: VFS: Disk quotas dquot_6.6.0 Aug 5 22:14:19.892387 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 5 22:14:19.892395 kernel: pnp: PnP ACPI init Aug 5 22:14:19.892487 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Aug 5 22:14:19.892499 kernel: pnp: PnP ACPI: found 1 devices Aug 5 22:14:19.892506 kernel: NET: Registered PF_INET protocol family Aug 5 22:14:19.892513 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 5 22:14:19.892524 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 5 22:14:19.892531 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 5 22:14:19.892539 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 5 22:14:19.892550 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 5 22:14:19.892557 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 5 22:14:19.892565 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 22:14:19.892572 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 22:14:19.892580 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 5 22:14:19.892587 kernel: PCI: CLS 0 bytes, default 64 Aug 5 22:14:19.892617 kernel: kvm [1]: HYP mode not available Aug 5 22:14:19.892626 kernel: Initialise system trusted keyrings Aug 5 22:14:19.892633 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 5 22:14:19.892641 kernel: Key type asymmetric registered Aug 5 22:14:19.892648 kernel: Asymmetric key parser 'x509' registered Aug 5 22:14:19.892655 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 5 22:14:19.892662 kernel: io scheduler mq-deadline registered Aug 5 22:14:19.892670 kernel: io scheduler kyber registered Aug 5 22:14:19.892677 kernel: io scheduler bfq registered Aug 5 22:14:19.892686 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 5 22:14:19.892693 kernel: ACPI: button: Power Button [PWRB] Aug 5 22:14:19.892701 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Aug 5 22:14:19.892774 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Aug 5 22:14:19.892784 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 5 22:14:19.892791 kernel: thunder_xcv, ver 1.0 Aug 5 22:14:19.892798 kernel: thunder_bgx, ver 1.0 Aug 5 22:14:19.892806 kernel: nicpf, ver 1.0 Aug 5 22:14:19.892813 kernel: nicvf, ver 1.0 Aug 5 22:14:19.892892 kernel: rtc-efi rtc-efi.0: registered as rtc0 Aug 5 22:14:19.892957 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-08-05T22:14:19 UTC (1722896059) Aug 5 22:14:19.892967 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 5 22:14:19.892975 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Aug 5 22:14:19.892982 kernel: watchdog: Delayed init of the lockup detector failed: -19 Aug 5 22:14:19.892989 kernel: watchdog: Hard watchdog permanently disabled Aug 5 22:14:19.892997 kernel: NET: Registered PF_INET6 protocol family Aug 5 22:14:19.893007 kernel: Segment Routing with IPv6 Aug 5 22:14:19.893017 kernel: In-situ OAM (IOAM) with IPv6 Aug 5 22:14:19.893024 kernel: NET: Registered PF_PACKET protocol family Aug 5 22:14:19.893031 kernel: Key type dns_resolver registered Aug 5 22:14:19.893039 kernel: registered taskstats version 1 Aug 5 22:14:19.893046 kernel: Loading compiled-in X.509 certificates Aug 5 22:14:19.893053 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.43-flatcar: 99cab5c9e2f0f3a5ca972c2df7b3d6ed64d627d4' Aug 5 22:14:19.893061 kernel: Key type .fscrypt registered Aug 5 22:14:19.893068 kernel: Key type fscrypt-provisioning registered Aug 5 22:14:19.893075 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 5 22:14:19.893084 kernel: ima: Allocated hash algorithm: sha1 Aug 5 22:14:19.893091 kernel: ima: No architecture policies found Aug 5 22:14:19.893098 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Aug 5 22:14:19.893106 kernel: clk: Disabling unused clocks Aug 5 22:14:19.893113 kernel: Freeing unused kernel memory: 39040K Aug 5 22:14:19.893120 kernel: Run /init as init process Aug 5 22:14:19.893127 kernel: with arguments: Aug 5 22:14:19.893134 kernel: /init Aug 5 22:14:19.893141 kernel: with environment: Aug 5 22:14:19.893150 kernel: HOME=/ Aug 5 22:14:19.893157 kernel: TERM=linux Aug 5 22:14:19.893164 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 5 22:14:19.893173 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 5 22:14:19.893182 systemd[1]: Detected virtualization kvm. Aug 5 22:14:19.893190 systemd[1]: Detected architecture arm64. Aug 5 22:14:19.893198 systemd[1]: Running in initrd. Aug 5 22:14:19.893207 systemd[1]: No hostname configured, using default hostname. Aug 5 22:14:19.893214 systemd[1]: Hostname set to . Aug 5 22:14:19.893222 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:14:19.893230 systemd[1]: Queued start job for default target initrd.target. Aug 5 22:14:19.893238 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:14:19.893245 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:14:19.893254 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 5 22:14:19.893261 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:14:19.893271 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 5 22:14:19.893279 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 5 22:14:19.893288 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 5 22:14:19.893296 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 5 22:14:19.893304 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:14:19.893311 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:14:19.893319 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:14:19.893328 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:14:19.893336 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:14:19.893344 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:14:19.893352 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:14:19.893360 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:14:19.893368 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 5 22:14:19.893375 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 5 22:14:19.893383 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:14:19.893391 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:14:19.893407 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:14:19.893415 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:14:19.893423 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 5 22:14:19.893433 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:14:19.893441 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 5 22:14:19.893449 systemd[1]: Starting systemd-fsck-usr.service... Aug 5 22:14:19.893457 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:14:19.893464 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:14:19.893474 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:14:19.893482 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 5 22:14:19.893490 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:14:19.893498 systemd[1]: Finished systemd-fsck-usr.service. Aug 5 22:14:19.893506 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 22:14:19.893515 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:14:19.893523 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:14:19.893553 systemd-journald[238]: Collecting audit messages is disabled. Aug 5 22:14:19.893577 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:14:19.893587 systemd-journald[238]: Journal started Aug 5 22:14:19.893623 systemd-journald[238]: Runtime Journal (/run/log/journal/62b905bffdb04ab6abe062aa78a3c512) is 5.9M, max 47.3M, 41.4M free. Aug 5 22:14:19.899943 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 5 22:14:19.899966 kernel: Bridge firewalling registered Aug 5 22:14:19.884930 systemd-modules-load[239]: Inserted module 'overlay' Aug 5 22:14:19.898386 systemd-modules-load[239]: Inserted module 'br_netfilter' Aug 5 22:14:19.903964 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:14:19.903984 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:14:19.906050 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:14:19.909442 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:14:19.911742 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:14:19.914616 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:14:19.917627 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:14:19.920024 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 5 22:14:19.924040 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:14:19.927103 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:14:19.929767 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:14:19.933335 dracut-cmdline[274]: dracut-dracut-053 Aug 5 22:14:19.935926 dracut-cmdline[274]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=4052403b8e39e55d48e6afcca927358798017aa0d33c868bc3038260a8d9be90 Aug 5 22:14:19.959417 systemd-resolved[281]: Positive Trust Anchors: Aug 5 22:14:19.959434 systemd-resolved[281]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:14:19.959465 systemd-resolved[281]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 5 22:14:19.964060 systemd-resolved[281]: Defaulting to hostname 'linux'. Aug 5 22:14:19.965113 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:14:19.966632 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:14:20.002622 kernel: SCSI subsystem initialized Aug 5 22:14:20.007613 kernel: Loading iSCSI transport class v2.0-870. Aug 5 22:14:20.015643 kernel: iscsi: registered transport (tcp) Aug 5 22:14:20.027858 kernel: iscsi: registered transport (qla4xxx) Aug 5 22:14:20.027889 kernel: QLogic iSCSI HBA Driver Aug 5 22:14:20.069587 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 5 22:14:20.075800 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 5 22:14:20.091332 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 5 22:14:20.091392 kernel: device-mapper: uevent: version 1.0.3 Aug 5 22:14:20.091417 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 5 22:14:20.138618 kernel: raid6: neonx8 gen() 15745 MB/s Aug 5 22:14:20.155606 kernel: raid6: neonx4 gen() 15650 MB/s Aug 5 22:14:20.172610 kernel: raid6: neonx2 gen() 13272 MB/s Aug 5 22:14:20.189615 kernel: raid6: neonx1 gen() 10444 MB/s Aug 5 22:14:20.206617 kernel: raid6: int64x8 gen() 6972 MB/s Aug 5 22:14:20.223611 kernel: raid6: int64x4 gen() 7085 MB/s Aug 5 22:14:20.240609 kernel: raid6: int64x2 gen() 6124 MB/s Aug 5 22:14:20.257609 kernel: raid6: int64x1 gen() 5058 MB/s Aug 5 22:14:20.257623 kernel: raid6: using algorithm neonx8 gen() 15745 MB/s Aug 5 22:14:20.274616 kernel: raid6: .... xor() 12059 MB/s, rmw enabled Aug 5 22:14:20.274630 kernel: raid6: using neon recovery algorithm Aug 5 22:14:20.279932 kernel: xor: measuring software checksum speed Aug 5 22:14:20.279946 kernel: 8regs : 19859 MB/sec Aug 5 22:14:20.280793 kernel: 32regs : 19654 MB/sec Aug 5 22:14:20.281947 kernel: arm64_neon : 27197 MB/sec Aug 5 22:14:20.281959 kernel: xor: using function: arm64_neon (27197 MB/sec) Aug 5 22:14:20.333625 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 5 22:14:20.344712 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:14:20.357801 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:14:20.369790 systemd-udevd[460]: Using default interface naming scheme 'v255'. Aug 5 22:14:20.372926 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:14:20.376509 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 5 22:14:20.390993 dracut-pre-trigger[465]: rd.md=0: removing MD RAID activation Aug 5 22:14:20.429565 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:14:20.442789 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:14:20.485191 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:14:20.493791 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 5 22:14:20.509407 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 5 22:14:20.511632 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:14:20.513177 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:14:20.515254 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:14:20.524351 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 5 22:14:20.537458 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:14:20.541129 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Aug 5 22:14:20.553023 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Aug 5 22:14:20.553137 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 5 22:14:20.553149 kernel: GPT:9289727 != 19775487 Aug 5 22:14:20.553159 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 5 22:14:20.553168 kernel: GPT:9289727 != 19775487 Aug 5 22:14:20.553177 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 5 22:14:20.553188 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:14:20.544412 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:14:20.544515 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:14:20.546784 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:14:20.547756 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:14:20.547878 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:14:20.549079 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:14:20.560845 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:14:20.575651 kernel: BTRFS: device fsid 278882ec-4175-45f0-a12b-7fddc0d6d9a3 devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (528) Aug 5 22:14:20.575697 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (523) Aug 5 22:14:20.575391 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:14:20.580746 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 5 22:14:20.585222 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 5 22:14:20.591820 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 5 22:14:20.592719 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 5 22:14:20.598727 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:14:20.610746 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 5 22:14:20.612749 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:14:20.620189 disk-uuid[556]: Primary Header is updated. Aug 5 22:14:20.620189 disk-uuid[556]: Secondary Entries is updated. Aug 5 22:14:20.620189 disk-uuid[556]: Secondary Header is updated. Aug 5 22:14:20.627334 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:14:20.634569 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:14:21.643058 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:14:21.643108 disk-uuid[560]: The operation has completed successfully. Aug 5 22:14:21.665276 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 5 22:14:21.665405 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 5 22:14:21.684769 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 5 22:14:21.687590 sh[580]: Success Aug 5 22:14:21.704086 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Aug 5 22:14:21.752120 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 5 22:14:21.753707 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 5 22:14:21.755297 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 5 22:14:21.767922 kernel: BTRFS info (device dm-0): first mount of filesystem 278882ec-4175-45f0-a12b-7fddc0d6d9a3 Aug 5 22:14:21.767959 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:14:21.767970 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 5 22:14:21.769637 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 5 22:14:21.769653 kernel: BTRFS info (device dm-0): using free space tree Aug 5 22:14:21.773679 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 5 22:14:21.774764 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 5 22:14:21.780764 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 5 22:14:21.782020 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 5 22:14:21.790901 kernel: BTRFS info (device vda6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:14:21.790937 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:14:21.790948 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:14:21.793622 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:14:21.800334 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 5 22:14:21.801913 kernel: BTRFS info (device vda6): last unmount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:14:21.809691 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 5 22:14:21.814762 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 5 22:14:21.875320 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:14:21.888725 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:14:21.906648 ignition[678]: Ignition 2.18.0 Aug 5 22:14:21.906657 ignition[678]: Stage: fetch-offline Aug 5 22:14:21.906696 ignition[678]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:14:21.906704 ignition[678]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:14:21.906789 ignition[678]: parsed url from cmdline: "" Aug 5 22:14:21.906792 ignition[678]: no config URL provided Aug 5 22:14:21.906797 ignition[678]: reading system config file "/usr/lib/ignition/user.ign" Aug 5 22:14:21.906803 ignition[678]: no config at "/usr/lib/ignition/user.ign" Aug 5 22:14:21.912755 systemd-networkd[770]: lo: Link UP Aug 5 22:14:21.906829 ignition[678]: op(1): [started] loading QEMU firmware config module Aug 5 22:14:21.912759 systemd-networkd[770]: lo: Gained carrier Aug 5 22:14:21.906833 ignition[678]: op(1): executing: "modprobe" "qemu_fw_cfg" Aug 5 22:14:21.913403 systemd-networkd[770]: Enumeration completed Aug 5 22:14:21.913548 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:14:21.913878 systemd-networkd[770]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:14:21.913881 systemd-networkd[770]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:14:21.914682 systemd-networkd[770]: eth0: Link UP Aug 5 22:14:21.914685 systemd-networkd[770]: eth0: Gained carrier Aug 5 22:14:21.923860 ignition[678]: op(1): [finished] loading QEMU firmware config module Aug 5 22:14:21.914692 systemd-networkd[770]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:14:21.915632 systemd[1]: Reached target network.target - Network. Aug 5 22:14:21.932695 ignition[678]: parsing config with SHA512: 82420c5cfaf820240072e7612bf6b5062bc03297d89beb42821ea409091fba34aaa43cc49ddf5aab99341329085622ed80c1bd2fe153e99f5d911d00d7e6a694 Aug 5 22:14:21.935715 unknown[678]: fetched base config from "system" Aug 5 22:14:21.935725 unknown[678]: fetched user config from "qemu" Aug 5 22:14:21.936002 ignition[678]: fetch-offline: fetch-offline passed Aug 5 22:14:21.937642 systemd-networkd[770]: eth0: DHCPv4 address 10.0.0.112/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 22:14:21.936057 ignition[678]: Ignition finished successfully Aug 5 22:14:21.938278 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:14:21.939837 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Aug 5 22:14:21.947789 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 5 22:14:21.959413 ignition[777]: Ignition 2.18.0 Aug 5 22:14:21.959423 ignition[777]: Stage: kargs Aug 5 22:14:21.959572 ignition[777]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:14:21.959581 ignition[777]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:14:21.960211 ignition[777]: kargs: kargs passed Aug 5 22:14:21.960251 ignition[777]: Ignition finished successfully Aug 5 22:14:21.964146 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 5 22:14:21.978734 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 5 22:14:21.988121 ignition[786]: Ignition 2.18.0 Aug 5 22:14:21.988130 ignition[786]: Stage: disks Aug 5 22:14:21.988284 ignition[786]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:14:21.991128 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 5 22:14:21.988293 ignition[786]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:14:21.992477 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 5 22:14:21.988943 ignition[786]: disks: disks passed Aug 5 22:14:21.993355 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 5 22:14:21.988986 ignition[786]: Ignition finished successfully Aug 5 22:14:21.994958 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:14:21.996472 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:14:21.998052 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:14:22.016806 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 5 22:14:22.029359 systemd-fsck[798]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 5 22:14:22.033209 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 5 22:14:22.035029 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 5 22:14:22.082618 kernel: EXT4-fs (vda9): mounted filesystem 44c9fced-dca5-4347-a15f-96911c2e5e61 r/w with ordered data mode. Quota mode: none. Aug 5 22:14:22.083233 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 5 22:14:22.084333 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 5 22:14:22.099699 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:14:22.101373 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 5 22:14:22.102189 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 5 22:14:22.102224 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 5 22:14:22.102244 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:14:22.108765 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 5 22:14:22.111255 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (806) Aug 5 22:14:22.111721 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 5 22:14:22.116070 kernel: BTRFS info (device vda6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:14:22.116090 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:14:22.116100 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:14:22.116110 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:14:22.117960 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:14:22.152741 initrd-setup-root[830]: cut: /sysroot/etc/passwd: No such file or directory Aug 5 22:14:22.155868 initrd-setup-root[837]: cut: /sysroot/etc/group: No such file or directory Aug 5 22:14:22.158983 initrd-setup-root[844]: cut: /sysroot/etc/shadow: No such file or directory Aug 5 22:14:22.161937 initrd-setup-root[851]: cut: /sysroot/etc/gshadow: No such file or directory Aug 5 22:14:22.234246 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 5 22:14:22.247689 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 5 22:14:22.249021 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 5 22:14:22.254611 kernel: BTRFS info (device vda6): last unmount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:14:22.270082 ignition[919]: INFO : Ignition 2.18.0 Aug 5 22:14:22.270082 ignition[919]: INFO : Stage: mount Aug 5 22:14:22.272674 ignition[919]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:14:22.272674 ignition[919]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:14:22.272674 ignition[919]: INFO : mount: mount passed Aug 5 22:14:22.272674 ignition[919]: INFO : Ignition finished successfully Aug 5 22:14:22.271833 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 5 22:14:22.272950 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 5 22:14:22.280680 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 5 22:14:22.767147 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 5 22:14:22.775816 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:14:22.780623 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (932) Aug 5 22:14:22.782935 kernel: BTRFS info (device vda6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 22:14:22.782971 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 22:14:22.782982 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:14:22.785613 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:14:22.786936 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:14:22.803055 ignition[949]: INFO : Ignition 2.18.0 Aug 5 22:14:22.803055 ignition[949]: INFO : Stage: files Aug 5 22:14:22.804369 ignition[949]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:14:22.804369 ignition[949]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:14:22.804369 ignition[949]: DEBUG : files: compiled without relabeling support, skipping Aug 5 22:14:22.807258 ignition[949]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 5 22:14:22.807258 ignition[949]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 5 22:14:22.809886 ignition[949]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 5 22:14:22.811154 ignition[949]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 5 22:14:22.811154 ignition[949]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 5 22:14:22.810419 unknown[949]: wrote ssh authorized keys file for user: core Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Aug 5 22:14:22.814628 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1 Aug 5 22:14:23.175563 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Aug 5 22:14:23.441705 ignition[949]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Aug 5 22:14:23.441705 ignition[949]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Aug 5 22:14:23.444778 ignition[949]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 22:14:23.444778 ignition[949]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 22:14:23.444778 ignition[949]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Aug 5 22:14:23.444778 ignition[949]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Aug 5 22:14:23.469633 ignition[949]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 22:14:23.474410 ignition[949]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 22:14:23.477743 ignition[949]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Aug 5 22:14:23.477743 ignition[949]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:14:23.477743 ignition[949]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:14:23.477743 ignition[949]: INFO : files: files passed Aug 5 22:14:23.477743 ignition[949]: INFO : Ignition finished successfully Aug 5 22:14:23.477125 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 5 22:14:23.485824 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 5 22:14:23.487351 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 5 22:14:23.489970 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 5 22:14:23.490066 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 5 22:14:23.495918 initrd-setup-root-after-ignition[978]: grep: /sysroot/oem/oem-release: No such file or directory Aug 5 22:14:23.499287 initrd-setup-root-after-ignition[980]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:14:23.499287 initrd-setup-root-after-ignition[980]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:14:23.501859 initrd-setup-root-after-ignition[984]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:14:23.504669 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:14:23.506267 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 5 22:14:23.519881 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 5 22:14:23.541770 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 5 22:14:23.541904 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 5 22:14:23.543734 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 5 22:14:23.545564 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 5 22:14:23.547050 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 5 22:14:23.547811 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 5 22:14:23.562941 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:14:23.565146 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 5 22:14:23.575730 systemd[1]: Stopped target network.target - Network. Aug 5 22:14:23.576433 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:14:23.577672 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:14:23.579141 systemd[1]: Stopped target timers.target - Timer Units. Aug 5 22:14:23.580609 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 5 22:14:23.580715 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:14:23.582857 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 5 22:14:23.584453 systemd[1]: Stopped target basic.target - Basic System. Aug 5 22:14:23.585865 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 5 22:14:23.587192 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:14:23.588521 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 5 22:14:23.590564 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 5 22:14:23.592129 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:14:23.593788 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 5 22:14:23.595449 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 5 22:14:23.597121 systemd[1]: Stopped target swap.target - Swaps. Aug 5 22:14:23.598462 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 5 22:14:23.598578 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:14:23.600623 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:14:23.602106 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:14:23.603737 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 5 22:14:23.603836 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:14:23.605652 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 5 22:14:23.605761 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 5 22:14:23.607771 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 5 22:14:23.607884 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:14:23.609415 systemd[1]: Stopped target paths.target - Path Units. Aug 5 22:14:23.610602 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 5 22:14:23.615650 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:14:23.616843 systemd[1]: Stopped target slices.target - Slice Units. Aug 5 22:14:23.618759 systemd[1]: Stopped target sockets.target - Socket Units. Aug 5 22:14:23.620213 systemd[1]: iscsid.socket: Deactivated successfully. Aug 5 22:14:23.620299 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:14:23.621341 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 5 22:14:23.621424 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:14:23.622728 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 5 22:14:23.622837 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:14:23.624215 systemd[1]: ignition-files.service: Deactivated successfully. Aug 5 22:14:23.624319 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 5 22:14:23.639828 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 5 22:14:23.641283 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 5 22:14:23.641421 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:14:23.644517 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 5 22:14:23.645716 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 5 22:14:23.648483 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 5 22:14:23.649433 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 5 22:14:23.652094 ignition[1004]: INFO : Ignition 2.18.0 Aug 5 22:14:23.652094 ignition[1004]: INFO : Stage: umount Aug 5 22:14:23.652094 ignition[1004]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:14:23.652094 ignition[1004]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:14:23.652094 ignition[1004]: INFO : umount: umount passed Aug 5 22:14:23.652094 ignition[1004]: INFO : Ignition finished successfully Aug 5 22:14:23.649559 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:14:23.651328 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 5 22:14:23.651428 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:14:23.655048 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 5 22:14:23.655120 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 5 22:14:23.661192 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 5 22:14:23.661716 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 5 22:14:23.661790 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 5 22:14:23.663880 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 5 22:14:23.663969 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 5 22:14:23.664640 systemd-networkd[770]: eth0: DHCPv6 lease lost Aug 5 22:14:23.665174 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 5 22:14:23.665221 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 5 22:14:23.667594 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 5 22:14:23.667661 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 5 22:14:23.669275 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 5 22:14:23.669314 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 5 22:14:23.671260 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 5 22:14:23.671363 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 5 22:14:23.673110 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 5 22:14:23.673354 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 5 22:14:23.675277 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 5 22:14:23.675331 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:14:23.688725 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 5 22:14:23.689361 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 5 22:14:23.689436 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:14:23.691106 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 5 22:14:23.691148 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:14:23.692670 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 5 22:14:23.692712 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 5 22:14:23.694406 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 5 22:14:23.694443 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:14:23.696203 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:14:23.704641 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 5 22:14:23.704745 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 5 22:14:23.711963 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 5 22:14:23.712051 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 5 22:14:23.713655 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 5 22:14:23.713734 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 5 22:14:23.717269 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 5 22:14:23.717408 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:14:23.719421 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 5 22:14:23.719458 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 5 22:14:23.721049 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 5 22:14:23.721079 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:14:23.722324 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 5 22:14:23.722361 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:14:23.724486 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 5 22:14:23.724535 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 5 22:14:23.726355 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:14:23.726403 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:14:23.736717 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 5 22:14:23.737674 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 5 22:14:23.737728 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:14:23.739638 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:14:23.739682 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:14:23.744324 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 5 22:14:23.744421 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 5 22:14:23.746530 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 5 22:14:23.748745 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 5 22:14:23.757793 systemd[1]: Switching root. Aug 5 22:14:23.783397 systemd-journald[238]: Journal stopped Aug 5 22:14:24.415716 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Aug 5 22:14:24.415771 kernel: SELinux: policy capability network_peer_controls=1 Aug 5 22:14:24.415783 kernel: SELinux: policy capability open_perms=1 Aug 5 22:14:24.415793 kernel: SELinux: policy capability extended_socket_class=1 Aug 5 22:14:24.415806 kernel: SELinux: policy capability always_check_network=0 Aug 5 22:14:24.415815 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 5 22:14:24.415825 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 5 22:14:24.415835 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 5 22:14:24.415844 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 5 22:14:24.415854 kernel: audit: type=1403 audit(1722896063.901:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 5 22:14:24.415868 systemd[1]: Successfully loaded SELinux policy in 29.681ms. Aug 5 22:14:24.415887 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.305ms. Aug 5 22:14:24.415901 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 5 22:14:24.415913 systemd[1]: Detected virtualization kvm. Aug 5 22:14:24.415923 systemd[1]: Detected architecture arm64. Aug 5 22:14:24.415934 systemd[1]: Detected first boot. Aug 5 22:14:24.415944 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:14:24.415956 zram_generator::config[1049]: No configuration found. Aug 5 22:14:24.415968 systemd[1]: Populated /etc with preset unit settings. Aug 5 22:14:24.415978 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 5 22:14:24.415989 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 5 22:14:24.416002 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 5 22:14:24.416014 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 5 22:14:24.416025 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 5 22:14:24.416035 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 5 22:14:24.416046 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 5 22:14:24.416058 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 5 22:14:24.416069 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 5 22:14:24.416079 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 5 22:14:24.416089 systemd[1]: Created slice user.slice - User and Session Slice. Aug 5 22:14:24.416100 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:14:24.416110 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:14:24.416121 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 5 22:14:24.416131 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 5 22:14:24.416142 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 5 22:14:24.416154 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:14:24.416166 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Aug 5 22:14:24.416176 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:14:24.416187 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 5 22:14:24.416197 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 5 22:14:24.416208 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 5 22:14:24.416219 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 5 22:14:24.416231 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:14:24.416242 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:14:24.416252 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:14:24.416263 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:14:24.416274 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 5 22:14:24.416284 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 5 22:14:24.416294 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:14:24.416305 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:14:24.416315 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:14:24.416328 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 5 22:14:24.416340 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 5 22:14:24.416350 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 5 22:14:24.416361 systemd[1]: Mounting media.mount - External Media Directory... Aug 5 22:14:24.416371 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 5 22:14:24.416382 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 5 22:14:24.416399 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 5 22:14:24.416412 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Aug 5 22:14:24.416422 systemd[1]: Reached target machines.target - Containers. Aug 5 22:14:24.416435 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 5 22:14:24.416446 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:14:24.416457 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:14:24.416468 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 5 22:14:24.416478 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:14:24.416493 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:14:24.416503 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:14:24.416513 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 5 22:14:24.416524 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:14:24.416536 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 5 22:14:24.416547 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 5 22:14:24.416557 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 5 22:14:24.416569 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 5 22:14:24.416579 systemd[1]: Stopped systemd-fsck-usr.service. Aug 5 22:14:24.416589 kernel: fuse: init (API version 7.39) Aug 5 22:14:24.416607 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:14:24.416618 kernel: ACPI: bus type drm_connector registered Aug 5 22:14:24.416630 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:14:24.416640 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 5 22:14:24.416651 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 5 22:14:24.416662 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:14:24.416672 kernel: loop: module loaded Aug 5 22:14:24.416682 systemd[1]: verity-setup.service: Deactivated successfully. Aug 5 22:14:24.416692 systemd[1]: Stopped verity-setup.service. Aug 5 22:14:24.416703 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 5 22:14:24.416713 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 5 22:14:24.416742 systemd-journald[1119]: Collecting audit messages is disabled. Aug 5 22:14:24.416762 systemd[1]: Mounted media.mount - External Media Directory. Aug 5 22:14:24.416772 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 5 22:14:24.416783 systemd-journald[1119]: Journal started Aug 5 22:14:24.416804 systemd-journald[1119]: Runtime Journal (/run/log/journal/62b905bffdb04ab6abe062aa78a3c512) is 5.9M, max 47.3M, 41.4M free. Aug 5 22:14:24.241256 systemd[1]: Queued start job for default target multi-user.target. Aug 5 22:14:24.253506 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 5 22:14:24.253848 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 5 22:14:24.419723 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:14:24.420216 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 5 22:14:24.421366 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 5 22:14:24.423625 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 5 22:14:24.424983 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:14:24.426972 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 5 22:14:24.427108 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 5 22:14:24.428183 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:14:24.428315 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:14:24.429478 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:14:24.429641 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:14:24.430728 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:14:24.430863 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:14:24.431971 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 5 22:14:24.432098 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 5 22:14:24.433111 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:14:24.433248 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:14:24.434545 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:14:24.435693 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 5 22:14:24.437315 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 5 22:14:24.448753 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 5 22:14:24.461699 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 5 22:14:24.463728 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 5 22:14:24.464537 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 5 22:14:24.464581 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:14:24.466345 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 5 22:14:24.468480 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 5 22:14:24.470306 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 5 22:14:24.471188 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:14:24.472570 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 5 22:14:24.474437 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 5 22:14:24.475437 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:14:24.478782 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 5 22:14:24.479824 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:14:24.483251 systemd-journald[1119]: Time spent on flushing to /var/log/journal/62b905bffdb04ab6abe062aa78a3c512 is 22.455ms for 833 entries. Aug 5 22:14:24.483251 systemd-journald[1119]: System Journal (/var/log/journal/62b905bffdb04ab6abe062aa78a3c512) is 8.0M, max 195.6M, 187.6M free. Aug 5 22:14:24.520261 systemd-journald[1119]: Received client request to flush runtime journal. Aug 5 22:14:24.520318 kernel: loop0: detected capacity change from 0 to 194096 Aug 5 22:14:24.520331 kernel: block loop0: the capability attribute has been deprecated. Aug 5 22:14:24.483759 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:14:24.489828 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 5 22:14:24.494027 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 5 22:14:24.497052 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:14:24.498350 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 5 22:14:24.500996 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 5 22:14:24.502272 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 5 22:14:24.506733 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 5 22:14:24.511773 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 5 22:14:24.517791 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 5 22:14:24.520263 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 5 22:14:24.526446 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 5 22:14:24.529454 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:14:24.538638 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 5 22:14:24.542128 udevadm[1170]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Aug 5 22:14:24.543840 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 5 22:14:24.561726 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:14:24.563693 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 5 22:14:24.564858 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 5 22:14:24.576665 kernel: loop1: detected capacity change from 0 to 113672 Aug 5 22:14:24.588919 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Aug 5 22:14:24.588936 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Aug 5 22:14:24.594191 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:14:24.606625 kernel: loop2: detected capacity change from 0 to 59688 Aug 5 22:14:24.635626 kernel: loop3: detected capacity change from 0 to 194096 Aug 5 22:14:24.640618 kernel: loop4: detected capacity change from 0 to 113672 Aug 5 22:14:24.645623 kernel: loop5: detected capacity change from 0 to 59688 Aug 5 22:14:24.648671 (sd-merge)[1186]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Aug 5 22:14:24.649035 (sd-merge)[1186]: Merged extensions into '/usr'. Aug 5 22:14:24.652924 systemd[1]: Reloading requested from client PID 1159 ('systemd-sysext') (unit systemd-sysext.service)... Aug 5 22:14:24.653075 systemd[1]: Reloading... Aug 5 22:14:24.709633 zram_generator::config[1211]: No configuration found. Aug 5 22:14:24.796925 ldconfig[1154]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 5 22:14:24.810590 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 5 22:14:24.848519 systemd[1]: Reloading finished in 195 ms. Aug 5 22:14:24.875913 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 5 22:14:24.877279 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 5 22:14:24.887783 systemd[1]: Starting ensure-sysext.service... Aug 5 22:14:24.889634 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:14:24.901283 systemd[1]: Reloading requested from client PID 1245 ('systemctl') (unit ensure-sysext.service)... Aug 5 22:14:24.901299 systemd[1]: Reloading... Aug 5 22:14:24.912457 systemd-tmpfiles[1247]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 5 22:14:24.912729 systemd-tmpfiles[1247]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 5 22:14:24.913347 systemd-tmpfiles[1247]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 5 22:14:24.913573 systemd-tmpfiles[1247]: ACLs are not supported, ignoring. Aug 5 22:14:24.913652 systemd-tmpfiles[1247]: ACLs are not supported, ignoring. Aug 5 22:14:24.915711 systemd-tmpfiles[1247]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:14:24.915723 systemd-tmpfiles[1247]: Skipping /boot Aug 5 22:14:24.922804 systemd-tmpfiles[1247]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:14:24.922821 systemd-tmpfiles[1247]: Skipping /boot Aug 5 22:14:24.949927 zram_generator::config[1273]: No configuration found. Aug 5 22:14:25.029204 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 5 22:14:25.066554 systemd[1]: Reloading finished in 164 ms. Aug 5 22:14:25.083809 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 5 22:14:25.092134 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:14:25.100472 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 22:14:25.106082 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 5 22:14:25.108108 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 5 22:14:25.113855 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:14:25.117147 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:14:25.122585 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 5 22:14:25.129054 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:14:25.130207 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:14:25.133462 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:14:25.136887 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:14:25.138956 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:14:25.143141 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 5 22:14:25.144885 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:14:25.145036 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:14:25.148531 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:14:25.149485 systemd-udevd[1317]: Using default interface naming scheme 'v255'. Aug 5 22:14:25.149657 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:14:25.152188 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:14:25.152580 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:14:25.158887 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 5 22:14:25.163689 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:14:25.165431 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:14:25.168951 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:14:25.173946 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:14:25.175113 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:14:25.177445 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 5 22:14:25.179035 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:14:25.181007 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:14:25.181171 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:14:25.191426 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 5 22:14:25.193495 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:14:25.193645 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:14:25.197309 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:14:25.199996 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:14:25.204830 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:14:25.205953 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:14:25.210326 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:14:25.211211 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:14:25.211346 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Aug 5 22:14:25.212319 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 5 22:14:25.214155 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:14:25.214285 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:14:25.216007 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 5 22:14:25.217212 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 5 22:14:25.218752 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:14:25.218902 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:14:25.224209 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:14:25.224353 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:14:25.228353 systemd[1]: Finished ensure-sysext.service. Aug 5 22:14:25.241831 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1339) Aug 5 22:14:25.239055 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:14:25.253801 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 5 22:14:25.255173 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Aug 5 22:14:25.266362 augenrules[1379]: No rules Aug 5 22:14:25.263948 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 22:14:25.312435 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1350) Aug 5 22:14:25.349003 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:14:25.350901 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 5 22:14:25.352378 systemd[1]: Reached target time-set.target - System Time Set. Aug 5 22:14:25.353407 systemd-networkd[1361]: lo: Link UP Aug 5 22:14:25.353411 systemd-networkd[1361]: lo: Gained carrier Aug 5 22:14:25.359267 systemd-resolved[1314]: Positive Trust Anchors: Aug 5 22:14:25.360628 systemd-networkd[1361]: Enumeration completed Aug 5 22:14:25.361679 systemd-resolved[1314]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:14:25.361713 systemd-resolved[1314]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 5 22:14:25.361928 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 5 22:14:25.363629 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:14:25.364114 systemd-networkd[1361]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:14:25.364118 systemd-networkd[1361]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:14:25.367562 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 5 22:14:25.370284 systemd-networkd[1361]: eth0: Link UP Aug 5 22:14:25.370294 systemd-networkd[1361]: eth0: Gained carrier Aug 5 22:14:25.370310 systemd-networkd[1361]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:14:25.374166 systemd-resolved[1314]: Defaulting to hostname 'linux'. Aug 5 22:14:25.377920 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:14:25.381023 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:14:25.386199 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 5 22:14:25.390150 systemd[1]: Reached target network.target - Network. Aug 5 22:14:25.391139 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:14:25.396689 systemd-networkd[1361]: eth0: DHCPv4 address 10.0.0.112/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 22:14:25.396855 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 5 22:14:25.397365 systemd-timesyncd[1378]: Network configuration changed, trying to establish connection. Aug 5 22:14:25.834896 systemd-resolved[1314]: Clock change detected. Flushing caches. Aug 5 22:14:25.834947 systemd-timesyncd[1378]: Contacted time server 10.0.0.1:123 (10.0.0.1). Aug 5 22:14:25.834988 systemd-timesyncd[1378]: Initial clock synchronization to Mon 2024-08-05 22:14:25.834848 UTC. Aug 5 22:14:25.852368 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 5 22:14:25.867624 lvm[1404]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:14:25.875661 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:14:25.894567 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 5 22:14:25.895693 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:14:25.896802 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:14:25.897706 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 5 22:14:25.898737 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 5 22:14:25.899829 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 5 22:14:25.900754 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 5 22:14:25.901746 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 5 22:14:25.902648 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 5 22:14:25.902680 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:14:25.903324 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:14:25.904745 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 5 22:14:25.907102 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 5 22:14:25.914064 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 5 22:14:25.916197 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 5 22:14:25.917679 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 5 22:14:25.918588 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:14:25.919496 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:14:25.920211 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:14:25.920241 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:14:25.921143 systemd[1]: Starting containerd.service - containerd container runtime... Aug 5 22:14:25.923074 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 5 22:14:25.924466 lvm[1411]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:14:25.926484 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 5 22:14:25.931257 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 5 22:14:25.932847 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Aug 5 22:14:25.933835 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 5 22:14:25.935834 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 5 22:14:25.940790 jq[1414]: false Aug 5 22:14:25.941291 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 5 22:14:25.945233 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 5 22:14:25.955092 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 5 22:14:25.955512 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 5 22:14:25.955978 dbus-daemon[1413]: [system] SELinux support is enabled Aug 5 22:14:25.956275 systemd[1]: Starting update-engine.service - Update Engine... Aug 5 22:14:25.962136 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 5 22:14:25.963673 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 5 22:14:25.963760 extend-filesystems[1415]: Found loop3 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found loop4 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found loop5 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda1 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda2 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda3 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found usr Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda4 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda6 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda7 Aug 5 22:14:25.965587 extend-filesystems[1415]: Found vda9 Aug 5 22:14:25.965587 extend-filesystems[1415]: Checking size of /dev/vda9 Aug 5 22:14:25.998296 extend-filesystems[1415]: Resized partition /dev/vda9 Aug 5 22:14:25.969487 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 5 22:14:25.999348 jq[1429]: true Aug 5 22:14:25.974413 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 5 22:14:25.974602 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 5 22:14:25.999650 jq[1437]: true Aug 5 22:14:25.974871 systemd[1]: motdgen.service: Deactivated successfully. Aug 5 22:14:25.975005 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 5 22:14:25.976791 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 5 22:14:25.979171 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 5 22:14:25.991198 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Aug 5 22:14:25.991265 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 5 22:14:25.992535 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Aug 5 22:14:25.992554 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 5 22:14:26.002479 extend-filesystems[1436]: resize2fs 1.47.0 (5-Feb-2023) Aug 5 22:14:26.013112 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Aug 5 22:14:26.013150 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1363) Aug 5 22:14:26.006718 (ntainerd)[1440]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 5 22:14:26.015944 systemd-logind[1421]: Watching system buttons on /dev/input/event0 (Power Button) Aug 5 22:14:26.019147 systemd-logind[1421]: New seat seat0. Aug 5 22:14:26.024487 update_engine[1427]: I0805 22:14:26.019791 1427 main.cc:92] Flatcar Update Engine starting Aug 5 22:14:26.020126 systemd[1]: Started systemd-logind.service - User Login Management. Aug 5 22:14:26.028349 systemd[1]: Started update-engine.service - Update Engine. Aug 5 22:14:26.044159 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Aug 5 22:14:26.044222 update_engine[1427]: I0805 22:14:26.029206 1427 update_check_scheduler.cc:74] Next update check in 5m42s Aug 5 22:14:26.036164 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 5 22:14:26.045307 extend-filesystems[1436]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Aug 5 22:14:26.045307 extend-filesystems[1436]: old_desc_blocks = 1, new_desc_blocks = 1 Aug 5 22:14:26.045307 extend-filesystems[1436]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Aug 5 22:14:26.056258 extend-filesystems[1415]: Resized filesystem in /dev/vda9 Aug 5 22:14:26.046541 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 5 22:14:26.049085 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 5 22:14:26.101738 bash[1465]: Updated "/home/core/.ssh/authorized_keys" Aug 5 22:14:26.104887 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 5 22:14:26.106022 locksmithd[1449]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 5 22:14:26.107381 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 5 22:14:26.212793 containerd[1440]: time="2024-08-05T22:14:26.212709574Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Aug 5 22:14:26.238921 containerd[1440]: time="2024-08-05T22:14:26.238868334Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 5 22:14:26.238921 containerd[1440]: time="2024-08-05T22:14:26.238914614Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240268 containerd[1440]: time="2024-08-05T22:14:26.240233454Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.43-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240268 containerd[1440]: time="2024-08-05T22:14:26.240263014Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240506 containerd[1440]: time="2024-08-05T22:14:26.240476694Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240506 containerd[1440]: time="2024-08-05T22:14:26.240499454Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 5 22:14:26.240586 containerd[1440]: time="2024-08-05T22:14:26.240572214Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240634 containerd[1440]: time="2024-08-05T22:14:26.240620614Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240654 containerd[1440]: time="2024-08-05T22:14:26.240635414Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240706 containerd[1440]: time="2024-08-05T22:14:26.240693174Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240912 containerd[1440]: time="2024-08-05T22:14:26.240885614Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 5 22:14:26.240936 containerd[1440]: time="2024-08-05T22:14:26.240910614Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Aug 5 22:14:26.240936 containerd[1440]: time="2024-08-05T22:14:26.240920694Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:14:26.241041 containerd[1440]: time="2024-08-05T22:14:26.241014894Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:14:26.241068 containerd[1440]: time="2024-08-05T22:14:26.241049494Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 5 22:14:26.241117 containerd[1440]: time="2024-08-05T22:14:26.241102214Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Aug 5 22:14:26.241140 containerd[1440]: time="2024-08-05T22:14:26.241119094Z" level=info msg="metadata content store policy set" policy=shared Aug 5 22:14:26.246352 containerd[1440]: time="2024-08-05T22:14:26.246317574Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 5 22:14:26.246389 containerd[1440]: time="2024-08-05T22:14:26.246354254Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 5 22:14:26.246389 containerd[1440]: time="2024-08-05T22:14:26.246373894Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 5 22:14:26.246439 containerd[1440]: time="2024-08-05T22:14:26.246404254Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 5 22:14:26.246439 containerd[1440]: time="2024-08-05T22:14:26.246419934Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 5 22:14:26.246439 containerd[1440]: time="2024-08-05T22:14:26.246429854Z" level=info msg="NRI interface is disabled by configuration." Aug 5 22:14:26.246493 containerd[1440]: time="2024-08-05T22:14:26.246441174Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 5 22:14:26.246593 containerd[1440]: time="2024-08-05T22:14:26.246564174Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 5 22:14:26.246593 containerd[1440]: time="2024-08-05T22:14:26.246589254Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 5 22:14:26.246642 containerd[1440]: time="2024-08-05T22:14:26.246602734Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 5 22:14:26.246642 containerd[1440]: time="2024-08-05T22:14:26.246617454Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 5 22:14:26.246675 containerd[1440]: time="2024-08-05T22:14:26.246639614Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.246675 containerd[1440]: time="2024-08-05T22:14:26.246656454Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.246675 containerd[1440]: time="2024-08-05T22:14:26.246669534Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.246726 containerd[1440]: time="2024-08-05T22:14:26.246681574Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.246726 containerd[1440]: time="2024-08-05T22:14:26.246695294Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.246726 containerd[1440]: time="2024-08-05T22:14:26.246709014Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.246726 containerd[1440]: time="2024-08-05T22:14:26.246721014Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.246791 containerd[1440]: time="2024-08-05T22:14:26.246732454Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 5 22:14:26.246861 containerd[1440]: time="2024-08-05T22:14:26.246836574Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 5 22:14:26.247104 containerd[1440]: time="2024-08-05T22:14:26.247087974Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 5 22:14:26.247137 containerd[1440]: time="2024-08-05T22:14:26.247117334Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247137 containerd[1440]: time="2024-08-05T22:14:26.247130534Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 5 22:14:26.247175 containerd[1440]: time="2024-08-05T22:14:26.247151934Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 5 22:14:26.247270 containerd[1440]: time="2024-08-05T22:14:26.247258574Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247290 containerd[1440]: time="2024-08-05T22:14:26.247277374Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247308 containerd[1440]: time="2024-08-05T22:14:26.247290414Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247308 containerd[1440]: time="2024-08-05T22:14:26.247302894Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247347 containerd[1440]: time="2024-08-05T22:14:26.247315214Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247347 containerd[1440]: time="2024-08-05T22:14:26.247327294Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247347 containerd[1440]: time="2024-08-05T22:14:26.247338814Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247396 containerd[1440]: time="2024-08-05T22:14:26.247349854Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247396 containerd[1440]: time="2024-08-05T22:14:26.247362934Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 5 22:14:26.247502 containerd[1440]: time="2024-08-05T22:14:26.247487494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247525 containerd[1440]: time="2024-08-05T22:14:26.247508134Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247547 containerd[1440]: time="2024-08-05T22:14:26.247522454Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247547 containerd[1440]: time="2024-08-05T22:14:26.247536494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247580 containerd[1440]: time="2024-08-05T22:14:26.247548734Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247580 containerd[1440]: time="2024-08-05T22:14:26.247562334Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247580 containerd[1440]: time="2024-08-05T22:14:26.247573774Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.247628 containerd[1440]: time="2024-08-05T22:14:26.247583974Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 5 22:14:26.248011 containerd[1440]: time="2024-08-05T22:14:26.247946854Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Aug 5 22:14:26.248011 containerd[1440]: time="2024-08-05T22:14:26.248010054Z" level=info msg="Connect containerd service" Aug 5 22:14:26.248156 containerd[1440]: time="2024-08-05T22:14:26.248058814Z" level=info msg="using legacy CRI server" Aug 5 22:14:26.248156 containerd[1440]: time="2024-08-05T22:14:26.248067814Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 5 22:14:26.248243 containerd[1440]: time="2024-08-05T22:14:26.248226414Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 5 22:14:26.248863 containerd[1440]: time="2024-08-05T22:14:26.248834894Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Aug 5 22:14:26.248903 containerd[1440]: time="2024-08-05T22:14:26.248887694Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 5 22:14:26.248926 containerd[1440]: time="2024-08-05T22:14:26.248907534Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Aug 5 22:14:26.248926 containerd[1440]: time="2024-08-05T22:14:26.248918294Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 5 22:14:26.248961 containerd[1440]: time="2024-08-05T22:14:26.248929614Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 5 22:14:26.249284 containerd[1440]: time="2024-08-05T22:14:26.249121214Z" level=info msg="Start subscribing containerd event" Aug 5 22:14:26.249329 containerd[1440]: time="2024-08-05T22:14:26.249302134Z" level=info msg="Start recovering state" Aug 5 22:14:26.249407 containerd[1440]: time="2024-08-05T22:14:26.249392774Z" level=info msg="Start event monitor" Aug 5 22:14:26.249440 containerd[1440]: time="2024-08-05T22:14:26.249411934Z" level=info msg="Start snapshots syncer" Aug 5 22:14:26.249440 containerd[1440]: time="2024-08-05T22:14:26.249421814Z" level=info msg="Start cni network conf syncer for default" Aug 5 22:14:26.249440 containerd[1440]: time="2024-08-05T22:14:26.249430214Z" level=info msg="Start streaming server" Aug 5 22:14:26.249498 containerd[1440]: time="2024-08-05T22:14:26.249472854Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 5 22:14:26.249516 containerd[1440]: time="2024-08-05T22:14:26.249508214Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 5 22:14:26.253524 containerd[1440]: time="2024-08-05T22:14:26.249559294Z" level=info msg="containerd successfully booted in 0.037798s" Aug 5 22:14:26.252147 systemd[1]: Started containerd.service - containerd container runtime. Aug 5 22:14:26.338091 sshd_keygen[1432]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 5 22:14:26.359072 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 5 22:14:26.373514 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 5 22:14:26.378255 systemd[1]: issuegen.service: Deactivated successfully. Aug 5 22:14:26.378437 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 5 22:14:26.381989 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 5 22:14:26.394530 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 5 22:14:26.399410 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 5 22:14:26.401522 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Aug 5 22:14:26.402536 systemd[1]: Reached target getty.target - Login Prompts. Aug 5 22:14:27.154200 systemd-networkd[1361]: eth0: Gained IPv6LL Aug 5 22:14:27.156646 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 5 22:14:27.158401 systemd[1]: Reached target network-online.target - Network is Online. Aug 5 22:14:27.169817 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 5 22:14:27.172128 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:14:27.174012 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 5 22:14:27.190462 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 5 22:14:27.190726 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 5 22:14:27.191923 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 5 22:14:27.194277 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 5 22:14:27.740661 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:14:27.742170 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 5 22:14:27.744282 (kubelet)[1519]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 5 22:14:27.748099 systemd[1]: Startup finished in 533ms (kernel) + 4.203s (initrd) + 3.440s (userspace) = 8.177s. Aug 5 22:14:28.258650 kubelet[1519]: E0805 22:14:28.258511 1519 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Aug 5 22:14:28.260693 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 5 22:14:28.260837 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 5 22:14:32.770981 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 5 22:14:32.774829 systemd[1]: Started sshd@0-10.0.0.112:22-10.0.0.1:46422.service - OpenSSH per-connection server daemon (10.0.0.1:46422). Aug 5 22:14:32.830529 sshd[1533]: Accepted publickey for core from 10.0.0.1 port 46422 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:14:32.832309 sshd[1533]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:14:32.848809 systemd-logind[1421]: New session 1 of user core. Aug 5 22:14:32.851848 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 5 22:14:32.864567 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 5 22:14:32.874628 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 5 22:14:32.880400 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 5 22:14:32.904438 (systemd)[1537]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:14:33.000063 systemd[1537]: Queued start job for default target default.target. Aug 5 22:14:33.011809 systemd[1537]: Created slice app.slice - User Application Slice. Aug 5 22:14:33.011843 systemd[1537]: Reached target paths.target - Paths. Aug 5 22:14:33.011855 systemd[1537]: Reached target timers.target - Timers. Aug 5 22:14:33.021984 systemd[1537]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 5 22:14:33.031544 systemd[1537]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 5 22:14:33.031653 systemd[1537]: Reached target sockets.target - Sockets. Aug 5 22:14:33.031666 systemd[1537]: Reached target basic.target - Basic System. Aug 5 22:14:33.031702 systemd[1537]: Reached target default.target - Main User Target. Aug 5 22:14:33.031728 systemd[1537]: Startup finished in 120ms. Aug 5 22:14:33.031934 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 5 22:14:33.033620 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 5 22:14:33.096126 systemd[1]: Started sshd@1-10.0.0.112:22-10.0.0.1:46428.service - OpenSSH per-connection server daemon (10.0.0.1:46428). Aug 5 22:14:33.129141 sshd[1548]: Accepted publickey for core from 10.0.0.1 port 46428 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:14:33.130578 sshd[1548]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:14:33.137375 systemd-logind[1421]: New session 2 of user core. Aug 5 22:14:33.149253 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 5 22:14:33.203243 sshd[1548]: pam_unix(sshd:session): session closed for user core Aug 5 22:14:33.216609 systemd[1]: sshd@1-10.0.0.112:22-10.0.0.1:46428.service: Deactivated successfully. Aug 5 22:14:33.219515 systemd[1]: session-2.scope: Deactivated successfully. Aug 5 22:14:33.221011 systemd-logind[1421]: Session 2 logged out. Waiting for processes to exit. Aug 5 22:14:33.230345 systemd[1]: Started sshd@2-10.0.0.112:22-10.0.0.1:46444.service - OpenSSH per-connection server daemon (10.0.0.1:46444). Aug 5 22:14:33.231469 systemd-logind[1421]: Removed session 2. Aug 5 22:14:33.279664 sshd[1555]: Accepted publickey for core from 10.0.0.1 port 46444 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:14:33.280966 sshd[1555]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:14:33.285355 systemd-logind[1421]: New session 3 of user core. Aug 5 22:14:33.300229 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 5 22:14:33.348913 sshd[1555]: pam_unix(sshd:session): session closed for user core Aug 5 22:14:33.367618 systemd[1]: sshd@2-10.0.0.112:22-10.0.0.1:46444.service: Deactivated successfully. Aug 5 22:14:33.369805 systemd[1]: session-3.scope: Deactivated successfully. Aug 5 22:14:33.371052 systemd-logind[1421]: Session 3 logged out. Waiting for processes to exit. Aug 5 22:14:33.377435 systemd[1]: Started sshd@3-10.0.0.112:22-10.0.0.1:46452.service - OpenSSH per-connection server daemon (10.0.0.1:46452). Aug 5 22:14:33.378500 systemd-logind[1421]: Removed session 3. Aug 5 22:14:33.407322 sshd[1562]: Accepted publickey for core from 10.0.0.1 port 46452 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:14:33.408640 sshd[1562]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:14:33.412715 systemd-logind[1421]: New session 4 of user core. Aug 5 22:14:33.419261 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 5 22:14:33.473304 sshd[1562]: pam_unix(sshd:session): session closed for user core Aug 5 22:14:33.494614 systemd[1]: sshd@3-10.0.0.112:22-10.0.0.1:46452.service: Deactivated successfully. Aug 5 22:14:33.496444 systemd[1]: session-4.scope: Deactivated successfully. Aug 5 22:14:33.499242 systemd-logind[1421]: Session 4 logged out. Waiting for processes to exit. Aug 5 22:14:33.500571 systemd[1]: Started sshd@4-10.0.0.112:22-10.0.0.1:46456.service - OpenSSH per-connection server daemon (10.0.0.1:46456). Aug 5 22:14:33.501296 systemd-logind[1421]: Removed session 4. Aug 5 22:14:33.533846 sshd[1569]: Accepted publickey for core from 10.0.0.1 port 46456 ssh2: RSA SHA256:m+vSf9MZ8jyHy+Dz2uz+ngzM5NRoRVVH/LZDa5ltoPE Aug 5 22:14:33.535181 sshd[1569]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:14:33.539338 systemd-logind[1421]: New session 5 of user core. Aug 5 22:14:33.545215 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 5 22:14:33.606064 sudo[1572]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 5 22:14:33.606346 sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 22:14:33.630396 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 5 22:14:33.651399 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 5 22:14:33.653232 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 5 22:14:34.207780 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:14:34.216280 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:14:34.233554 systemd[1]: Reloading requested from client PID 1623 ('systemctl') (unit session-5.scope)... Aug 5 22:14:34.233572 systemd[1]: Reloading... Aug 5 22:14:34.298148 zram_generator::config[1659]: No configuration found. Aug 5 22:14:34.476087 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 5 22:14:34.529551 systemd[1]: Reloading finished in 295 ms. Aug 5 22:14:34.571915 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Aug 5 22:14:34.571988 systemd[1]: kubelet.service: Failed with result 'signal'. Aug 5 22:14:34.572219 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:14:34.576302 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:14:34.674967 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:14:34.679750 (kubelet)[1705]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 5 22:14:34.731215 kubelet[1705]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Aug 5 22:14:34.731215 kubelet[1705]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Aug 5 22:14:34.731215 kubelet[1705]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Aug 5 22:14:34.732274 kubelet[1705]: I0805 22:14:34.732208 1705 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 5 22:14:35.190102 kubelet[1705]: I0805 22:14:35.189955 1705 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Aug 5 22:14:35.190102 kubelet[1705]: I0805 22:14:35.189989 1705 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 5 22:14:35.190260 kubelet[1705]: I0805 22:14:35.190224 1705 server.go:927] "Client rotation is on, will bootstrap in background" Aug 5 22:14:35.243187 kubelet[1705]: I0805 22:14:35.242961 1705 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 5 22:14:35.258476 kubelet[1705]: I0805 22:14:35.258375 1705 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 5 22:14:35.260081 kubelet[1705]: I0805 22:14:35.259804 1705 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 5 22:14:35.260167 kubelet[1705]: I0805 22:14:35.259936 1705 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.112","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Aug 5 22:14:35.260383 kubelet[1705]: I0805 22:14:35.260344 1705 topology_manager.go:138] "Creating topology manager with none policy" Aug 5 22:14:35.260383 kubelet[1705]: I0805 22:14:35.260371 1705 container_manager_linux.go:301] "Creating device plugin manager" Aug 5 22:14:35.260668 kubelet[1705]: I0805 22:14:35.260645 1705 state_mem.go:36] "Initialized new in-memory state store" Aug 5 22:14:35.263773 kubelet[1705]: I0805 22:14:35.263732 1705 kubelet.go:400] "Attempting to sync node with API server" Aug 5 22:14:35.263773 kubelet[1705]: I0805 22:14:35.263768 1705 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 5 22:14:35.264056 kubelet[1705]: I0805 22:14:35.264018 1705 kubelet.go:312] "Adding apiserver pod source" Aug 5 22:14:35.264056 kubelet[1705]: I0805 22:14:35.264047 1705 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 5 22:14:35.265326 kubelet[1705]: E0805 22:14:35.264497 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:35.265326 kubelet[1705]: E0805 22:14:35.264708 1705 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:35.267119 kubelet[1705]: I0805 22:14:35.267084 1705 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.17" apiVersion="v1" Aug 5 22:14:35.267467 kubelet[1705]: I0805 22:14:35.267454 1705 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Aug 5 22:14:35.267590 kubelet[1705]: W0805 22:14:35.267564 1705 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 5 22:14:35.268534 kubelet[1705]: I0805 22:14:35.268437 1705 server.go:1264] "Started kubelet" Aug 5 22:14:35.269790 kubelet[1705]: I0805 22:14:35.269758 1705 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 5 22:14:35.273363 kubelet[1705]: I0805 22:14:35.272079 1705 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Aug 5 22:14:35.273363 kubelet[1705]: I0805 22:14:35.273091 1705 server.go:455] "Adding debug handlers to kubelet server" Aug 5 22:14:35.274136 kubelet[1705]: I0805 22:14:35.274115 1705 volume_manager.go:291] "Starting Kubelet Volume Manager" Aug 5 22:14:35.279133 kubelet[1705]: I0805 22:14:35.278960 1705 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Aug 5 22:14:35.279256 kubelet[1705]: I0805 22:14:35.279229 1705 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Aug 5 22:14:35.279256 kubelet[1705]: I0805 22:14:35.279254 1705 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 5 22:14:35.279334 kubelet[1705]: I0805 22:14:35.279320 1705 reconciler.go:26] "Reconciler: start to sync state" Aug 5 22:14:35.279670 kubelet[1705]: E0805 22:14:35.279642 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:35.281739 kubelet[1705]: I0805 22:14:35.281710 1705 factory.go:221] Registration of the systemd container factory successfully Aug 5 22:14:35.281831 kubelet[1705]: I0805 22:14:35.281814 1705 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Aug 5 22:14:35.287241 kubelet[1705]: I0805 22:14:35.287204 1705 factory.go:221] Registration of the containerd container factory successfully Aug 5 22:14:35.287587 kubelet[1705]: W0805 22:14:35.287563 1705 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.112" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 5 22:14:35.287702 kubelet[1705]: E0805 22:14:35.287689 1705 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.112" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 5 22:14:35.287878 kubelet[1705]: E0805 22:14:35.287851 1705 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.112\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Aug 5 22:14:35.288055 kubelet[1705]: W0805 22:14:35.288038 1705 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Aug 5 22:14:35.288122 kubelet[1705]: E0805 22:14:35.288111 1705 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Aug 5 22:14:35.288439 kubelet[1705]: E0805 22:14:35.288420 1705 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 5 22:14:35.305824 kubelet[1705]: I0805 22:14:35.305286 1705 cpu_manager.go:214] "Starting CPU manager" policy="none" Aug 5 22:14:35.305824 kubelet[1705]: I0805 22:14:35.305357 1705 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Aug 5 22:14:35.305824 kubelet[1705]: I0805 22:14:35.305380 1705 state_mem.go:36] "Initialized new in-memory state store" Aug 5 22:14:35.381401 kubelet[1705]: I0805 22:14:35.380829 1705 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.112" Aug 5 22:14:35.381584 kubelet[1705]: I0805 22:14:35.381569 1705 policy_none.go:49] "None policy: Start" Aug 5 22:14:35.384848 kubelet[1705]: I0805 22:14:35.384795 1705 memory_manager.go:170] "Starting memorymanager" policy="None" Aug 5 22:14:35.384848 kubelet[1705]: I0805 22:14:35.384842 1705 state_mem.go:35] "Initializing new in-memory state store" Aug 5 22:14:35.389559 kubelet[1705]: I0805 22:14:35.389278 1705 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.112" Aug 5 22:14:35.395959 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Aug 5 22:14:35.397581 kubelet[1705]: I0805 22:14:35.397517 1705 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Aug 5 22:14:35.398007 containerd[1440]: time="2024-08-05T22:14:35.397962134Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Aug 5 22:14:35.408756 kubelet[1705]: I0805 22:14:35.398184 1705 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Aug 5 22:14:35.414697 kubelet[1705]: E0805 22:14:35.414633 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:35.415238 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Aug 5 22:14:35.421462 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Aug 5 22:14:35.433078 kubelet[1705]: I0805 22:14:35.433007 1705 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 5 22:14:35.434354 kubelet[1705]: I0805 22:14:35.434309 1705 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 5 22:14:35.434426 kubelet[1705]: I0805 22:14:35.434412 1705 status_manager.go:217] "Starting to sync pod status with apiserver" Aug 5 22:14:35.434463 kubelet[1705]: I0805 22:14:35.434431 1705 kubelet.go:2337] "Starting kubelet main sync loop" Aug 5 22:14:35.434499 kubelet[1705]: E0805 22:14:35.434477 1705 kubelet.go:2361] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Aug 5 22:14:35.434643 kubelet[1705]: I0805 22:14:35.434323 1705 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 5 22:14:35.434897 kubelet[1705]: I0805 22:14:35.434836 1705 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Aug 5 22:14:35.434968 kubelet[1705]: I0805 22:14:35.434954 1705 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 5 22:14:35.439126 kubelet[1705]: E0805 22:14:35.439073 1705 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.112\" not found" Aug 5 22:14:35.515686 kubelet[1705]: E0805 22:14:35.515498 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:35.616243 kubelet[1705]: E0805 22:14:35.616172 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:35.703089 sudo[1572]: pam_unix(sudo:session): session closed for user root Aug 5 22:14:35.704754 sshd[1569]: pam_unix(sshd:session): session closed for user core Aug 5 22:14:35.708240 systemd[1]: sshd@4-10.0.0.112:22-10.0.0.1:46456.service: Deactivated successfully. Aug 5 22:14:35.709810 systemd[1]: session-5.scope: Deactivated successfully. Aug 5 22:14:35.710510 systemd-logind[1421]: Session 5 logged out. Waiting for processes to exit. Aug 5 22:14:35.711649 systemd-logind[1421]: Removed session 5. Aug 5 22:14:35.716807 kubelet[1705]: E0805 22:14:35.716762 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:35.818149 kubelet[1705]: E0805 22:14:35.817668 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:35.918404 kubelet[1705]: E0805 22:14:35.918333 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:36.019018 kubelet[1705]: E0805 22:14:36.018967 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:36.119716 kubelet[1705]: E0805 22:14:36.119571 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:36.193216 kubelet[1705]: I0805 22:14:36.193135 1705 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Aug 5 22:14:36.193452 kubelet[1705]: W0805 22:14:36.193346 1705 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Aug 5 22:14:36.193452 kubelet[1705]: W0805 22:14:36.193418 1705 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Aug 5 22:14:36.220643 kubelet[1705]: E0805 22:14:36.220574 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:36.267842 kubelet[1705]: E0805 22:14:36.264966 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:36.322436 kubelet[1705]: E0805 22:14:36.322357 1705 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.112\" not found" Aug 5 22:14:37.266089 kubelet[1705]: E0805 22:14:37.266044 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:37.266696 kubelet[1705]: I0805 22:14:37.266480 1705 apiserver.go:52] "Watching apiserver" Aug 5 22:14:37.283891 kubelet[1705]: I0805 22:14:37.283849 1705 topology_manager.go:215] "Topology Admit Handler" podUID="fa641f0d-5c33-41af-8f2d-1b040a4aa288" podNamespace="kube-flannel" podName="kube-flannel-ds-5xm4d" Aug 5 22:14:37.283986 kubelet[1705]: I0805 22:14:37.283976 1705 topology_manager.go:215] "Topology Admit Handler" podUID="911411b2-e27f-4db6-bd9b-b12cfee22cea" podNamespace="kube-system" podName="kube-proxy-ftvtc" Aug 5 22:14:37.290022 systemd[1]: Created slice kubepods-besteffort-pod911411b2_e27f_4db6_bd9b_b12cfee22cea.slice - libcontainer container kubepods-besteffort-pod911411b2_e27f_4db6_bd9b_b12cfee22cea.slice. Aug 5 22:14:37.302442 systemd[1]: Created slice kubepods-burstable-podfa641f0d_5c33_41af_8f2d_1b040a4aa288.slice - libcontainer container kubepods-burstable-podfa641f0d_5c33_41af_8f2d_1b040a4aa288.slice. Aug 5 22:14:37.379710 kubelet[1705]: I0805 22:14:37.379675 1705 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Aug 5 22:14:37.394728 kubelet[1705]: I0805 22:14:37.394659 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/fa641f0d-5c33-41af-8f2d-1b040a4aa288-cni\") pod \"kube-flannel-ds-5xm4d\" (UID: \"fa641f0d-5c33-41af-8f2d-1b040a4aa288\") " pod="kube-flannel/kube-flannel-ds-5xm4d" Aug 5 22:14:37.394728 kubelet[1705]: I0805 22:14:37.394698 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/fa641f0d-5c33-41af-8f2d-1b040a4aa288-flannel-cfg\") pod \"kube-flannel-ds-5xm4d\" (UID: \"fa641f0d-5c33-41af-8f2d-1b040a4aa288\") " pod="kube-flannel/kube-flannel-ds-5xm4d" Aug 5 22:14:37.394728 kubelet[1705]: I0805 22:14:37.394717 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/911411b2-e27f-4db6-bd9b-b12cfee22cea-kube-proxy\") pod \"kube-proxy-ftvtc\" (UID: \"911411b2-e27f-4db6-bd9b-b12cfee22cea\") " pod="kube-system/kube-proxy-ftvtc" Aug 5 22:14:37.394882 kubelet[1705]: I0805 22:14:37.394733 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/911411b2-e27f-4db6-bd9b-b12cfee22cea-lib-modules\") pod \"kube-proxy-ftvtc\" (UID: \"911411b2-e27f-4db6-bd9b-b12cfee22cea\") " pod="kube-system/kube-proxy-ftvtc" Aug 5 22:14:37.394882 kubelet[1705]: I0805 22:14:37.394765 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/911411b2-e27f-4db6-bd9b-b12cfee22cea-xtables-lock\") pod \"kube-proxy-ftvtc\" (UID: \"911411b2-e27f-4db6-bd9b-b12cfee22cea\") " pod="kube-system/kube-proxy-ftvtc" Aug 5 22:14:37.394882 kubelet[1705]: I0805 22:14:37.394805 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lcf4q\" (UniqueName: \"kubernetes.io/projected/911411b2-e27f-4db6-bd9b-b12cfee22cea-kube-api-access-lcf4q\") pod \"kube-proxy-ftvtc\" (UID: \"911411b2-e27f-4db6-bd9b-b12cfee22cea\") " pod="kube-system/kube-proxy-ftvtc" Aug 5 22:14:37.394882 kubelet[1705]: I0805 22:14:37.394838 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/fa641f0d-5c33-41af-8f2d-1b040a4aa288-run\") pod \"kube-flannel-ds-5xm4d\" (UID: \"fa641f0d-5c33-41af-8f2d-1b040a4aa288\") " pod="kube-flannel/kube-flannel-ds-5xm4d" Aug 5 22:14:37.394882 kubelet[1705]: I0805 22:14:37.394862 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/fa641f0d-5c33-41af-8f2d-1b040a4aa288-cni-plugin\") pod \"kube-flannel-ds-5xm4d\" (UID: \"fa641f0d-5c33-41af-8f2d-1b040a4aa288\") " pod="kube-flannel/kube-flannel-ds-5xm4d" Aug 5 22:14:37.394986 kubelet[1705]: I0805 22:14:37.394879 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/fa641f0d-5c33-41af-8f2d-1b040a4aa288-xtables-lock\") pod \"kube-flannel-ds-5xm4d\" (UID: \"fa641f0d-5c33-41af-8f2d-1b040a4aa288\") " pod="kube-flannel/kube-flannel-ds-5xm4d" Aug 5 22:14:37.394986 kubelet[1705]: I0805 22:14:37.394920 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5b5ff\" (UniqueName: \"kubernetes.io/projected/fa641f0d-5c33-41af-8f2d-1b040a4aa288-kube-api-access-5b5ff\") pod \"kube-flannel-ds-5xm4d\" (UID: \"fa641f0d-5c33-41af-8f2d-1b040a4aa288\") " pod="kube-flannel/kube-flannel-ds-5xm4d" Aug 5 22:14:37.600730 kubelet[1705]: E0805 22:14:37.600269 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:37.600972 containerd[1440]: time="2024-08-05T22:14:37.600866534Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ftvtc,Uid:911411b2-e27f-4db6-bd9b-b12cfee22cea,Namespace:kube-system,Attempt:0,}" Aug 5 22:14:37.616981 kubelet[1705]: E0805 22:14:37.616740 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:37.617259 containerd[1440]: time="2024-08-05T22:14:37.617193294Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5xm4d,Uid:fa641f0d-5c33-41af-8f2d-1b040a4aa288,Namespace:kube-flannel,Attempt:0,}" Aug 5 22:14:38.082909 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount248961553.mount: Deactivated successfully. Aug 5 22:14:38.089476 containerd[1440]: time="2024-08-05T22:14:38.089430774Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 5 22:14:38.090311 containerd[1440]: time="2024-08-05T22:14:38.090263894Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 5 22:14:38.090960 containerd[1440]: time="2024-08-05T22:14:38.090935694Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Aug 5 22:14:38.091822 containerd[1440]: time="2024-08-05T22:14:38.091790614Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 5 22:14:38.092669 containerd[1440]: time="2024-08-05T22:14:38.092149654Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 5 22:14:38.095047 containerd[1440]: time="2024-08-05T22:14:38.094989134Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 5 22:14:38.096011 containerd[1440]: time="2024-08-05T22:14:38.095961294Z" 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 478.65128ms" Aug 5 22:14:38.098852 containerd[1440]: time="2024-08-05T22:14:38.098823854Z" 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 497.85588ms" Aug 5 22:14:38.236540 containerd[1440]: time="2024-08-05T22:14:38.236421974Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:14:38.236540 containerd[1440]: time="2024-08-05T22:14:38.236491694Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:38.236817 containerd[1440]: time="2024-08-05T22:14:38.236511134Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:14:38.236817 containerd[1440]: time="2024-08-05T22:14:38.236525614Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:38.237585 containerd[1440]: time="2024-08-05T22:14:38.237503694Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:14:38.237585 containerd[1440]: time="2024-08-05T22:14:38.237559214Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:38.237731 containerd[1440]: time="2024-08-05T22:14:38.237583454Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:14:38.237731 containerd[1440]: time="2024-08-05T22:14:38.237596694Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:38.266600 kubelet[1705]: E0805 22:14:38.266573 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:38.351193 systemd[1]: Started cri-containerd-62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593.scope - libcontainer container 62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593. Aug 5 22:14:38.352886 systemd[1]: Started cri-containerd-8fa7065e0c30ce792d0e1db2d7b61497f75034c0ad055c9d8c124571ac85f322.scope - libcontainer container 8fa7065e0c30ce792d0e1db2d7b61497f75034c0ad055c9d8c124571ac85f322. Aug 5 22:14:38.373955 containerd[1440]: time="2024-08-05T22:14:38.373832454Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ftvtc,Uid:911411b2-e27f-4db6-bd9b-b12cfee22cea,Namespace:kube-system,Attempt:0,} returns sandbox id \"8fa7065e0c30ce792d0e1db2d7b61497f75034c0ad055c9d8c124571ac85f322\"" Aug 5 22:14:38.375734 kubelet[1705]: E0805 22:14:38.375252 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:38.378636 containerd[1440]: time="2024-08-05T22:14:38.378594174Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.3\"" Aug 5 22:14:38.382892 containerd[1440]: time="2024-08-05T22:14:38.381741574Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5xm4d,Uid:fa641f0d-5c33-41af-8f2d-1b040a4aa288,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593\"" Aug 5 22:14:38.383885 kubelet[1705]: E0805 22:14:38.383596 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:39.267870 kubelet[1705]: E0805 22:14:39.267827 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:39.315104 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3501278218.mount: Deactivated successfully. Aug 5 22:14:39.523086 containerd[1440]: time="2024-08-05T22:14:39.522764254Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:39.523584 containerd[1440]: time="2024-08-05T22:14:39.523426214Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.3: active requests=0, bytes read=25646938" Aug 5 22:14:39.524127 containerd[1440]: time="2024-08-05T22:14:39.524071494Z" level=info msg="ImageCreate event name:\"sha256:2351f570ed0eac5533e538280d73c6aa5d6b6f6379f5f3fac08f51378621e6be\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:39.526544 containerd[1440]: time="2024-08-05T22:14:39.526488294Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:b26e535e8ee1cbd7dc5642fb61bd36e9d23f32e9242ae0010b2905656e664f65\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:39.527267 containerd[1440]: time="2024-08-05T22:14:39.527225014Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.3\" with image id \"sha256:2351f570ed0eac5533e538280d73c6aa5d6b6f6379f5f3fac08f51378621e6be\", repo tag \"registry.k8s.io/kube-proxy:v1.30.3\", repo digest \"registry.k8s.io/kube-proxy@sha256:b26e535e8ee1cbd7dc5642fb61bd36e9d23f32e9242ae0010b2905656e664f65\", size \"25645955\" in 1.1485916s" Aug 5 22:14:39.527336 containerd[1440]: time="2024-08-05T22:14:39.527267734Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.3\" returns image reference \"sha256:2351f570ed0eac5533e538280d73c6aa5d6b6f6379f5f3fac08f51378621e6be\"" Aug 5 22:14:39.528864 containerd[1440]: time="2024-08-05T22:14:39.528827094Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Aug 5 22:14:39.530199 containerd[1440]: time="2024-08-05T22:14:39.530068334Z" level=info msg="CreateContainer within sandbox \"8fa7065e0c30ce792d0e1db2d7b61497f75034c0ad055c9d8c124571ac85f322\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Aug 5 22:14:39.543624 containerd[1440]: time="2024-08-05T22:14:39.543462134Z" level=info msg="CreateContainer within sandbox \"8fa7065e0c30ce792d0e1db2d7b61497f75034c0ad055c9d8c124571ac85f322\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"6f6e42f23a79be7440d013b80e0e03c8c194b20ca5563453f414ee9ecefc0c2e\"" Aug 5 22:14:39.544216 containerd[1440]: time="2024-08-05T22:14:39.544170174Z" level=info msg="StartContainer for \"6f6e42f23a79be7440d013b80e0e03c8c194b20ca5563453f414ee9ecefc0c2e\"" Aug 5 22:14:39.574192 systemd[1]: Started cri-containerd-6f6e42f23a79be7440d013b80e0e03c8c194b20ca5563453f414ee9ecefc0c2e.scope - libcontainer container 6f6e42f23a79be7440d013b80e0e03c8c194b20ca5563453f414ee9ecefc0c2e. Aug 5 22:14:39.597795 containerd[1440]: time="2024-08-05T22:14:39.597745534Z" level=info msg="StartContainer for \"6f6e42f23a79be7440d013b80e0e03c8c194b20ca5563453f414ee9ecefc0c2e\" returns successfully" Aug 5 22:14:40.268698 kubelet[1705]: E0805 22:14:40.268657 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:40.447811 kubelet[1705]: E0805 22:14:40.447475 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:40.457653 kubelet[1705]: I0805 22:14:40.457452 1705 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-ftvtc" podStartSLOduration=4.305566454 podStartE2EDuration="5.457268374s" podCreationTimestamp="2024-08-05 22:14:35 +0000 UTC" firstStartedPulling="2024-08-05 22:14:38.376461854 +0000 UTC m=+3.693123241" lastFinishedPulling="2024-08-05 22:14:39.528163774 +0000 UTC m=+4.844825161" observedRunningTime="2024-08-05 22:14:40.456099094 +0000 UTC m=+5.772760481" watchObservedRunningTime="2024-08-05 22:14:40.457268374 +0000 UTC m=+5.773929761" Aug 5 22:14:40.516141 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4281078793.mount: Deactivated successfully. Aug 5 22:14:40.537958 containerd[1440]: time="2024-08-05T22:14:40.537844734Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:40.540426 containerd[1440]: time="2024-08-05T22:14:40.540338374Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Aug 5 22:14:40.541247 containerd[1440]: time="2024-08-05T22:14:40.541212174Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:40.543807 containerd[1440]: time="2024-08-05T22:14:40.543776254Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:40.544878 containerd[1440]: time="2024-08-05T22:14:40.544851014Z" 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.01598956s" Aug 5 22:14:40.545337 containerd[1440]: time="2024-08-05T22:14:40.545315534Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Aug 5 22:14:40.547337 containerd[1440]: time="2024-08-05T22:14:40.547307294Z" level=info msg="CreateContainer within sandbox \"62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Aug 5 22:14:40.561382 containerd[1440]: time="2024-08-05T22:14:40.561334534Z" level=info msg="CreateContainer within sandbox \"62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5\"" Aug 5 22:14:40.562936 containerd[1440]: time="2024-08-05T22:14:40.562048934Z" level=info msg="StartContainer for \"47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5\"" Aug 5 22:14:40.579018 systemd[1]: run-containerd-runc-k8s.io-47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5-runc.e0U2kV.mount: Deactivated successfully. Aug 5 22:14:40.589204 systemd[1]: Started cri-containerd-47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5.scope - libcontainer container 47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5. Aug 5 22:14:40.613550 containerd[1440]: time="2024-08-05T22:14:40.613497414Z" level=info msg="StartContainer for \"47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5\" returns successfully" Aug 5 22:14:40.619865 systemd[1]: cri-containerd-47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5.scope: Deactivated successfully. Aug 5 22:14:40.753529 containerd[1440]: time="2024-08-05T22:14:40.753475174Z" level=info msg="shim disconnected" id=47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5 namespace=k8s.io Aug 5 22:14:40.753801 containerd[1440]: time="2024-08-05T22:14:40.753781494Z" level=warning msg="cleaning up after shim disconnected" id=47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5 namespace=k8s.io Aug 5 22:14:40.753865 containerd[1440]: time="2024-08-05T22:14:40.753852054Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 5 22:14:41.268935 kubelet[1705]: E0805 22:14:41.268876 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:41.449482 kubelet[1705]: E0805 22:14:41.449340 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:41.449482 kubelet[1705]: E0805 22:14:41.449382 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:41.449874 containerd[1440]: time="2024-08-05T22:14:41.449846334Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Aug 5 22:14:41.554136 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-47fe7b934b04fea66884d03b1e1ad3975efbdb95dfbf43051fbeb1e394ee26e5-rootfs.mount: Deactivated successfully. Aug 5 22:14:42.269472 kubelet[1705]: E0805 22:14:42.269438 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:42.496374 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1517826752.mount: Deactivated successfully. Aug 5 22:14:42.971432 containerd[1440]: time="2024-08-05T22:14:42.971382734Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:42.973502 containerd[1440]: time="2024-08-05T22:14:42.972793494Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Aug 5 22:14:42.974267 containerd[1440]: time="2024-08-05T22:14:42.974231454Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:42.978325 containerd[1440]: time="2024-08-05T22:14:42.977752254Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:42.979135 containerd[1440]: time="2024-08-05T22:14:42.979099894Z" 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.52921144s" Aug 5 22:14:42.979260 containerd[1440]: time="2024-08-05T22:14:42.979221734Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Aug 5 22:14:42.981478 containerd[1440]: time="2024-08-05T22:14:42.981446214Z" level=info msg="CreateContainer within sandbox \"62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Aug 5 22:14:42.992382 containerd[1440]: time="2024-08-05T22:14:42.992262334Z" level=info msg="CreateContainer within sandbox \"62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0\"" Aug 5 22:14:42.992939 containerd[1440]: time="2024-08-05T22:14:42.992917734Z" level=info msg="StartContainer for \"6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0\"" Aug 5 22:14:43.021224 systemd[1]: Started cri-containerd-6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0.scope - libcontainer container 6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0. Aug 5 22:14:43.043074 containerd[1440]: time="2024-08-05T22:14:43.043015694Z" level=info msg="StartContainer for \"6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0\" returns successfully" Aug 5 22:14:43.046775 systemd[1]: cri-containerd-6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0.scope: Deactivated successfully. Aug 5 22:14:43.052465 kubelet[1705]: I0805 22:14:43.052435 1705 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Aug 5 22:14:43.167970 containerd[1440]: time="2024-08-05T22:14:43.167832014Z" level=info msg="shim disconnected" id=6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0 namespace=k8s.io Aug 5 22:14:43.167970 containerd[1440]: time="2024-08-05T22:14:43.167894494Z" level=warning msg="cleaning up after shim disconnected" id=6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0 namespace=k8s.io Aug 5 22:14:43.167970 containerd[1440]: time="2024-08-05T22:14:43.167903094Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 5 22:14:43.269709 kubelet[1705]: E0805 22:14:43.269558 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:43.453514 kubelet[1705]: E0805 22:14:43.453371 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:43.455123 containerd[1440]: time="2024-08-05T22:14:43.455082454Z" level=info msg="CreateContainer within sandbox \"62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Aug 5 22:14:43.467366 containerd[1440]: time="2024-08-05T22:14:43.467228974Z" level=info msg="CreateContainer within sandbox \"62ac60be16d927ef4d9f79e4848f5bfe7a027bdc6b636e61866b7609438e2593\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"6c9dd6da8c876fc63dde4c88c51dc34881ca204594acad7bc21c11daa60f3ba0\"" Aug 5 22:14:43.467937 containerd[1440]: time="2024-08-05T22:14:43.467901414Z" level=info msg="StartContainer for \"6c9dd6da8c876fc63dde4c88c51dc34881ca204594acad7bc21c11daa60f3ba0\"" Aug 5 22:14:43.493243 systemd[1]: Started cri-containerd-6c9dd6da8c876fc63dde4c88c51dc34881ca204594acad7bc21c11daa60f3ba0.scope - libcontainer container 6c9dd6da8c876fc63dde4c88c51dc34881ca204594acad7bc21c11daa60f3ba0. Aug 5 22:14:43.516640 containerd[1440]: time="2024-08-05T22:14:43.516593734Z" level=info msg="StartContainer for \"6c9dd6da8c876fc63dde4c88c51dc34881ca204594acad7bc21c11daa60f3ba0\" returns successfully" Aug 5 22:14:43.989246 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6321e587eb8506a1e8c86f3c9c4243fc2339c8b603b7ad382a1c44c2c1aa48a0-rootfs.mount: Deactivated successfully. Aug 5 22:14:44.270598 kubelet[1705]: E0805 22:14:44.270501 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:44.456763 kubelet[1705]: E0805 22:14:44.456721 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:44.465715 kubelet[1705]: I0805 22:14:44.465505 1705 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-5xm4d" podStartSLOduration=4.869632574 podStartE2EDuration="9.465489334s" podCreationTimestamp="2024-08-05 22:14:35 +0000 UTC" firstStartedPulling="2024-08-05 22:14:38.384150614 +0000 UTC m=+3.700812001" lastFinishedPulling="2024-08-05 22:14:42.980007374 +0000 UTC m=+8.296668761" observedRunningTime="2024-08-05 22:14:44.465363494 +0000 UTC m=+9.782024881" watchObservedRunningTime="2024-08-05 22:14:44.465489334 +0000 UTC m=+9.782150681" Aug 5 22:14:44.587440 systemd-networkd[1361]: flannel.1: Link UP Aug 5 22:14:44.587447 systemd-networkd[1361]: flannel.1: Gained carrier Aug 5 22:14:45.271274 kubelet[1705]: E0805 22:14:45.271226 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:45.460116 kubelet[1705]: E0805 22:14:45.458559 1705 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 5 22:14:46.272129 kubelet[1705]: E0805 22:14:46.272081 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:46.537573 kubelet[1705]: I0805 22:14:46.537464 1705 topology_manager.go:215] "Topology Admit Handler" podUID="0028ee6d-17b7-4a6a-b04f-ea658afa022f" podNamespace="default" podName="nginx-deployment-85f456d6dd-shnh2" Aug 5 22:14:46.542394 systemd[1]: Created slice kubepods-besteffort-pod0028ee6d_17b7_4a6a_b04f_ea658afa022f.slice - libcontainer container kubepods-besteffort-pod0028ee6d_17b7_4a6a_b04f_ea658afa022f.slice. Aug 5 22:14:46.546151 systemd-networkd[1361]: flannel.1: Gained IPv6LL Aug 5 22:14:46.649725 kubelet[1705]: I0805 22:14:46.649674 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4qz9n\" (UniqueName: \"kubernetes.io/projected/0028ee6d-17b7-4a6a-b04f-ea658afa022f-kube-api-access-4qz9n\") pod \"nginx-deployment-85f456d6dd-shnh2\" (UID: \"0028ee6d-17b7-4a6a-b04f-ea658afa022f\") " pod="default/nginx-deployment-85f456d6dd-shnh2" Aug 5 22:14:46.844980 containerd[1440]: time="2024-08-05T22:14:46.844881974Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-shnh2,Uid:0028ee6d-17b7-4a6a-b04f-ea658afa022f,Namespace:default,Attempt:0,}" Aug 5 22:14:46.885638 systemd-networkd[1361]: cni0: Link UP Aug 5 22:14:46.885643 systemd-networkd[1361]: cni0: Gained carrier Aug 5 22:14:46.886348 systemd-networkd[1361]: cni0: Lost carrier Aug 5 22:14:46.891009 systemd-networkd[1361]: veth20d7cae8: Link UP Aug 5 22:14:46.892707 kernel: cni0: port 1(veth20d7cae8) entered blocking state Aug 5 22:14:46.892775 kernel: cni0: port 1(veth20d7cae8) entered disabled state Aug 5 22:14:46.892795 kernel: veth20d7cae8: entered allmulticast mode Aug 5 22:14:46.894217 kernel: veth20d7cae8: entered promiscuous mode Aug 5 22:14:46.894308 kernel: cni0: port 1(veth20d7cae8) entered blocking state Aug 5 22:14:46.896024 kernel: cni0: port 1(veth20d7cae8) entered forwarding state Aug 5 22:14:46.896097 kernel: cni0: port 1(veth20d7cae8) entered disabled state Aug 5 22:14:46.908055 kernel: cni0: port 1(veth20d7cae8) entered blocking state Aug 5 22:14:46.908159 kernel: cni0: port 1(veth20d7cae8) entered forwarding state Aug 5 22:14:46.907861 systemd-networkd[1361]: veth20d7cae8: Gained carrier Aug 5 22:14:46.908517 systemd-networkd[1361]: cni0: Gained carrier Aug 5 22:14:46.909223 containerd[1440]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016938), "name":"cbr0", "type":"bridge"} Aug 5 22:14:46.909223 containerd[1440]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:14:46.924276 containerd[1440]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-08-05T22:14:46.923723734Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:14:46.924276 containerd[1440]: time="2024-08-05T22:14:46.924231094Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:46.924276 containerd[1440]: time="2024-08-05T22:14:46.924247734Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:14:46.924276 containerd[1440]: time="2024-08-05T22:14:46.924260214Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:46.947218 systemd[1]: Started cri-containerd-5d93bc45af9e7aafd67bc00dedc97fd04216f4d764069cee473a3204d98b498d.scope - libcontainer container 5d93bc45af9e7aafd67bc00dedc97fd04216f4d764069cee473a3204d98b498d. Aug 5 22:14:46.957313 systemd-resolved[1314]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 22:14:46.973492 containerd[1440]: time="2024-08-05T22:14:46.973457534Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-shnh2,Uid:0028ee6d-17b7-4a6a-b04f-ea658afa022f,Namespace:default,Attempt:0,} returns sandbox id \"5d93bc45af9e7aafd67bc00dedc97fd04216f4d764069cee473a3204d98b498d\"" Aug 5 22:14:46.974648 containerd[1440]: time="2024-08-05T22:14:46.974627174Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 5 22:14:47.272780 kubelet[1705]: E0805 22:14:47.272655 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:48.273587 kubelet[1705]: E0805 22:14:48.273547 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:48.274337 systemd-networkd[1361]: veth20d7cae8: Gained IPv6LL Aug 5 22:14:48.595356 systemd-networkd[1361]: cni0: Gained IPv6LL Aug 5 22:14:48.613561 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3873729838.mount: Deactivated successfully. Aug 5 22:14:49.250718 containerd[1440]: time="2024-08-05T22:14:49.250655334Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:49.251759 containerd[1440]: time="2024-08-05T22:14:49.251660094Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67637073" Aug 5 22:14:49.252344 containerd[1440]: time="2024-08-05T22:14:49.252304374Z" level=info msg="ImageCreate event name:\"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:49.256093 containerd[1440]: time="2024-08-05T22:14:49.256050814Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:49.257112 containerd[1440]: time="2024-08-05T22:14:49.257061614Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"67636951\" in 2.2822768s" Aug 5 22:14:49.257168 containerd[1440]: time="2024-08-05T22:14:49.257118974Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\"" Aug 5 22:14:49.262815 containerd[1440]: time="2024-08-05T22:14:49.262764574Z" level=info msg="CreateContainer within sandbox \"5d93bc45af9e7aafd67bc00dedc97fd04216f4d764069cee473a3204d98b498d\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Aug 5 22:14:49.272201 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4009419754.mount: Deactivated successfully. Aug 5 22:14:49.273997 kubelet[1705]: E0805 22:14:49.273949 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:49.275453 containerd[1440]: time="2024-08-05T22:14:49.275416854Z" level=info msg="CreateContainer within sandbox \"5d93bc45af9e7aafd67bc00dedc97fd04216f4d764069cee473a3204d98b498d\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"0941b87d90da4ab2d290fffe2818003173e4114af8be8aa3f48f034ff8164e62\"" Aug 5 22:14:49.275813 containerd[1440]: time="2024-08-05T22:14:49.275788574Z" level=info msg="StartContainer for \"0941b87d90da4ab2d290fffe2818003173e4114af8be8aa3f48f034ff8164e62\"" Aug 5 22:14:49.308183 systemd[1]: Started cri-containerd-0941b87d90da4ab2d290fffe2818003173e4114af8be8aa3f48f034ff8164e62.scope - libcontainer container 0941b87d90da4ab2d290fffe2818003173e4114af8be8aa3f48f034ff8164e62. Aug 5 22:14:49.329007 containerd[1440]: time="2024-08-05T22:14:49.327939454Z" level=info msg="StartContainer for \"0941b87d90da4ab2d290fffe2818003173e4114af8be8aa3f48f034ff8164e62\" returns successfully" Aug 5 22:14:49.470571 kubelet[1705]: I0805 22:14:49.470515 1705 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-shnh2" podStartSLOduration=1.183552214 podStartE2EDuration="3.470498894s" podCreationTimestamp="2024-08-05 22:14:46 +0000 UTC" firstStartedPulling="2024-08-05 22:14:46.974463094 +0000 UTC m=+12.291124481" lastFinishedPulling="2024-08-05 22:14:49.261409774 +0000 UTC m=+14.578071161" observedRunningTime="2024-08-05 22:14:49.470324734 +0000 UTC m=+14.786986121" watchObservedRunningTime="2024-08-05 22:14:49.470498894 +0000 UTC m=+14.787160281" Aug 5 22:14:50.270051 systemd[1]: run-containerd-runc-k8s.io-0941b87d90da4ab2d290fffe2818003173e4114af8be8aa3f48f034ff8164e62-runc.xyc4v6.mount: Deactivated successfully. Aug 5 22:14:50.275110 kubelet[1705]: E0805 22:14:50.275065 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:51.275832 kubelet[1705]: E0805 22:14:51.275763 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:52.277507 kubelet[1705]: E0805 22:14:52.276886 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:53.277840 kubelet[1705]: E0805 22:14:53.277784 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:53.474024 kubelet[1705]: I0805 22:14:53.473988 1705 topology_manager.go:215] "Topology Admit Handler" podUID="8e296a2b-d00c-4034-ad02-dd5cc2599b9b" podNamespace="default" podName="nfs-server-provisioner-0" Aug 5 22:14:53.478925 systemd[1]: Created slice kubepods-besteffort-pod8e296a2b_d00c_4034_ad02_dd5cc2599b9b.slice - libcontainer container kubepods-besteffort-pod8e296a2b_d00c_4034_ad02_dd5cc2599b9b.slice. Aug 5 22:14:53.490743 kubelet[1705]: I0805 22:14:53.490698 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mm8k9\" (UniqueName: \"kubernetes.io/projected/8e296a2b-d00c-4034-ad02-dd5cc2599b9b-kube-api-access-mm8k9\") pod \"nfs-server-provisioner-0\" (UID: \"8e296a2b-d00c-4034-ad02-dd5cc2599b9b\") " pod="default/nfs-server-provisioner-0" Aug 5 22:14:53.490872 kubelet[1705]: I0805 22:14:53.490767 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/8e296a2b-d00c-4034-ad02-dd5cc2599b9b-data\") pod \"nfs-server-provisioner-0\" (UID: \"8e296a2b-d00c-4034-ad02-dd5cc2599b9b\") " pod="default/nfs-server-provisioner-0" Aug 5 22:14:53.782665 containerd[1440]: time="2024-08-05T22:14:53.782614174Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:8e296a2b-d00c-4034-ad02-dd5cc2599b9b,Namespace:default,Attempt:0,}" Aug 5 22:14:53.815106 systemd-networkd[1361]: veth9a459ff5: Link UP Aug 5 22:14:53.818786 kernel: cni0: port 2(veth9a459ff5) entered blocking state Aug 5 22:14:53.818848 kernel: cni0: port 2(veth9a459ff5) entered disabled state Aug 5 22:14:53.818876 kernel: veth9a459ff5: entered allmulticast mode Aug 5 22:14:53.818893 kernel: veth9a459ff5: entered promiscuous mode Aug 5 22:14:53.822738 systemd-networkd[1361]: veth9a459ff5: Gained carrier Aug 5 22:14:53.823310 kernel: cni0: port 2(veth9a459ff5) entered blocking state Aug 5 22:14:53.823359 kernel: cni0: port 2(veth9a459ff5) entered forwarding state Aug 5 22:14:53.827527 containerd[1440]: 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)(0x4000014938), "name":"cbr0", "type":"bridge"} Aug 5 22:14:53.827527 containerd[1440]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:14:53.845244 containerd[1440]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-08-05T22:14:53.844921574Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:14:53.845371 containerd[1440]: time="2024-08-05T22:14:53.845047254Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:53.845371 containerd[1440]: time="2024-08-05T22:14:53.845067174Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:14:53.845371 containerd[1440]: time="2024-08-05T22:14:53.845080374Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:14:53.869209 systemd[1]: Started cri-containerd-0673a908194888c240e15900edc2013cb2daf7f542e7209b8f4eb3397662373b.scope - libcontainer container 0673a908194888c240e15900edc2013cb2daf7f542e7209b8f4eb3397662373b. Aug 5 22:14:53.882687 systemd-resolved[1314]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 22:14:53.898892 containerd[1440]: time="2024-08-05T22:14:53.898766734Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:8e296a2b-d00c-4034-ad02-dd5cc2599b9b,Namespace:default,Attempt:0,} returns sandbox id \"0673a908194888c240e15900edc2013cb2daf7f542e7209b8f4eb3397662373b\"" Aug 5 22:14:53.900608 containerd[1440]: time="2024-08-05T22:14:53.900550774Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Aug 5 22:14:54.278302 kubelet[1705]: E0805 22:14:54.278267 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:55.264199 kubelet[1705]: E0805 22:14:55.264097 1705 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:55.278672 kubelet[1705]: E0805 22:14:55.278475 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:55.570179 systemd-networkd[1361]: veth9a459ff5: Gained IPv6LL Aug 5 22:14:55.825217 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3626021496.mount: Deactivated successfully. Aug 5 22:14:56.279193 kubelet[1705]: E0805 22:14:56.279157 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:57.146953 containerd[1440]: time="2024-08-05T22:14:57.146903774Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:57.148140 containerd[1440]: time="2024-08-05T22:14:57.148107774Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Aug 5 22:14:57.150085 containerd[1440]: time="2024-08-05T22:14:57.149481254Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:57.151800 containerd[1440]: time="2024-08-05T22:14:57.151767134Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:14:57.153111 containerd[1440]: time="2024-08-05T22:14:57.153079014Z" 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.25249848s" Aug 5 22:14:57.153151 containerd[1440]: time="2024-08-05T22:14:57.153113454Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Aug 5 22:14:57.157264 containerd[1440]: time="2024-08-05T22:14:57.157215334Z" level=info msg="CreateContainer within sandbox \"0673a908194888c240e15900edc2013cb2daf7f542e7209b8f4eb3397662373b\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Aug 5 22:14:57.169107 containerd[1440]: time="2024-08-05T22:14:57.169054254Z" level=info msg="CreateContainer within sandbox \"0673a908194888c240e15900edc2013cb2daf7f542e7209b8f4eb3397662373b\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"18880d782f1966b1c3f35fcde4f42b53bc1515b6c12c54d70208e0f6cdafca83\"" Aug 5 22:14:57.169506 containerd[1440]: time="2024-08-05T22:14:57.169474534Z" level=info msg="StartContainer for \"18880d782f1966b1c3f35fcde4f42b53bc1515b6c12c54d70208e0f6cdafca83\"" Aug 5 22:14:57.193177 systemd[1]: Started cri-containerd-18880d782f1966b1c3f35fcde4f42b53bc1515b6c12c54d70208e0f6cdafca83.scope - libcontainer container 18880d782f1966b1c3f35fcde4f42b53bc1515b6c12c54d70208e0f6cdafca83. Aug 5 22:14:57.211873 containerd[1440]: time="2024-08-05T22:14:57.211834294Z" level=info msg="StartContainer for \"18880d782f1966b1c3f35fcde4f42b53bc1515b6c12c54d70208e0f6cdafca83\" returns successfully" Aug 5 22:14:57.281631 kubelet[1705]: E0805 22:14:57.281592 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:58.281913 kubelet[1705]: E0805 22:14:58.281856 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:14:59.282049 kubelet[1705]: E0805 22:14:59.281981 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:00.283042 kubelet[1705]: E0805 22:15:00.282980 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:01.283423 kubelet[1705]: E0805 22:15:01.283380 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:02.284180 kubelet[1705]: E0805 22:15:02.284126 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:03.284558 kubelet[1705]: E0805 22:15:03.284508 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:04.285047 kubelet[1705]: E0805 22:15:04.284989 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:05.285496 kubelet[1705]: E0805 22:15:05.285460 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:06.286525 kubelet[1705]: E0805 22:15:06.286493 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:06.962100 kubelet[1705]: I0805 22:15:06.962002 1705 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.7082511 podStartE2EDuration="13.96198302s" podCreationTimestamp="2024-08-05 22:14:53 +0000 UTC" firstStartedPulling="2024-08-05 22:14:53.900106094 +0000 UTC m=+19.216767481" lastFinishedPulling="2024-08-05 22:14:57.153838014 +0000 UTC m=+22.470499401" observedRunningTime="2024-08-05 22:14:57.489123814 +0000 UTC m=+22.805785201" watchObservedRunningTime="2024-08-05 22:15:06.96198302 +0000 UTC m=+32.278644407" Aug 5 22:15:06.962276 kubelet[1705]: I0805 22:15:06.962249 1705 topology_manager.go:215] "Topology Admit Handler" podUID="d7c1f458-72b5-459c-809a-0c220742e8c2" podNamespace="default" podName="test-pod-1" Aug 5 22:15:06.968429 systemd[1]: Created slice kubepods-besteffort-podd7c1f458_72b5_459c_809a_0c220742e8c2.slice - libcontainer container kubepods-besteffort-podd7c1f458_72b5_459c_809a_0c220742e8c2.slice. Aug 5 22:15:07.066308 kubelet[1705]: I0805 22:15:07.066255 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-f6f47d2a-eb6e-48da-92a8-1154f5e6e622\" (UniqueName: \"kubernetes.io/nfs/d7c1f458-72b5-459c-809a-0c220742e8c2-pvc-f6f47d2a-eb6e-48da-92a8-1154f5e6e622\") pod \"test-pod-1\" (UID: \"d7c1f458-72b5-459c-809a-0c220742e8c2\") " pod="default/test-pod-1" Aug 5 22:15:07.066308 kubelet[1705]: I0805 22:15:07.066296 1705 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-d4fbc\" (UniqueName: \"kubernetes.io/projected/d7c1f458-72b5-459c-809a-0c220742e8c2-kube-api-access-d4fbc\") pod \"test-pod-1\" (UID: \"d7c1f458-72b5-459c-809a-0c220742e8c2\") " pod="default/test-pod-1" Aug 5 22:15:07.196069 kernel: FS-Cache: Loaded Aug 5 22:15:07.220059 kernel: RPC: Registered named UNIX socket transport module. Aug 5 22:15:07.220158 kernel: RPC: Registered udp transport module. Aug 5 22:15:07.220176 kernel: RPC: Registered tcp transport module. Aug 5 22:15:07.221095 kernel: RPC: Registered tcp-with-tls transport module. Aug 5 22:15:07.221159 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 5 22:15:07.286855 kubelet[1705]: E0805 22:15:07.286782 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:07.401069 kernel: NFS: Registering the id_resolver key type Aug 5 22:15:07.401129 kernel: Key type id_resolver registered Aug 5 22:15:07.401147 kernel: Key type id_legacy registered Aug 5 22:15:07.425787 nfsidmap[2728]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 5 22:15:07.429440 nfsidmap[2731]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 5 22:15:07.571937 containerd[1440]: time="2024-08-05T22:15:07.571892163Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d7c1f458-72b5-459c-809a-0c220742e8c2,Namespace:default,Attempt:0,}" Aug 5 22:15:07.587988 systemd-networkd[1361]: vethb5f65e46: Link UP Aug 5 22:15:07.589142 kernel: cni0: port 3(vethb5f65e46) entered blocking state Aug 5 22:15:07.589180 kernel: cni0: port 3(vethb5f65e46) entered disabled state Aug 5 22:15:07.589197 kernel: vethb5f65e46: entered allmulticast mode Aug 5 22:15:07.590214 kernel: vethb5f65e46: entered promiscuous mode Aug 5 22:15:07.594849 kernel: cni0: port 3(vethb5f65e46) entered blocking state Aug 5 22:15:07.594892 kernel: cni0: port 3(vethb5f65e46) entered forwarding state Aug 5 22:15:07.594004 systemd-networkd[1361]: vethb5f65e46: Gained carrier Aug 5 22:15:07.597670 containerd[1440]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Aug 5 22:15:07.597670 containerd[1440]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:15:07.613126 containerd[1440]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-08-05T22:15:07.612703346Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:15:07.613126 containerd[1440]: time="2024-08-05T22:15:07.612758666Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:15:07.613126 containerd[1440]: time="2024-08-05T22:15:07.612782026Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:15:07.613126 containerd[1440]: time="2024-08-05T22:15:07.612796305Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:15:07.627245 systemd[1]: Started cri-containerd-0070c441dfd6afe63b98ca7216dd736a536af91aa295cd2108f60dc175f17b70.scope - libcontainer container 0070c441dfd6afe63b98ca7216dd736a536af91aa295cd2108f60dc175f17b70. Aug 5 22:15:07.637471 systemd-resolved[1314]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 22:15:07.654543 containerd[1440]: time="2024-08-05T22:15:07.654386923Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d7c1f458-72b5-459c-809a-0c220742e8c2,Namespace:default,Attempt:0,} returns sandbox id \"0070c441dfd6afe63b98ca7216dd736a536af91aa295cd2108f60dc175f17b70\"" Aug 5 22:15:07.656331 containerd[1440]: time="2024-08-05T22:15:07.656200670Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 5 22:15:07.906285 containerd[1440]: time="2024-08-05T22:15:07.906147853Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:15:07.907612 containerd[1440]: time="2024-08-05T22:15:07.907567923Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Aug 5 22:15:07.910500 containerd[1440]: time="2024-08-05T22:15:07.910441982Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"67636951\" in 254.204352ms" Aug 5 22:15:07.910500 containerd[1440]: time="2024-08-05T22:15:07.910486381Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f4699a95f1a5667d7cf577523b5823477fe56b7b62df686b6bd1d27f96d005b9\"" Aug 5 22:15:07.912809 containerd[1440]: time="2024-08-05T22:15:07.912660086Z" level=info msg="CreateContainer within sandbox \"0070c441dfd6afe63b98ca7216dd736a536af91aa295cd2108f60dc175f17b70\" for container &ContainerMetadata{Name:test,Attempt:0,}" Aug 5 22:15:07.924814 containerd[1440]: time="2024-08-05T22:15:07.924744678Z" level=info msg="CreateContainer within sandbox \"0070c441dfd6afe63b98ca7216dd736a536af91aa295cd2108f60dc175f17b70\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"d85ffcfb22bfc2f32e95a6bbffa07899126f428cf9cdd3175b4338ab9e821b6b\"" Aug 5 22:15:07.925387 containerd[1440]: time="2024-08-05T22:15:07.925344713Z" level=info msg="StartContainer for \"d85ffcfb22bfc2f32e95a6bbffa07899126f428cf9cdd3175b4338ab9e821b6b\"" Aug 5 22:15:07.948552 systemd[1]: Started cri-containerd-d85ffcfb22bfc2f32e95a6bbffa07899126f428cf9cdd3175b4338ab9e821b6b.scope - libcontainer container d85ffcfb22bfc2f32e95a6bbffa07899126f428cf9cdd3175b4338ab9e821b6b. Aug 5 22:15:07.968870 containerd[1440]: time="2024-08-05T22:15:07.968827757Z" level=info msg="StartContainer for \"d85ffcfb22bfc2f32e95a6bbffa07899126f428cf9cdd3175b4338ab9e821b6b\" returns successfully" Aug 5 22:15:08.287408 kubelet[1705]: E0805 22:15:08.287361 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:08.507533 kubelet[1705]: I0805 22:15:08.507451 1705 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.251971449 podStartE2EDuration="15.507435912s" podCreationTimestamp="2024-08-05 22:14:53 +0000 UTC" firstStartedPulling="2024-08-05 22:15:07.655757793 +0000 UTC m=+32.972419140" lastFinishedPulling="2024-08-05 22:15:07.911222216 +0000 UTC m=+33.227883603" observedRunningTime="2024-08-05 22:15:08.507216434 +0000 UTC m=+33.823877821" watchObservedRunningTime="2024-08-05 22:15:08.507435912 +0000 UTC m=+33.824097299" Aug 5 22:15:09.266198 systemd-networkd[1361]: vethb5f65e46: Gained IPv6LL Aug 5 22:15:09.288435 kubelet[1705]: E0805 22:15:09.288371 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:10.288602 kubelet[1705]: E0805 22:15:10.288526 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:15:11.289428 kubelet[1705]: E0805 22:15:11.289383 1705 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"