Sep 4 17:39:49.935524 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 4 17:39:49.935546 kernel: Linux version 6.6.48-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 Wed Sep 4 15:52:28 -00 2024 Sep 4 17:39:49.935556 kernel: KASLR enabled Sep 4 17:39:49.935562 kernel: efi: EFI v2.7 by EDK II Sep 4 17:39:49.935568 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb8fd018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Sep 4 17:39:49.935574 kernel: random: crng init done Sep 4 17:39:49.935581 kernel: ACPI: Early table checksum verification disabled Sep 4 17:39:49.935588 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Sep 4 17:39:49.935594 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 4 17:39:49.935602 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935608 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935615 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935621 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935627 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935635 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935643 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935649 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935656 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:39:49.935662 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 4 17:39:49.935669 kernel: NUMA: Failed to initialise from firmware Sep 4 17:39:49.935675 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 17:39:49.935682 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Sep 4 17:39:49.935688 kernel: Zone ranges: Sep 4 17:39:49.935695 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 17:39:49.935701 kernel: DMA32 empty Sep 4 17:39:49.935709 kernel: Normal empty Sep 4 17:39:49.935715 kernel: Movable zone start for each node Sep 4 17:39:49.935722 kernel: Early memory node ranges Sep 4 17:39:49.935728 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Sep 4 17:39:49.935735 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Sep 4 17:39:49.935741 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Sep 4 17:39:49.935748 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 4 17:39:49.935754 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 4 17:39:49.935761 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 4 17:39:49.935767 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 4 17:39:49.935774 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 17:39:49.935780 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 4 17:39:49.935788 kernel: psci: probing for conduit method from ACPI. Sep 4 17:39:49.935795 kernel: psci: PSCIv1.1 detected in firmware. Sep 4 17:39:49.935801 kernel: psci: Using standard PSCI v0.2 function IDs Sep 4 17:39:49.935823 kernel: psci: Trusted OS migration not required Sep 4 17:39:49.935830 kernel: psci: SMC Calling Convention v1.1 Sep 4 17:39:49.935837 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 4 17:39:49.935846 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 4 17:39:49.935853 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 4 17:39:49.935861 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 4 17:39:49.935868 kernel: Detected PIPT I-cache on CPU0 Sep 4 17:39:49.935874 kernel: CPU features: detected: GIC system register CPU interface Sep 4 17:39:49.935882 kernel: CPU features: detected: Hardware dirty bit management Sep 4 17:39:49.935888 kernel: CPU features: detected: Spectre-v4 Sep 4 17:39:49.935895 kernel: CPU features: detected: Spectre-BHB Sep 4 17:39:49.935902 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 4 17:39:49.935909 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 4 17:39:49.935918 kernel: CPU features: detected: ARM erratum 1418040 Sep 4 17:39:49.935925 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 4 17:39:49.935932 kernel: alternatives: applying boot alternatives Sep 4 17:39:49.935941 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=7913866621ae0af53522ae1b4ff4e1e453dd69d966d437a439147039341ecbbc Sep 4 17:39:49.935948 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 17:39:49.935955 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 17:39:49.935962 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 17:39:49.935969 kernel: Fallback order for Node 0: 0 Sep 4 17:39:49.935976 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Sep 4 17:39:49.935983 kernel: Policy zone: DMA Sep 4 17:39:49.935990 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 17:39:49.935998 kernel: software IO TLB: area num 4. Sep 4 17:39:49.936005 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Sep 4 17:39:49.936013 kernel: Memory: 2386852K/2572288K available (10240K kernel code, 2182K rwdata, 8076K rodata, 39040K init, 897K bss, 185436K reserved, 0K cma-reserved) Sep 4 17:39:49.936025 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 4 17:39:49.936033 kernel: trace event string verifier disabled Sep 4 17:39:49.936039 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 17:39:49.936047 kernel: rcu: RCU event tracing is enabled. Sep 4 17:39:49.936055 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 4 17:39:49.936062 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 17:39:49.936069 kernel: Tracing variant of Tasks RCU enabled. Sep 4 17:39:49.936076 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 17:39:49.936083 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 4 17:39:49.936092 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 4 17:39:49.936098 kernel: GICv3: 256 SPIs implemented Sep 4 17:39:49.936105 kernel: GICv3: 0 Extended SPIs implemented Sep 4 17:39:49.936112 kernel: Root IRQ handler: gic_handle_irq Sep 4 17:39:49.936119 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 4 17:39:49.936126 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 4 17:39:49.936133 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 4 17:39:49.936141 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Sep 4 17:39:49.936148 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Sep 4 17:39:49.936155 kernel: GICv3: using LPI property table @0x00000000400f0000 Sep 4 17:39:49.936162 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Sep 4 17:39:49.936171 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 17:39:49.936178 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:39:49.936185 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 4 17:39:49.936192 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 4 17:39:49.936199 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 4 17:39:49.936206 kernel: arm-pv: using stolen time PV Sep 4 17:39:49.936214 kernel: Console: colour dummy device 80x25 Sep 4 17:39:49.936221 kernel: ACPI: Core revision 20230628 Sep 4 17:39:49.936228 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 4 17:39:49.936235 kernel: pid_max: default: 32768 minimum: 301 Sep 4 17:39:49.936244 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Sep 4 17:39:49.936251 kernel: SELinux: Initializing. Sep 4 17:39:49.936258 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 17:39:49.936265 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 17:39:49.936272 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:39:49.936280 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:39:49.936287 kernel: rcu: Hierarchical SRCU implementation. Sep 4 17:39:49.936294 kernel: rcu: Max phase no-delay instances is 400. Sep 4 17:39:49.936301 kernel: Platform MSI: ITS@0x8080000 domain created Sep 4 17:39:49.936308 kernel: PCI/MSI: ITS@0x8080000 domain created Sep 4 17:39:49.936316 kernel: Remapping and enabling EFI services. Sep 4 17:39:49.936323 kernel: smp: Bringing up secondary CPUs ... Sep 4 17:39:49.936330 kernel: Detected PIPT I-cache on CPU1 Sep 4 17:39:49.936337 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 4 17:39:49.936344 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Sep 4 17:39:49.936352 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:39:49.936359 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 4 17:39:49.936366 kernel: Detected PIPT I-cache on CPU2 Sep 4 17:39:49.936374 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 4 17:39:49.936382 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Sep 4 17:39:49.936390 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:39:49.936402 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 4 17:39:49.936411 kernel: Detected PIPT I-cache on CPU3 Sep 4 17:39:49.936418 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 4 17:39:49.936426 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Sep 4 17:39:49.936433 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:39:49.936440 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 4 17:39:49.936448 kernel: smp: Brought up 1 node, 4 CPUs Sep 4 17:39:49.936457 kernel: SMP: Total of 4 processors activated. Sep 4 17:39:49.936464 kernel: CPU features: detected: 32-bit EL0 Support Sep 4 17:39:49.936472 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 4 17:39:49.936480 kernel: CPU features: detected: Common not Private translations Sep 4 17:39:49.936487 kernel: CPU features: detected: CRC32 instructions Sep 4 17:39:49.936495 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 4 17:39:49.936502 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 4 17:39:49.936510 kernel: CPU features: detected: LSE atomic instructions Sep 4 17:39:49.936519 kernel: CPU features: detected: Privileged Access Never Sep 4 17:39:49.936526 kernel: CPU features: detected: RAS Extension Support Sep 4 17:39:49.936534 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 4 17:39:49.936541 kernel: CPU: All CPU(s) started at EL1 Sep 4 17:39:49.936553 kernel: alternatives: applying system-wide alternatives Sep 4 17:39:49.936561 kernel: devtmpfs: initialized Sep 4 17:39:49.936568 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 17:39:49.936576 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 4 17:39:49.936584 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 17:39:49.936593 kernel: SMBIOS 3.0.0 present. Sep 4 17:39:49.936601 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Sep 4 17:39:49.936609 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 17:39:49.936617 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 4 17:39:49.936625 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 4 17:39:49.936633 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 4 17:39:49.936641 kernel: audit: initializing netlink subsys (disabled) Sep 4 17:39:49.936648 kernel: audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 Sep 4 17:39:49.936656 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 17:39:49.936664 kernel: cpuidle: using governor menu Sep 4 17:39:49.936672 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 4 17:39:49.936680 kernel: ASID allocator initialised with 32768 entries Sep 4 17:39:49.936687 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 17:39:49.936695 kernel: Serial: AMBA PL011 UART driver Sep 4 17:39:49.936702 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 4 17:39:49.936710 kernel: Modules: 0 pages in range for non-PLT usage Sep 4 17:39:49.936717 kernel: Modules: 509120 pages in range for PLT usage Sep 4 17:39:49.936725 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 17:39:49.936734 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 17:39:49.936742 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 4 17:39:49.936749 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 4 17:39:49.936757 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 17:39:49.936765 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 17:39:49.936772 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 4 17:39:49.936780 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 4 17:39:49.936788 kernel: ACPI: Added _OSI(Module Device) Sep 4 17:39:49.936795 kernel: ACPI: Added _OSI(Processor Device) Sep 4 17:39:49.936811 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 4 17:39:49.936819 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 17:39:49.936827 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 17:39:49.936834 kernel: ACPI: Interpreter enabled Sep 4 17:39:49.936841 kernel: ACPI: Using GIC for interrupt routing Sep 4 17:39:49.936849 kernel: ACPI: MCFG table detected, 1 entries Sep 4 17:39:49.936856 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 4 17:39:49.936864 kernel: printk: console [ttyAMA0] enabled Sep 4 17:39:49.936872 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 4 17:39:49.937012 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 4 17:39:49.937102 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 4 17:39:49.937171 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 4 17:39:49.937235 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 4 17:39:49.937298 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 4 17:39:49.937308 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 4 17:39:49.937316 kernel: PCI host bridge to bus 0000:00 Sep 4 17:39:49.937391 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 4 17:39:49.937452 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 4 17:39:49.937512 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 4 17:39:49.937572 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 4 17:39:49.937652 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Sep 4 17:39:49.937730 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Sep 4 17:39:49.937801 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Sep 4 17:39:49.937893 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Sep 4 17:39:49.937962 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Sep 4 17:39:49.938038 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Sep 4 17:39:49.938108 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Sep 4 17:39:49.938175 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Sep 4 17:39:49.938236 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 4 17:39:49.938294 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 4 17:39:49.938356 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 4 17:39:49.938367 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 4 17:39:49.938375 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 4 17:39:49.938383 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 4 17:39:49.938391 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 4 17:39:49.938398 kernel: iommu: Default domain type: Translated Sep 4 17:39:49.938406 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 4 17:39:49.938414 kernel: efivars: Registered efivars operations Sep 4 17:39:49.938424 kernel: vgaarb: loaded Sep 4 17:39:49.938432 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 4 17:39:49.938439 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 17:39:49.938448 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 17:39:49.938455 kernel: pnp: PnP ACPI init Sep 4 17:39:49.938544 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 4 17:39:49.938557 kernel: pnp: PnP ACPI: found 1 devices Sep 4 17:39:49.938564 kernel: NET: Registered PF_INET protocol family Sep 4 17:39:49.938574 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 17:39:49.938582 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 17:39:49.938590 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 17:39:49.938598 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 17:39:49.938606 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 17:39:49.938613 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 17:39:49.938622 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 17:39:49.938630 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 17:39:49.938638 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 17:39:49.938647 kernel: PCI: CLS 0 bytes, default 64 Sep 4 17:39:49.938654 kernel: kvm [1]: HYP mode not available Sep 4 17:39:49.938662 kernel: Initialise system trusted keyrings Sep 4 17:39:49.938670 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 17:39:49.938678 kernel: Key type asymmetric registered Sep 4 17:39:49.938686 kernel: Asymmetric key parser 'x509' registered Sep 4 17:39:49.938694 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 4 17:39:49.938702 kernel: io scheduler mq-deadline registered Sep 4 17:39:49.938710 kernel: io scheduler kyber registered Sep 4 17:39:49.938719 kernel: io scheduler bfq registered Sep 4 17:39:49.938728 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 4 17:39:49.938736 kernel: ACPI: button: Power Button [PWRB] Sep 4 17:39:49.938744 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 4 17:39:49.938825 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 4 17:39:49.938837 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 17:39:49.938845 kernel: thunder_xcv, ver 1.0 Sep 4 17:39:49.938852 kernel: thunder_bgx, ver 1.0 Sep 4 17:39:49.938860 kernel: nicpf, ver 1.0 Sep 4 17:39:49.938870 kernel: nicvf, ver 1.0 Sep 4 17:39:49.938945 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 4 17:39:49.939010 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-09-04T17:39:49 UTC (1725471589) Sep 4 17:39:49.939027 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 17:39:49.939036 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Sep 4 17:39:49.939043 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 4 17:39:49.939051 kernel: watchdog: Hard watchdog permanently disabled Sep 4 17:39:49.939059 kernel: NET: Registered PF_INET6 protocol family Sep 4 17:39:49.939069 kernel: Segment Routing with IPv6 Sep 4 17:39:49.939077 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 17:39:49.939084 kernel: NET: Registered PF_PACKET protocol family Sep 4 17:39:49.939092 kernel: Key type dns_resolver registered Sep 4 17:39:49.939099 kernel: registered taskstats version 1 Sep 4 17:39:49.939107 kernel: Loading compiled-in X.509 certificates Sep 4 17:39:49.939114 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.48-flatcar: 1f5b9f288f9cae6ec9698678cdc0f614482066f7' Sep 4 17:39:49.939122 kernel: Key type .fscrypt registered Sep 4 17:39:49.939130 kernel: Key type fscrypt-provisioning registered Sep 4 17:39:49.939139 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 17:39:49.939146 kernel: ima: Allocated hash algorithm: sha1 Sep 4 17:39:49.939154 kernel: ima: No architecture policies found Sep 4 17:39:49.939161 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 4 17:39:49.939169 kernel: clk: Disabling unused clocks Sep 4 17:39:49.939176 kernel: Freeing unused kernel memory: 39040K Sep 4 17:39:49.939184 kernel: Run /init as init process Sep 4 17:39:49.939191 kernel: with arguments: Sep 4 17:39:49.939198 kernel: /init Sep 4 17:39:49.939207 kernel: with environment: Sep 4 17:39:49.939215 kernel: HOME=/ Sep 4 17:39:49.939222 kernel: TERM=linux Sep 4 17:39:49.939230 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 17:39:49.939239 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) Sep 4 17:39:49.939249 systemd[1]: Detected virtualization kvm. Sep 4 17:39:49.939258 systemd[1]: Detected architecture arm64. Sep 4 17:39:49.939266 systemd[1]: Running in initrd. Sep 4 17:39:49.939275 systemd[1]: No hostname configured, using default hostname. Sep 4 17:39:49.939283 systemd[1]: Hostname set to . Sep 4 17:39:49.939291 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:39:49.939299 systemd[1]: Queued start job for default target initrd.target. Sep 4 17:39:49.939308 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:39:49.939316 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:39:49.939325 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 17:39:49.939333 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:39:49.939343 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 17:39:49.939351 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 17:39:49.939361 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 17:39:49.939369 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 17:39:49.939378 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:39:49.939386 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:39:49.939396 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:39:49.939404 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:39:49.939412 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:39:49.939420 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:39:49.939429 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:39:49.939437 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:39:49.939445 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 17:39:49.939454 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 4 17:39:49.939462 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:39:49.939472 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:39:49.939481 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:39:49.939489 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:39:49.939497 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 17:39:49.939506 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:39:49.939514 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 17:39:49.939522 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 17:39:49.939530 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:39:49.939538 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:39:49.939548 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:39:49.939561 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 17:39:49.939569 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:39:49.939578 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 17:39:49.939586 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 17:39:49.939596 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:39:49.939605 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:39:49.939614 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 17:39:49.939622 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 17:39:49.939631 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:39:49.939660 systemd-journald[237]: Collecting audit messages is disabled. Sep 4 17:39:49.939682 kernel: Bridge firewalling registered Sep 4 17:39:49.939691 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:39:49.939700 systemd-journald[237]: Journal started Sep 4 17:39:49.939721 systemd-journald[237]: Runtime Journal (/run/log/journal/3d66509ca58d4f6a906d3d9726269b10) is 5.9M, max 47.3M, 41.4M free. Sep 4 17:39:49.912205 systemd-modules-load[238]: Inserted module 'overlay' Sep 4 17:39:49.933529 systemd-modules-load[238]: Inserted module 'br_netfilter' Sep 4 17:39:49.944887 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:39:49.944911 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:39:49.946866 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:39:49.948356 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:39:49.951521 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:39:49.964009 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 17:39:49.965711 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Sep 4 17:39:49.975644 dracut-cmdline[270]: dracut-dracut-053 Sep 4 17:39:49.976674 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Sep 4 17:39:49.979102 dracut-cmdline[270]: 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=7913866621ae0af53522ae1b4ff4e1e453dd69d966d437a439147039341ecbbc Sep 4 17:39:49.992069 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:39:50.021486 systemd-resolved[289]: Positive Trust Anchors: Sep 4 17:39:50.021506 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:39:50.021539 systemd-resolved[289]: 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 Sep 4 17:39:50.026281 systemd-resolved[289]: Defaulting to hostname 'linux'. Sep 4 17:39:50.027326 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:39:50.031129 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:39:50.056846 kernel: SCSI subsystem initialized Sep 4 17:39:50.061824 kernel: Loading iSCSI transport class v2.0-870. Sep 4 17:39:50.068829 kernel: iscsi: registered transport (tcp) Sep 4 17:39:50.082891 kernel: iscsi: registered transport (qla4xxx) Sep 4 17:39:50.082926 kernel: QLogic iSCSI HBA Driver Sep 4 17:39:50.133650 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 17:39:50.144982 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 17:39:50.165840 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 17:39:50.165899 kernel: device-mapper: uevent: version 1.0.3 Sep 4 17:39:50.167847 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 17:39:50.213837 kernel: raid6: neonx8 gen() 15770 MB/s Sep 4 17:39:50.230827 kernel: raid6: neonx4 gen() 15647 MB/s Sep 4 17:39:50.247828 kernel: raid6: neonx2 gen() 13218 MB/s Sep 4 17:39:50.264830 kernel: raid6: neonx1 gen() 10511 MB/s Sep 4 17:39:50.281827 kernel: raid6: int64x8 gen() 6956 MB/s Sep 4 17:39:50.298828 kernel: raid6: int64x4 gen() 7349 MB/s Sep 4 17:39:50.315825 kernel: raid6: int64x2 gen() 6127 MB/s Sep 4 17:39:50.332915 kernel: raid6: int64x1 gen() 5053 MB/s Sep 4 17:39:50.332929 kernel: raid6: using algorithm neonx8 gen() 15770 MB/s Sep 4 17:39:50.350902 kernel: raid6: .... xor() 12006 MB/s, rmw enabled Sep 4 17:39:50.350917 kernel: raid6: using neon recovery algorithm Sep 4 17:39:50.357190 kernel: xor: measuring software checksum speed Sep 4 17:39:50.357213 kernel: 8regs : 19869 MB/sec Sep 4 17:39:50.358198 kernel: 32regs : 19711 MB/sec Sep 4 17:39:50.362826 kernel: arm64_neon : 27224 MB/sec Sep 4 17:39:50.362840 kernel: xor: using function: arm64_neon (27224 MB/sec) Sep 4 17:39:50.424837 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 17:39:50.441891 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:39:50.454997 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:39:50.469686 systemd-udevd[461]: Using default interface naming scheme 'v255'. Sep 4 17:39:50.472862 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:39:50.483991 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 17:39:50.496022 dracut-pre-trigger[469]: rd.md=0: removing MD RAID activation Sep 4 17:39:50.528158 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:39:50.546999 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:39:50.588903 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:39:50.594577 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 17:39:50.608137 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 17:39:50.610480 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:39:50.614138 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:39:50.615713 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:39:50.627045 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 17:39:50.635447 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:39:50.639946 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 4 17:39:50.640114 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 4 17:39:50.643151 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 4 17:39:50.643188 kernel: GPT:9289727 != 19775487 Sep 4 17:39:50.643199 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 4 17:39:50.645579 kernel: GPT:9289727 != 19775487 Sep 4 17:39:50.645525 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:39:50.649613 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 4 17:39:50.649634 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:39:50.645592 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:39:50.649493 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:39:50.652835 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:39:50.652898 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:39:50.655439 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:39:50.668183 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:39:50.675849 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (505) Sep 4 17:39:50.682868 kernel: BTRFS: device fsid 2be47701-3393-455e-86fc-33755ceb9c20 devid 1 transid 35 /dev/vda3 scanned by (udev-worker) (520) Sep 4 17:39:50.685246 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 4 17:39:50.689896 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 4 17:39:50.691462 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:39:50.700428 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 17:39:50.704555 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 4 17:39:50.705924 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 4 17:39:50.717963 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 17:39:50.719961 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:39:50.726991 disk-uuid[552]: Primary Header is updated. Sep 4 17:39:50.726991 disk-uuid[552]: Secondary Entries is updated. Sep 4 17:39:50.726991 disk-uuid[552]: Secondary Header is updated. Sep 4 17:39:50.730291 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:39:50.747764 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:39:51.746590 disk-uuid[554]: The operation has completed successfully. Sep 4 17:39:51.748335 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:39:51.766564 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 17:39:51.766665 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 17:39:51.788963 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 17:39:51.792076 sh[577]: Success Sep 4 17:39:51.807026 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 4 17:39:51.851378 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 17:39:51.853303 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 17:39:51.854230 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 17:39:51.865543 kernel: BTRFS info (device dm-0): first mount of filesystem 2be47701-3393-455e-86fc-33755ceb9c20 Sep 4 17:39:51.865586 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:39:51.865597 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 17:39:51.867358 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 17:39:51.867377 kernel: BTRFS info (device dm-0): using free space tree Sep 4 17:39:51.871153 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 17:39:51.872499 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 17:39:51.883949 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 17:39:51.885602 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 17:39:51.892915 kernel: BTRFS info (device vda6): first mount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:39:51.892965 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:39:51.892984 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:39:51.895824 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:39:51.903837 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 4 17:39:51.905823 kernel: BTRFS info (device vda6): last unmount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:39:51.911976 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 17:39:51.918994 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 17:39:51.989400 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:39:52.004042 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:39:52.028172 systemd-networkd[764]: lo: Link UP Sep 4 17:39:52.028184 systemd-networkd[764]: lo: Gained carrier Sep 4 17:39:52.029153 systemd-networkd[764]: Enumeration completed Sep 4 17:39:52.029251 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:39:52.029688 systemd-networkd[764]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:39:52.029692 systemd-networkd[764]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:39:52.030556 systemd[1]: Reached target network.target - Network. Sep 4 17:39:52.031024 systemd-networkd[764]: eth0: Link UP Sep 4 17:39:52.040777 ignition[670]: Ignition 2.18.0 Sep 4 17:39:52.031027 systemd-networkd[764]: eth0: Gained carrier Sep 4 17:39:52.040783 ignition[670]: Stage: fetch-offline Sep 4 17:39:52.031034 systemd-networkd[764]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:39:52.040827 ignition[670]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:39:52.040835 ignition[670]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:39:52.040922 ignition[670]: parsed url from cmdline: "" Sep 4 17:39:52.040926 ignition[670]: no config URL provided Sep 4 17:39:52.040930 ignition[670]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 17:39:52.040937 ignition[670]: no config at "/usr/lib/ignition/user.ign" Sep 4 17:39:52.040963 ignition[670]: op(1): [started] loading QEMU firmware config module Sep 4 17:39:52.040967 ignition[670]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 4 17:39:52.056463 systemd-networkd[764]: eth0: DHCPv4 address 10.0.0.156/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 17:39:52.057828 ignition[670]: op(1): [finished] loading QEMU firmware config module Sep 4 17:39:52.063831 ignition[670]: parsing config with SHA512: 49ca33960c35592977d5d55e0c7d04f063b94b0b9563e443e3c2b0f1a805427c2d365565fb1e5a10169b07df40d83e29d79f495d6423813b6e56edae3227994d Sep 4 17:39:52.068638 unknown[670]: fetched base config from "system" Sep 4 17:39:52.068648 unknown[670]: fetched user config from "qemu" Sep 4 17:39:52.068934 ignition[670]: fetch-offline: fetch-offline passed Sep 4 17:39:52.070762 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:39:52.068986 ignition[670]: Ignition finished successfully Sep 4 17:39:52.072389 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 4 17:39:52.082966 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 17:39:52.093665 ignition[776]: Ignition 2.18.0 Sep 4 17:39:52.093675 ignition[776]: Stage: kargs Sep 4 17:39:52.093842 ignition[776]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:39:52.093852 ignition[776]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:39:52.094514 ignition[776]: kargs: kargs passed Sep 4 17:39:52.094557 ignition[776]: Ignition finished successfully Sep 4 17:39:52.098884 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 17:39:52.112969 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 17:39:52.122849 ignition[785]: Ignition 2.18.0 Sep 4 17:39:52.122860 ignition[785]: Stage: disks Sep 4 17:39:52.123036 ignition[785]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:39:52.123047 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:39:52.125250 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 17:39:52.123680 ignition[785]: disks: disks passed Sep 4 17:39:52.123724 ignition[785]: Ignition finished successfully Sep 4 17:39:52.128313 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 17:39:52.129622 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 17:39:52.131349 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:39:52.133141 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:39:52.135099 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:39:52.146995 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 17:39:52.159227 systemd-fsck[796]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 4 17:39:52.163626 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 17:39:52.170950 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 17:39:52.218817 kernel: EXT4-fs (vda9): mounted filesystem f2f4f3ba-c5a3-49c0-ace4-444935e9934b r/w with ordered data mode. Quota mode: none. Sep 4 17:39:52.219676 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 17:39:52.221112 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 17:39:52.235903 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:39:52.237820 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 17:39:52.239223 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 4 17:39:52.239281 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 17:39:52.246394 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (804) Sep 4 17:39:52.246421 kernel: BTRFS info (device vda6): first mount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:39:52.239309 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:39:52.250673 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:39:52.250736 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:39:52.247798 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 17:39:52.253843 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:39:52.258964 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 17:39:52.260967 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:39:52.305948 initrd-setup-root[828]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 17:39:52.310521 initrd-setup-root[835]: cut: /sysroot/etc/group: No such file or directory Sep 4 17:39:52.315198 initrd-setup-root[842]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 17:39:52.319350 initrd-setup-root[849]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 17:39:52.396672 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 17:39:52.407896 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 17:39:52.410358 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 17:39:52.415821 kernel: BTRFS info (device vda6): last unmount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:39:52.436048 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 17:39:52.438018 ignition[918]: INFO : Ignition 2.18.0 Sep 4 17:39:52.438018 ignition[918]: INFO : Stage: mount Sep 4 17:39:52.438018 ignition[918]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:39:52.438018 ignition[918]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:39:52.438018 ignition[918]: INFO : mount: mount passed Sep 4 17:39:52.438018 ignition[918]: INFO : Ignition finished successfully Sep 4 17:39:52.438895 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 17:39:52.450949 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 17:39:52.864362 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 17:39:52.872965 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:39:52.879261 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (931) Sep 4 17:39:52.879292 kernel: BTRFS info (device vda6): first mount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:39:52.879303 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:39:52.880828 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:39:52.882823 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:39:52.883993 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:39:52.900370 ignition[948]: INFO : Ignition 2.18.0 Sep 4 17:39:52.900370 ignition[948]: INFO : Stage: files Sep 4 17:39:52.902096 ignition[948]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:39:52.902096 ignition[948]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:39:52.902096 ignition[948]: DEBUG : files: compiled without relabeling support, skipping Sep 4 17:39:52.902096 ignition[948]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 17:39:52.902096 ignition[948]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 17:39:52.908458 ignition[948]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 17:39:52.908458 ignition[948]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 17:39:52.908458 ignition[948]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Sep 4 17:39:52.908458 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.28.7-arm64.raw: attempt #1 Sep 4 17:39:52.904356 unknown[948]: wrote ssh authorized keys file for user: core Sep 4 17:39:53.179162 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 4 17:39:53.456784 ignition[948]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Sep 4 17:39:53.456784 ignition[948]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 4 17:39:53.460444 ignition[948]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 17:39:53.460444 ignition[948]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 17:39:53.460444 ignition[948]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 4 17:39:53.460444 ignition[948]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 4 17:39:53.486978 ignition[948]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 17:39:53.491032 ignition[948]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 17:39:53.493818 ignition[948]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 4 17:39:53.493818 ignition[948]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:39:53.493818 ignition[948]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:39:53.493818 ignition[948]: INFO : files: files passed Sep 4 17:39:53.493818 ignition[948]: INFO : Ignition finished successfully Sep 4 17:39:53.494225 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 17:39:53.506977 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 17:39:53.507469 systemd-networkd[764]: eth0: Gained IPv6LL Sep 4 17:39:53.512929 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 17:39:53.515171 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 17:39:53.516287 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 17:39:53.520790 initrd-setup-root-after-ignition[977]: grep: /sysroot/oem/oem-release: No such file or directory Sep 4 17:39:53.524350 initrd-setup-root-after-ignition[979]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:39:53.524350 initrd-setup-root-after-ignition[979]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:39:53.527517 initrd-setup-root-after-ignition[983]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:39:53.527871 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:39:53.531347 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 17:39:53.547053 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 17:39:53.570478 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 17:39:53.570611 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 17:39:53.572951 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 17:39:53.574735 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 17:39:53.576610 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 17:39:53.577983 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 17:39:53.593337 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:39:53.595836 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 17:39:53.608031 systemd[1]: Stopped target network.target - Network. Sep 4 17:39:53.609713 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:39:53.610905 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:39:53.613026 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 17:39:53.614763 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 17:39:53.614916 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:39:53.617394 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 17:39:53.619308 systemd[1]: Stopped target basic.target - Basic System. Sep 4 17:39:53.620887 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 17:39:53.622559 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:39:53.624417 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 17:39:53.626405 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 17:39:53.628225 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:39:53.630133 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 17:39:53.632105 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 17:39:53.633771 systemd[1]: Stopped target swap.target - Swaps. Sep 4 17:39:53.635359 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 17:39:53.635495 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:39:53.637741 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:39:53.639641 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:39:53.641568 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 17:39:53.641648 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:39:53.643672 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 17:39:53.643795 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 17:39:53.646557 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 17:39:53.646686 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:39:53.648647 systemd[1]: Stopped target paths.target - Path Units. Sep 4 17:39:53.650198 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 17:39:53.655832 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:39:53.657120 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 17:39:53.659270 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 17:39:53.660891 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 17:39:53.660987 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:39:53.662528 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 17:39:53.662611 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:39:53.664163 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 17:39:53.664278 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:39:53.666071 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 17:39:53.666176 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 17:39:53.677014 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 17:39:53.677924 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 17:39:53.678075 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:39:53.683030 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 17:39:53.684071 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 17:39:53.687950 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 17:39:53.689954 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 17:39:53.690101 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:39:53.693533 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 17:39:53.693647 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:39:53.697879 systemd-networkd[764]: eth0: DHCPv6 lease lost Sep 4 17:39:53.700842 ignition[1003]: INFO : Ignition 2.18.0 Sep 4 17:39:53.700842 ignition[1003]: INFO : Stage: umount Sep 4 17:39:53.700842 ignition[1003]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:39:53.700842 ignition[1003]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:39:53.700842 ignition[1003]: INFO : umount: umount passed Sep 4 17:39:53.700842 ignition[1003]: INFO : Ignition finished successfully Sep 4 17:39:53.698458 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 17:39:53.699946 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 17:39:53.700086 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 17:39:53.705001 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 17:39:53.705137 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 17:39:53.708305 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 17:39:53.708538 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 17:39:53.710382 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 17:39:53.710470 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 17:39:53.714268 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 17:39:53.714335 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:39:53.716510 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 17:39:53.716568 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 17:39:53.718394 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 17:39:53.718442 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 17:39:53.720160 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 17:39:53.720204 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 17:39:53.721777 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 17:39:53.721852 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 17:39:53.731909 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 17:39:53.733253 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 17:39:53.733316 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:39:53.735260 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 17:39:53.735422 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:39:53.737145 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 17:39:53.737190 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 17:39:53.738965 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 17:39:53.739023 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Sep 4 17:39:53.740959 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:39:53.751076 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 17:39:53.751203 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 17:39:53.762636 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 17:39:53.762790 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 17:39:53.764905 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 17:39:53.765054 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:39:53.767591 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 17:39:53.767667 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 17:39:53.768846 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 17:39:53.768881 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:39:53.770840 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 17:39:53.770890 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:39:53.773735 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 17:39:53.773780 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 17:39:53.776603 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:39:53.776655 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:39:53.779604 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 17:39:53.779651 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 17:39:53.793994 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 17:39:53.795140 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 17:39:53.795210 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:39:53.797513 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:39:53.797563 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:39:53.802045 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 17:39:53.802145 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 17:39:53.804683 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 17:39:53.807470 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 17:39:53.818292 systemd[1]: Switching root. Sep 4 17:39:53.846917 systemd-journald[237]: Journal stopped Sep 4 17:39:54.537159 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Sep 4 17:39:54.537223 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 17:39:54.537238 kernel: SELinux: policy capability open_perms=1 Sep 4 17:39:54.537248 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 17:39:54.537262 kernel: SELinux: policy capability always_check_network=0 Sep 4 17:39:54.537273 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 17:39:54.537282 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 17:39:54.537292 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 17:39:54.537302 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 17:39:54.537329 kernel: audit: type=1403 audit(1725471593.979:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 17:39:54.537341 systemd[1]: Successfully loaded SELinux policy in 35.163ms. Sep 4 17:39:54.537355 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.294ms. Sep 4 17:39:54.537367 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) Sep 4 17:39:54.537380 systemd[1]: Detected virtualization kvm. Sep 4 17:39:54.537390 systemd[1]: Detected architecture arm64. Sep 4 17:39:54.537400 systemd[1]: Detected first boot. Sep 4 17:39:54.537411 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:39:54.537421 zram_generator::config[1047]: No configuration found. Sep 4 17:39:54.537435 systemd[1]: Populated /etc with preset unit settings. Sep 4 17:39:54.537445 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 17:39:54.537455 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 17:39:54.537467 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 17:39:54.537480 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 17:39:54.537492 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 17:39:54.537502 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 17:39:54.537513 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 17:39:54.537524 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 17:39:54.537534 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 17:39:54.537545 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 17:39:54.537559 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 17:39:54.537569 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:39:54.537581 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:39:54.537591 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 17:39:54.537602 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 17:39:54.537614 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 17:39:54.537624 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:39:54.537635 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 4 17:39:54.537645 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:39:54.537655 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 17:39:54.537665 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 17:39:54.537677 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 17:39:54.537688 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 17:39:54.537698 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:39:54.537708 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:39:54.537719 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:39:54.537729 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:39:54.537739 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 17:39:54.537749 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 17:39:54.537761 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:39:54.537771 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:39:54.537781 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:39:54.537792 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 17:39:54.537864 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 17:39:54.537879 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 17:39:54.537889 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 17:39:54.537900 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 17:39:54.537910 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 17:39:54.537923 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 17:39:54.537938 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). Sep 4 17:39:54.537950 systemd[1]: Reached target machines.target - Containers. Sep 4 17:39:54.537960 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 17:39:54.537971 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:39:54.537995 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:39:54.538010 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 17:39:54.538021 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:39:54.538033 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:39:54.538045 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:39:54.538055 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 17:39:54.538066 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:39:54.538076 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 17:39:54.538087 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 17:39:54.538097 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 17:39:54.538107 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 17:39:54.538117 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 17:39:54.538130 kernel: fuse: init (API version 7.39) Sep 4 17:39:54.538140 kernel: loop: module loaded Sep 4 17:39:54.538150 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:39:54.538160 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:39:54.538170 kernel: ACPI: bus type drm_connector registered Sep 4 17:39:54.538180 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 17:39:54.538191 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 17:39:54.538201 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:39:54.538211 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 17:39:54.538245 systemd-journald[1113]: Collecting audit messages is disabled. Sep 4 17:39:54.538268 systemd[1]: Stopped verity-setup.service. Sep 4 17:39:54.538279 systemd-journald[1113]: Journal started Sep 4 17:39:54.538302 systemd-journald[1113]: Runtime Journal (/run/log/journal/3d66509ca58d4f6a906d3d9726269b10) is 5.9M, max 47.3M, 41.4M free. Sep 4 17:39:54.332072 systemd[1]: Queued start job for default target multi-user.target. Sep 4 17:39:54.351316 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 4 17:39:54.351689 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 17:39:54.540828 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:39:54.541518 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 17:39:54.542940 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 17:39:54.544190 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 17:39:54.545279 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 17:39:54.546532 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 17:39:54.547765 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 17:39:54.550840 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 17:39:54.552251 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:39:54.553889 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 17:39:54.554863 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 17:39:54.556378 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:39:54.556525 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:39:54.558009 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:39:54.558144 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:39:54.559495 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:39:54.559662 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:39:54.561272 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 17:39:54.561415 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 17:39:54.562754 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:39:54.564008 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:39:54.565349 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:39:54.566706 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 17:39:54.568247 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 17:39:54.583470 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 17:39:54.600925 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 17:39:54.603094 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 17:39:54.604240 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 17:39:54.604281 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:39:54.606284 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 4 17:39:54.608632 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 17:39:54.610935 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 17:39:54.612101 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:39:54.613662 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 17:39:54.617992 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 17:39:54.619268 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:39:54.620692 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 17:39:54.621988 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:39:54.627395 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:39:54.628078 systemd-journald[1113]: Time spent on flushing to /var/log/journal/3d66509ca58d4f6a906d3d9726269b10 is 25.903ms for 836 entries. Sep 4 17:39:54.628078 systemd-journald[1113]: System Journal (/var/log/journal/3d66509ca58d4f6a906d3d9726269b10) is 8.0M, max 195.6M, 187.6M free. Sep 4 17:39:54.662061 systemd-journald[1113]: Received client request to flush runtime journal. Sep 4 17:39:54.631137 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 17:39:54.638015 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 17:39:54.641102 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:39:54.642645 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 17:39:54.645680 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 17:39:54.656194 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 17:39:54.657849 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 17:39:54.664644 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 17:39:54.664826 kernel: loop0: detected capacity change from 0 to 193208 Sep 4 17:39:54.664867 kernel: block loop0: the capability attribute has been deprecated. Sep 4 17:39:54.669189 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 17:39:54.681587 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 4 17:39:54.687725 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 17:39:54.687941 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 17:39:54.692495 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:39:54.694661 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 17:39:54.699874 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 17:39:54.701278 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 4 17:39:54.714294 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:39:54.716979 udevadm[1172]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 4 17:39:54.719874 kernel: loop1: detected capacity change from 0 to 59688 Sep 4 17:39:54.739021 systemd-tmpfiles[1176]: ACLs are not supported, ignoring. Sep 4 17:39:54.739035 systemd-tmpfiles[1176]: ACLs are not supported, ignoring. Sep 4 17:39:54.746174 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:39:54.756841 kernel: loop2: detected capacity change from 0 to 113672 Sep 4 17:39:54.787859 kernel: loop3: detected capacity change from 0 to 193208 Sep 4 17:39:54.793841 kernel: loop4: detected capacity change from 0 to 59688 Sep 4 17:39:54.801344 kernel: loop5: detected capacity change from 0 to 113672 Sep 4 17:39:54.802203 (sd-merge)[1181]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 4 17:39:54.802611 (sd-merge)[1181]: Merged extensions into '/usr'. Sep 4 17:39:54.806217 systemd[1]: Reloading requested from client PID 1157 ('systemd-sysext') (unit systemd-sysext.service)... Sep 4 17:39:54.806237 systemd[1]: Reloading... Sep 4 17:39:54.862843 zram_generator::config[1205]: No configuration found. Sep 4 17:39:54.896703 ldconfig[1152]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 17:39:54.959488 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. Sep 4 17:39:54.996799 systemd[1]: Reloading finished in 190 ms. Sep 4 17:39:55.025104 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 17:39:55.026720 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 17:39:55.044331 systemd[1]: Starting ensure-sysext.service... Sep 4 17:39:55.046586 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Sep 4 17:39:55.051646 systemd[1]: Reloading requested from client PID 1239 ('systemctl') (unit ensure-sysext.service)... Sep 4 17:39:55.051662 systemd[1]: Reloading... Sep 4 17:39:55.064554 systemd-tmpfiles[1241]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 17:39:55.064827 systemd-tmpfiles[1241]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 17:39:55.065479 systemd-tmpfiles[1241]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 17:39:55.065691 systemd-tmpfiles[1241]: ACLs are not supported, ignoring. Sep 4 17:39:55.065739 systemd-tmpfiles[1241]: ACLs are not supported, ignoring. Sep 4 17:39:55.068422 systemd-tmpfiles[1241]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:39:55.068435 systemd-tmpfiles[1241]: Skipping /boot Sep 4 17:39:55.075167 systemd-tmpfiles[1241]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:39:55.075176 systemd-tmpfiles[1241]: Skipping /boot Sep 4 17:39:55.100162 zram_generator::config[1267]: No configuration found. Sep 4 17:39:55.182419 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. Sep 4 17:39:55.220414 systemd[1]: Reloading finished in 168 ms. Sep 4 17:39:55.238885 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 17:39:55.247347 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Sep 4 17:39:55.255835 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 17:39:55.258498 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 17:39:55.262872 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 17:39:55.266644 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:39:55.273102 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:39:55.277185 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 17:39:55.280747 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:39:55.282216 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:39:55.287097 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:39:55.290238 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:39:55.291415 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:39:55.302113 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 17:39:55.304309 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 17:39:55.308341 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:39:55.308541 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:39:55.310404 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:39:55.310533 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:39:55.312437 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:39:55.312568 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:39:55.314024 systemd-udevd[1308]: Using default interface naming scheme 'v255'. Sep 4 17:39:55.321557 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:39:55.329230 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:39:55.332134 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:39:55.337073 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:39:55.338212 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:39:55.339699 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 17:39:55.341862 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:39:55.345169 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 17:39:55.347117 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:39:55.347267 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:39:55.358902 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 17:39:55.362947 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 17:39:55.366296 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 17:39:55.373646 systemd[1]: Finished ensure-sysext.service. Sep 4 17:39:55.384832 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1338) Sep 4 17:39:55.386360 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 4 17:39:55.386777 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:39:55.392827 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 35 scanned by (udev-worker) (1331) Sep 4 17:39:55.393794 augenrules[1364]: No rules Sep 4 17:39:55.394111 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:39:55.398019 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:39:55.399134 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:39:55.404993 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:39:55.409125 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 4 17:39:55.410275 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). Sep 4 17:39:55.410773 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 17:39:55.412506 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:39:55.412649 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:39:55.415219 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:39:55.415354 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:39:55.416728 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:39:55.416864 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:39:55.421498 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:39:55.421646 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:39:55.440307 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 17:39:55.444341 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 17:39:55.445527 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:39:55.445604 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:39:55.476849 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 17:39:55.480796 systemd-resolved[1307]: Positive Trust Anchors: Sep 4 17:39:55.481129 systemd-resolved[1307]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:39:55.481211 systemd-resolved[1307]: 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 Sep 4 17:39:55.487663 systemd-resolved[1307]: Defaulting to hostname 'linux'. Sep 4 17:39:55.489726 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:39:55.491526 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 4 17:39:55.493265 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:39:55.494523 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 17:39:55.512260 systemd-networkd[1375]: lo: Link UP Sep 4 17:39:55.512273 systemd-networkd[1375]: lo: Gained carrier Sep 4 17:39:55.513010 systemd-networkd[1375]: Enumeration completed Sep 4 17:39:55.518063 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:39:55.518378 systemd-networkd[1375]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:39:55.518386 systemd-networkd[1375]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:39:55.519090 systemd-networkd[1375]: eth0: Link UP Sep 4 17:39:55.519098 systemd-networkd[1375]: eth0: Gained carrier Sep 4 17:39:55.519110 systemd-networkd[1375]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:39:55.519481 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:39:55.521098 systemd[1]: Reached target network.target - Network. Sep 4 17:39:55.524012 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 17:39:55.529234 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 17:39:55.531963 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 17:39:55.532903 systemd-networkd[1375]: eth0: DHCPv4 address 10.0.0.156/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 17:39:55.534071 systemd-timesyncd[1376]: Network configuration changed, trying to establish connection. Sep 4 17:39:55.537195 systemd-timesyncd[1376]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 4 17:39:55.537275 systemd-timesyncd[1376]: Initial clock synchronization to Wed 2024-09-04 17:39:55.296526 UTC. Sep 4 17:39:55.552821 lvm[1396]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:39:55.560911 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:39:55.583905 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 17:39:55.585452 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:39:55.586646 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:39:55.587821 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 17:39:55.589097 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 17:39:55.590458 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 17:39:55.591614 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 17:39:55.592834 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 17:39:55.594049 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 17:39:55.594082 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:39:55.594950 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:39:55.596750 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 17:39:55.599131 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 17:39:55.612868 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 17:39:55.615380 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 17:39:55.616983 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 17:39:55.618232 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:39:55.619265 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:39:55.620177 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:39:55.620208 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:39:55.621088 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 17:39:55.623039 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 17:39:55.625960 lvm[1403]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:39:55.626961 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 17:39:55.630037 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 17:39:55.633898 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). Sep 4 17:39:55.635078 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 17:39:55.637020 jq[1406]: false Sep 4 17:39:55.637109 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 17:39:55.642992 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 17:39:55.647081 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 17:39:55.655849 dbus-daemon[1405]: [system] SELinux support is enabled Sep 4 17:39:55.656247 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 17:39:55.656753 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 17:39:55.663385 extend-filesystems[1407]: Found loop3 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found loop4 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found loop5 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda1 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda2 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda3 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found usr Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda4 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda6 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda7 Sep 4 17:39:55.667675 extend-filesystems[1407]: Found vda9 Sep 4 17:39:55.667675 extend-filesystems[1407]: Checking size of /dev/vda9 Sep 4 17:39:55.666009 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 17:39:55.703734 extend-filesystems[1407]: Resized partition /dev/vda9 Sep 4 17:39:55.671018 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 17:39:55.706640 extend-filesystems[1428]: resize2fs 1.47.0 (5-Feb-2023) Sep 4 17:39:55.712757 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 4 17:39:55.712783 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 35 scanned by (udev-worker) (1334) Sep 4 17:39:55.673465 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 17:39:55.713023 jq[1424]: true Sep 4 17:39:55.676545 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 17:39:55.681224 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 17:39:55.681427 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 17:39:55.681688 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 17:39:55.681948 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 17:39:55.683433 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 17:39:55.683575 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 17:39:55.706566 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). Sep 4 17:39:55.706595 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 17:39:55.713616 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). Sep 4 17:39:55.713652 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 17:39:55.723193 systemd-logind[1412]: Watching system buttons on /dev/input/event0 (Power Button) Sep 4 17:39:55.723393 systemd-logind[1412]: New seat seat0. Sep 4 17:39:55.724087 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 17:39:55.738540 (ntainerd)[1436]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 17:39:55.742581 jq[1435]: true Sep 4 17:39:55.758227 update_engine[1420]: I0904 17:39:55.757067 1420 main.cc:92] Flatcar Update Engine starting Sep 4 17:39:55.760156 systemd[1]: Started update-engine.service - Update Engine. Sep 4 17:39:55.760608 update_engine[1420]: I0904 17:39:55.760201 1420 update_check_scheduler.cc:74] Next update check in 2m32s Sep 4 17:39:55.768848 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 4 17:39:55.771152 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 17:39:55.795563 extend-filesystems[1428]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 4 17:39:55.795563 extend-filesystems[1428]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 4 17:39:55.795563 extend-filesystems[1428]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 4 17:39:55.803561 extend-filesystems[1407]: Resized filesystem in /dev/vda9 Sep 4 17:39:55.798371 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 17:39:55.798557 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 17:39:55.805792 bash[1455]: Updated "/home/core/.ssh/authorized_keys" Sep 4 17:39:55.807053 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 17:39:55.809041 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 17:39:55.841737 locksmithd[1451]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 17:39:55.944888 containerd[1436]: time="2024-09-04T17:39:55.944764600Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Sep 4 17:39:55.970870 containerd[1436]: time="2024-09-04T17:39:55.970740280Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 17:39:55.970870 containerd[1436]: time="2024-09-04T17:39:55.970791440Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972185 containerd[1436]: time="2024-09-04T17:39:55.972144800Z" 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.48-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972185 containerd[1436]: time="2024-09-04T17:39:55.972179360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972446 containerd[1436]: time="2024-09-04T17:39:55.972413680Z" 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 Sep 4 17:39:55.972446 containerd[1436]: time="2024-09-04T17:39:55.972437240Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 17:39:55.972531 containerd[1436]: time="2024-09-04T17:39:55.972511880Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972581 containerd[1436]: time="2024-09-04T17:39:55.972567400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972609 containerd[1436]: time="2024-09-04T17:39:55.972582360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972665 containerd[1436]: time="2024-09-04T17:39:55.972651280Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972886 containerd[1436]: time="2024-09-04T17:39:55.972866240Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 17:39:55.972909 containerd[1436]: time="2024-09-04T17:39:55.972890640Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Sep 4 17:39:55.972909 containerd[1436]: time="2024-09-04T17:39:55.972901560Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:39:55.973030 containerd[1436]: time="2024-09-04T17:39:55.973009280Z" 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 Sep 4 17:39:55.973058 containerd[1436]: time="2024-09-04T17:39:55.973031280Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 17:39:55.973104 containerd[1436]: time="2024-09-04T17:39:55.973088120Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Sep 4 17:39:55.973131 containerd[1436]: time="2024-09-04T17:39:55.973105200Z" level=info msg="metadata content store policy set" policy=shared Sep 4 17:39:56.011877 containerd[1436]: time="2024-09-04T17:39:56.011830428Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 17:39:56.011877 containerd[1436]: time="2024-09-04T17:39:56.011871476Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 17:39:56.011877 containerd[1436]: time="2024-09-04T17:39:56.011883969Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 17:39:56.011996 containerd[1436]: time="2024-09-04T17:39:56.011915434Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 17:39:56.011996 containerd[1436]: time="2024-09-04T17:39:56.011930022Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 17:39:56.011996 containerd[1436]: time="2024-09-04T17:39:56.011940614Z" level=info msg="NRI interface is disabled by configuration." Sep 4 17:39:56.011996 containerd[1436]: time="2024-09-04T17:39:56.011952059Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 17:39:56.012113 containerd[1436]: time="2024-09-04T17:39:56.012094252Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 17:39:56.012139 containerd[1436]: time="2024-09-04T17:39:56.012118501Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 17:39:56.012139 containerd[1436]: time="2024-09-04T17:39:56.012134408Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 17:39:56.012183 containerd[1436]: time="2024-09-04T17:39:56.012147405Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 17:39:56.012183 containerd[1436]: time="2024-09-04T17:39:56.012161062Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012183 containerd[1436]: time="2024-09-04T17:39:56.012177434Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012238 containerd[1436]: time="2024-09-04T17:39:56.012189384Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012238 containerd[1436]: time="2024-09-04T17:39:56.012201877Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012238 containerd[1436]: time="2024-09-04T17:39:56.012216116Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012238 containerd[1436]: time="2024-09-04T17:39:56.012235553Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012308 containerd[1436]: time="2024-09-04T17:39:56.012248240Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012308 containerd[1436]: time="2024-09-04T17:39:56.012259802Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 17:39:56.012408 containerd[1436]: time="2024-09-04T17:39:56.012346553Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 17:39:56.012640 containerd[1436]: time="2024-09-04T17:39:56.012599941Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 17:39:56.012953 containerd[1436]: time="2024-09-04T17:39:56.012721960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.012953 containerd[1436]: time="2024-09-04T17:39:56.012745898Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 17:39:56.012953 containerd[1436]: time="2024-09-04T17:39:56.012772474Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 17:39:56.013076 containerd[1436]: time="2024-09-04T17:39:56.013053098Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.013232 containerd[1436]: time="2024-09-04T17:39:56.013216475Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.013294 containerd[1436]: time="2024-09-04T17:39:56.013281577Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.013356 containerd[1436]: time="2024-09-04T17:39:56.013342722Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.013409 containerd[1436]: time="2024-09-04T17:39:56.013396573Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013446972Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013466564Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013478514Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013492210Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013655625Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013674947Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013687944Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013700786Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013715141Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013730893Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013743968Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014691 containerd[1436]: time="2024-09-04T17:39:56.013757353Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 17:39:56.014983 containerd[1436]: time="2024-09-04T17:39:56.014022496Z" 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}" Sep 4 17:39:56.014983 containerd[1436]: time="2024-09-04T17:39:56.014081042Z" level=info msg="Connect containerd service" Sep 4 17:39:56.014983 containerd[1436]: time="2024-09-04T17:39:56.014108705Z" level=info msg="using legacy CRI server" Sep 4 17:39:56.014983 containerd[1436]: time="2024-09-04T17:39:56.014116542Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 17:39:56.014983 containerd[1436]: time="2024-09-04T17:39:56.014346534Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 17:39:56.015572 containerd[1436]: time="2024-09-04T17:39:56.015490949Z" 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" Sep 4 17:39:56.015680 containerd[1436]: time="2024-09-04T17:39:56.015661348Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 17:39:56.015964 containerd[1436]: time="2024-09-04T17:39:56.015945580Z" 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 Sep 4 17:39:56.016043 containerd[1436]: time="2024-09-04T17:39:56.016030043Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 17:39:56.016133 containerd[1436]: time="2024-09-04T17:39:56.016117066Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 4 17:39:56.016343 containerd[1436]: time="2024-09-04T17:39:56.015911904Z" level=info msg="Start subscribing containerd event" Sep 4 17:39:56.016890 containerd[1436]: time="2024-09-04T17:39:56.016870866Z" level=info msg="Start recovering state" Sep 4 17:39:56.017008 containerd[1436]: time="2024-09-04T17:39:56.016993893Z" level=info msg="Start event monitor" Sep 4 17:39:56.017060 containerd[1436]: time="2024-09-04T17:39:56.017047860Z" level=info msg="Start snapshots syncer" Sep 4 17:39:56.017106 containerd[1436]: time="2024-09-04T17:39:56.017095155Z" level=info msg="Start cni network conf syncer for default" Sep 4 17:39:56.017151 containerd[1436]: time="2024-09-04T17:39:56.017139966Z" level=info msg="Start streaming server" Sep 4 17:39:56.017354 containerd[1436]: time="2024-09-04T17:39:56.016836685Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 17:39:56.017450 containerd[1436]: time="2024-09-04T17:39:56.017435566Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 17:39:56.017641 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 17:39:56.018879 containerd[1436]: time="2024-09-04T17:39:56.018857578Z" level=info msg="containerd successfully booted in 0.075098s" Sep 4 17:39:56.096586 sshd_keygen[1421]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 17:39:56.114688 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 17:39:56.124034 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 17:39:56.129378 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 17:39:56.129554 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 17:39:56.133099 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 17:39:56.144496 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 17:39:56.156190 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 17:39:56.158489 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 4 17:39:56.159859 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 17:39:56.770964 systemd-networkd[1375]: eth0: Gained IPv6LL Sep 4 17:39:56.773631 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 17:39:56.775708 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 17:39:56.789073 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 17:39:56.791757 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:39:56.794001 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 17:39:56.810107 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 17:39:56.811328 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 17:39:56.813375 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 17:39:56.816443 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 17:39:57.269490 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:39:57.271217 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 17:39:57.273099 systemd[1]: Startup finished in 613ms (kernel) + 4.271s (initrd) + 3.328s (userspace) = 8.213s. Sep 4 17:39:57.274248 (kubelet)[1510]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 4 17:39:57.749510 kubelet[1510]: E0904 17:39:57.749359 1510 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" Sep 4 17:39:57.752236 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 4 17:39:57.752377 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 4 17:40:02.437492 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 17:40:02.438613 systemd[1]: Started sshd@0-10.0.0.156:22-10.0.0.1:53364.service - OpenSSH per-connection server daemon (10.0.0.1:53364). Sep 4 17:40:02.485096 sshd[1525]: Accepted publickey for core from 10.0.0.1 port 53364 ssh2: RSA SHA256:TcdII3DD+/vh6fGiZDuqtLwdsO9LHnvXRMQO7IdpdiA Sep 4 17:40:02.486703 sshd[1525]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:40:02.501687 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 17:40:02.513103 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 17:40:02.514926 systemd-logind[1412]: New session 1 of user core. Sep 4 17:40:02.522566 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 17:40:02.524837 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 17:40:02.531494 (systemd)[1529]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:40:02.604008 systemd[1529]: Queued start job for default target default.target. Sep 4 17:40:02.615786 systemd[1529]: Created slice app.slice - User Application Slice. Sep 4 17:40:02.615836 systemd[1529]: Reached target paths.target - Paths. Sep 4 17:40:02.615848 systemd[1529]: Reached target timers.target - Timers. Sep 4 17:40:02.617060 systemd[1529]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 17:40:02.627462 systemd[1529]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 17:40:02.627528 systemd[1529]: Reached target sockets.target - Sockets. Sep 4 17:40:02.627540 systemd[1529]: Reached target basic.target - Basic System. Sep 4 17:40:02.627577 systemd[1529]: Reached target default.target - Main User Target. Sep 4 17:40:02.627615 systemd[1529]: Startup finished in 90ms. Sep 4 17:40:02.627895 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 17:40:02.629294 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 17:40:02.691144 systemd[1]: Started sshd@1-10.0.0.156:22-10.0.0.1:32848.service - OpenSSH per-connection server daemon (10.0.0.1:32848). Sep 4 17:40:02.733154 sshd[1540]: Accepted publickey for core from 10.0.0.1 port 32848 ssh2: RSA SHA256:TcdII3DD+/vh6fGiZDuqtLwdsO9LHnvXRMQO7IdpdiA Sep 4 17:40:02.734540 sshd[1540]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:40:02.738975 systemd-logind[1412]: New session 2 of user core. Sep 4 17:40:02.752017 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 17:40:02.804037 sshd[1540]: pam_unix(sshd:session): session closed for user core Sep 4 17:40:02.823318 systemd[1]: sshd@1-10.0.0.156:22-10.0.0.1:32848.service: Deactivated successfully. Sep 4 17:40:02.824861 systemd[1]: session-2.scope: Deactivated successfully. Sep 4 17:40:02.826879 systemd-logind[1412]: Session 2 logged out. Waiting for processes to exit. Sep 4 17:40:02.828062 systemd[1]: Started sshd@2-10.0.0.156:22-10.0.0.1:32858.service - OpenSSH per-connection server daemon (10.0.0.1:32858). Sep 4 17:40:02.828868 systemd-logind[1412]: Removed session 2. Sep 4 17:40:02.860726 sshd[1547]: Accepted publickey for core from 10.0.0.1 port 32858 ssh2: RSA SHA256:TcdII3DD+/vh6fGiZDuqtLwdsO9LHnvXRMQO7IdpdiA Sep 4 17:40:02.861973 sshd[1547]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:40:02.865438 systemd-logind[1412]: New session 3 of user core. Sep 4 17:40:02.874014 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 17:40:02.921448 sshd[1547]: pam_unix(sshd:session): session closed for user core Sep 4 17:40:02.934745 systemd[1]: sshd@2-10.0.0.156:22-10.0.0.1:32858.service: Deactivated successfully. Sep 4 17:40:02.936662 systemd[1]: session-3.scope: Deactivated successfully. Sep 4 17:40:02.939333 systemd-logind[1412]: Session 3 logged out. Waiting for processes to exit. Sep 4 17:40:02.953193 systemd[1]: Started sshd@3-10.0.0.156:22-10.0.0.1:32862.service - OpenSSH per-connection server daemon (10.0.0.1:32862). Sep 4 17:40:02.954363 systemd-logind[1412]: Removed session 3. Sep 4 17:40:02.984632 sshd[1554]: Accepted publickey for core from 10.0.0.1 port 32862 ssh2: RSA SHA256:TcdII3DD+/vh6fGiZDuqtLwdsO9LHnvXRMQO7IdpdiA Sep 4 17:40:02.986204 sshd[1554]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:40:02.989860 systemd-logind[1412]: New session 4 of user core. Sep 4 17:40:03.004041 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 17:40:03.057862 sshd[1554]: pam_unix(sshd:session): session closed for user core Sep 4 17:40:03.066216 systemd[1]: sshd@3-10.0.0.156:22-10.0.0.1:32862.service: Deactivated successfully. Sep 4 17:40:03.069816 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 17:40:03.071366 systemd-logind[1412]: Session 4 logged out. Waiting for processes to exit. Sep 4 17:40:03.090158 systemd[1]: Started sshd@4-10.0.0.156:22-10.0.0.1:32866.service - OpenSSH per-connection server daemon (10.0.0.1:32866). Sep 4 17:40:03.091021 systemd-logind[1412]: Removed session 4. Sep 4 17:40:03.131603 sshd[1561]: Accepted publickey for core from 10.0.0.1 port 32866 ssh2: RSA SHA256:TcdII3DD+/vh6fGiZDuqtLwdsO9LHnvXRMQO7IdpdiA Sep 4 17:40:03.133083 sshd[1561]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:40:03.138997 systemd-logind[1412]: New session 5 of user core. Sep 4 17:40:03.148974 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 17:40:03.222041 sudo[1564]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 4 17:40:03.222283 sudo[1564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 4 17:40:03.241128 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 17:40:03.262866 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 17:40:03.263101 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 17:40:03.834605 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:40:03.845059 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:40:03.870788 systemd[1]: Reloading requested from client PID 1612 ('systemctl') (unit session-5.scope)... Sep 4 17:40:03.870883 systemd[1]: Reloading... Sep 4 17:40:03.940962 zram_generator::config[1648]: No configuration found. Sep 4 17:40:04.088453 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. Sep 4 17:40:04.144953 systemd[1]: Reloading finished in 273 ms. Sep 4 17:40:04.198231 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 4 17:40:04.198311 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 4 17:40:04.198528 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:40:04.202110 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:40:04.289214 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:40:04.293606 (kubelet)[1693]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 4 17:40:04.334106 kubelet[1693]: 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. Sep 4 17:40:04.334106 kubelet[1693]: 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. Sep 4 17:40:04.334106 kubelet[1693]: 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. Sep 4 17:40:04.335301 kubelet[1693]: I0904 17:40:04.335241 1693 server.go:203] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 4 17:40:04.965875 kubelet[1693]: I0904 17:40:04.965832 1693 server.go:467] "Kubelet version" kubeletVersion="v1.28.7" Sep 4 17:40:04.965875 kubelet[1693]: I0904 17:40:04.965863 1693 server.go:469] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 4 17:40:04.966074 kubelet[1693]: I0904 17:40:04.966059 1693 server.go:895] "Client rotation is on, will bootstrap in background" Sep 4 17:40:04.986746 kubelet[1693]: I0904 17:40:04.986684 1693 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 4 17:40:04.998474 kubelet[1693]: W0904 17:40:04.998427 1693 machine.go:65] Cannot read vendor id correctly, set empty. Sep 4 17:40:04.999716 kubelet[1693]: I0904 17:40:04.999684 1693 server.go:725] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 4 17:40:04.999929 kubelet[1693]: I0904 17:40:04.999907 1693 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 4 17:40:05.000101 kubelet[1693]: I0904 17:40:05.000071 1693 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"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} Sep 4 17:40:05.000174 kubelet[1693]: I0904 17:40:05.000109 1693 topology_manager.go:138] "Creating topology manager with none policy" Sep 4 17:40:05.000174 kubelet[1693]: I0904 17:40:05.000118 1693 container_manager_linux.go:301] "Creating device plugin manager" Sep 4 17:40:05.000312 kubelet[1693]: I0904 17:40:05.000283 1693 state_mem.go:36] "Initialized new in-memory state store" Sep 4 17:40:05.001420 kubelet[1693]: I0904 17:40:05.001379 1693 kubelet.go:393] "Attempting to sync node with API server" Sep 4 17:40:05.001420 kubelet[1693]: I0904 17:40:05.001402 1693 kubelet.go:298] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 4 17:40:05.001513 kubelet[1693]: I0904 17:40:05.001493 1693 kubelet.go:309] "Adding apiserver pod source" Sep 4 17:40:05.001513 kubelet[1693]: I0904 17:40:05.001506 1693 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 4 17:40:05.001585 kubelet[1693]: E0904 17:40:05.001551 1693 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:05.001618 kubelet[1693]: E0904 17:40:05.001609 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:05.002756 kubelet[1693]: I0904 17:40:05.002698 1693 kuberuntime_manager.go:257] "Container runtime initialized" containerRuntime="containerd" version="v1.7.17" apiVersion="v1" Sep 4 17:40:05.004928 kubelet[1693]: W0904 17:40:05.004887 1693 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 4 17:40:05.006776 kubelet[1693]: I0904 17:40:05.006492 1693 server.go:1232] "Started kubelet" Sep 4 17:40:05.006776 kubelet[1693]: I0904 17:40:05.006722 1693 ratelimit.go:65] "Setting rate limiting for podresources endpoint" qps=100 burstTokens=10 Sep 4 17:40:05.006977 kubelet[1693]: E0904 17:40:05.006960 1693 cri_stats_provider.go:448] "Failed to get the info of the filesystem with mountpoint" err="unable to find data in memory cache" mountpoint="/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs" Sep 4 17:40:05.007009 kubelet[1693]: E0904 17:40:05.007000 1693 kubelet.go:1431] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 4 17:40:05.007135 kubelet[1693]: I0904 17:40:05.007125 1693 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Sep 4 17:40:05.007510 kubelet[1693]: I0904 17:40:05.007470 1693 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 4 17:40:05.008190 kubelet[1693]: I0904 17:40:05.008089 1693 server.go:462] "Adding debug handlers to kubelet server" Sep 4 17:40:05.009870 kubelet[1693]: I0904 17:40:05.009671 1693 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 4 17:40:05.012297 kubelet[1693]: E0904 17:40:05.012275 1693 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.156\" not found" Sep 4 17:40:05.014228 kubelet[1693]: I0904 17:40:05.012310 1693 volume_manager.go:291] "Starting Kubelet Volume Manager" Sep 4 17:40:05.014228 kubelet[1693]: I0904 17:40:05.012409 1693 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Sep 4 17:40:05.014228 kubelet[1693]: I0904 17:40:05.012471 1693 reconciler_new.go:29] "Reconciler: start to sync state" Sep 4 17:40:05.015379 kubelet[1693]: W0904 17:40:05.014448 1693 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Sep 4 17:40:05.015379 kubelet[1693]: E0904 17:40:05.014483 1693 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Sep 4 17:40:05.015379 kubelet[1693]: W0904 17:40:05.014720 1693 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes "10.0.0.156" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Sep 4 17:40:05.015379 kubelet[1693]: E0904 17:40:05.014757 1693 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.156" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Sep 4 17:40:05.015379 kubelet[1693]: W0904 17:40:05.014796 1693 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Sep 4 17:40:05.015379 kubelet[1693]: E0904 17:40:05.014821 1693 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Sep 4 17:40:05.018172 kubelet[1693]: E0904 17:40:05.018134 1693 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.156\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Sep 4 17:40:05.023401 kubelet[1693]: E0904 17:40:05.023297 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b48666f0a9b", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"Starting", Message:"Starting kubelet.", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 6469787, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 6469787, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.024346 kubelet[1693]: E0904 17:40:05.024280 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486676ee01", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"InvalidDiskCapacity", Message:"invalid capacity 0 on image filesystem", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 6986753, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 6986753, time.Local), Count:1, Type:"Warning", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.040931 kubelet[1693]: E0904 17:40:05.040842 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863c4e6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.156 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39285478, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39285478, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.041705 kubelet[1693]: I0904 17:40:05.041310 1693 cpu_manager.go:214] "Starting CPU manager" policy="none" Sep 4 17:40:05.041705 kubelet[1693]: I0904 17:40:05.041330 1693 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Sep 4 17:40:05.041705 kubelet[1693]: I0904 17:40:05.041355 1693 state_mem.go:36] "Initialized new in-memory state store" Sep 4 17:40:05.042354 kubelet[1693]: E0904 17:40:05.042092 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863d9a5", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.156 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39290789, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39290789, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.043611 kubelet[1693]: E0904 17:40:05.043550 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b48686403c3", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.156 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39301571, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39301571, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.100956 kubelet[1693]: I0904 17:40:05.100922 1693 policy_none.go:49] "None policy: Start" Sep 4 17:40:05.101840 kubelet[1693]: I0904 17:40:05.101822 1693 memory_manager.go:169] "Starting memorymanager" policy="None" Sep 4 17:40:05.101912 kubelet[1693]: I0904 17:40:05.101847 1693 state_mem.go:35] "Initializing new in-memory state store" Sep 4 17:40:05.111161 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 4 17:40:05.114549 kubelet[1693]: I0904 17:40:05.114486 1693 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.156" Sep 4 17:40:05.116001 kubelet[1693]: E0904 17:40:05.115976 1693 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.156" Sep 4 17:40:05.116508 kubelet[1693]: E0904 17:40:05.116431 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863c4e6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.156 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39285478, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 114428198, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b486863c4e6" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.117601 kubelet[1693]: E0904 17:40:05.117528 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863d9a5", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.156 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39290789, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 114449246, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b486863d9a5" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.118659 kubelet[1693]: E0904 17:40:05.118589 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b48686403c3", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.156 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39301571, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 114453765, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b48686403c3" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.121126 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 4 17:40:05.124575 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 4 17:40:05.127058 kubelet[1693]: I0904 17:40:05.127032 1693 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 4 17:40:05.127916 kubelet[1693]: I0904 17:40:05.127901 1693 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 4 17:40:05.127973 kubelet[1693]: I0904 17:40:05.127929 1693 status_manager.go:217] "Starting to sync pod status with apiserver" Sep 4 17:40:05.127973 kubelet[1693]: I0904 17:40:05.127946 1693 kubelet.go:2303] "Starting kubelet main sync loop" Sep 4 17:40:05.128016 kubelet[1693]: E0904 17:40:05.127991 1693 kubelet.go:2327] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Sep 4 17:40:05.129593 kubelet[1693]: W0904 17:40:05.129568 1693 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Sep 4 17:40:05.129667 kubelet[1693]: E0904 17:40:05.129598 1693 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.RuntimeClass: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Sep 4 17:40:05.136862 kubelet[1693]: I0904 17:40:05.136652 1693 manager.go:471] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 4 17:40:05.136994 kubelet[1693]: I0904 17:40:05.136933 1693 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 4 17:40:05.137553 kubelet[1693]: E0904 17:40:05.137524 1693 eviction_manager.go:258] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.156\" not found" Sep 4 17:40:05.143267 kubelet[1693]: E0904 17:40:05.143184 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486e4a3236", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeAllocatableEnforced", Message:"Updated Node Allocatable limit across pods", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 138272822, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 138272822, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.220331 kubelet[1693]: E0904 17:40:05.219686 1693 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.156\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="400ms" Sep 4 17:40:05.317024 kubelet[1693]: I0904 17:40:05.316998 1693 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.156" Sep 4 17:40:05.318340 kubelet[1693]: E0904 17:40:05.318312 1693 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.156" Sep 4 17:40:05.318437 kubelet[1693]: E0904 17:40:05.318348 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863c4e6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.156 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39285478, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 316950138, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b486863c4e6" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.319418 kubelet[1693]: E0904 17:40:05.319344 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863d9a5", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.156 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39290789, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 316964487, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b486863d9a5" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.320431 kubelet[1693]: E0904 17:40:05.320369 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b48686403c3", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.156 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39301571, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 316968372, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b48686403c3" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.621120 kubelet[1693]: E0904 17:40:05.620998 1693 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.156\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="800ms" Sep 4 17:40:05.719153 kubelet[1693]: I0904 17:40:05.719129 1693 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.156" Sep 4 17:40:05.720524 kubelet[1693]: E0904 17:40:05.720489 1693 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.156" Sep 4 17:40:05.720595 kubelet[1693]: E0904 17:40:05.720464 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863c4e6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.156 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39285478, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 719086334, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b486863c4e6" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.721440 kubelet[1693]: E0904 17:40:05.721376 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b486863d9a5", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.156 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39290789, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 719096958, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b486863d9a5" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.722254 kubelet[1693]: E0904 17:40:05.722192 1693 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.156.17f21b48686403c3", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.156", UID:"10.0.0.156", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.156 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.156"}, FirstTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 39301571, time.Local), LastTimestamp:time.Date(2024, time.September, 4, 17, 40, 5, 719100049, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.156"}': 'events "10.0.0.156.17f21b48686403c3" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Sep 4 17:40:05.969178 kubelet[1693]: I0904 17:40:05.968985 1693 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 4 17:40:06.002609 kubelet[1693]: E0904 17:40:06.002582 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:06.002609 kubelet[1693]: I0904 17:40:06.002587 1693 apiserver.go:52] "Watching apiserver" Sep 4 17:40:06.013012 kubelet[1693]: I0904 17:40:06.012979 1693 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Sep 4 17:40:06.365101 kubelet[1693]: E0904 17:40:06.364984 1693 csi_plugin.go:295] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.156" not found Sep 4 17:40:06.425780 kubelet[1693]: E0904 17:40:06.425731 1693 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.156\" not found" node="10.0.0.156" Sep 4 17:40:06.522046 kubelet[1693]: I0904 17:40:06.522013 1693 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.156" Sep 4 17:40:06.525606 kubelet[1693]: I0904 17:40:06.525568 1693 kubelet_node_status.go:73] "Successfully registered node" node="10.0.0.156" Sep 4 17:40:06.544792 kubelet[1693]: I0904 17:40:06.544749 1693 topology_manager.go:215] "Topology Admit Handler" podUID="0ca97486-cc3e-4d79-ac97-67dee8eeca18" podNamespace="kube-system" podName="kube-proxy-7gsn2" Sep 4 17:40:06.547435 kubelet[1693]: I0904 17:40:06.547398 1693 topology_manager.go:215] "Topology Admit Handler" podUID="a97e55b0-2ba6-4017-8fed-191c1e7e3525" podNamespace="kube-flannel" podName="kube-flannel-ds-ctcwz" Sep 4 17:40:06.549194 kubelet[1693]: W0904 17:40:06.549163 1693 reflector.go:535] object-"kube-flannel"/"kube-root-ca.crt": failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:10.0.0.156" cannot list resource "configmaps" in API group "" in the namespace "kube-flannel": no relationship found between node '10.0.0.156' and this object Sep 4 17:40:06.549194 kubelet[1693]: E0904 17:40:06.549193 1693 reflector.go:147] object-"kube-flannel"/"kube-root-ca.crt": Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:10.0.0.156" cannot list resource "configmaps" in API group "" in the namespace "kube-flannel": no relationship found between node '10.0.0.156' and this object Sep 4 17:40:06.551476 systemd[1]: Created slice kubepods-besteffort-pod0ca97486_cc3e_4d79_ac97_67dee8eeca18.slice - libcontainer container kubepods-besteffort-pod0ca97486_cc3e_4d79_ac97_67dee8eeca18.slice. Sep 4 17:40:06.562210 systemd[1]: Created slice kubepods-burstable-poda97e55b0_2ba6_4017_8fed_191c1e7e3525.slice - libcontainer container kubepods-burstable-poda97e55b0_2ba6_4017_8fed_191c1e7e3525.slice. Sep 4 17:40:06.621027 kubelet[1693]: I0904 17:40:06.620905 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hjffr\" (UniqueName: \"kubernetes.io/projected/0ca97486-cc3e-4d79-ac97-67dee8eeca18-kube-api-access-hjffr\") pod \"kube-proxy-7gsn2\" (UID: \"0ca97486-cc3e-4d79-ac97-67dee8eeca18\") " pod="kube-system/kube-proxy-7gsn2" Sep 4 17:40:06.621027 kubelet[1693]: I0904 17:40:06.620945 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/a97e55b0-2ba6-4017-8fed-191c1e7e3525-cni-plugin\") pod \"kube-flannel-ds-ctcwz\" (UID: \"a97e55b0-2ba6-4017-8fed-191c1e7e3525\") " pod="kube-flannel/kube-flannel-ds-ctcwz" Sep 4 17:40:06.621027 kubelet[1693]: I0904 17:40:06.620970 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/a97e55b0-2ba6-4017-8fed-191c1e7e3525-cni\") pod \"kube-flannel-ds-ctcwz\" (UID: \"a97e55b0-2ba6-4017-8fed-191c1e7e3525\") " pod="kube-flannel/kube-flannel-ds-ctcwz" Sep 4 17:40:06.621027 kubelet[1693]: I0904 17:40:06.620988 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/a97e55b0-2ba6-4017-8fed-191c1e7e3525-flannel-cfg\") pod \"kube-flannel-ds-ctcwz\" (UID: \"a97e55b0-2ba6-4017-8fed-191c1e7e3525\") " pod="kube-flannel/kube-flannel-ds-ctcwz" Sep 4 17:40:06.621027 kubelet[1693]: I0904 17:40:06.621030 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/0ca97486-cc3e-4d79-ac97-67dee8eeca18-kube-proxy\") pod \"kube-proxy-7gsn2\" (UID: \"0ca97486-cc3e-4d79-ac97-67dee8eeca18\") " pod="kube-system/kube-proxy-7gsn2" Sep 4 17:40:06.621410 kubelet[1693]: I0904 17:40:06.621382 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0ca97486-cc3e-4d79-ac97-67dee8eeca18-lib-modules\") pod \"kube-proxy-7gsn2\" (UID: \"0ca97486-cc3e-4d79-ac97-67dee8eeca18\") " pod="kube-system/kube-proxy-7gsn2" Sep 4 17:40:06.621584 kubelet[1693]: I0904 17:40:06.621425 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/a97e55b0-2ba6-4017-8fed-191c1e7e3525-run\") pod \"kube-flannel-ds-ctcwz\" (UID: \"a97e55b0-2ba6-4017-8fed-191c1e7e3525\") " pod="kube-flannel/kube-flannel-ds-ctcwz" Sep 4 17:40:06.621584 kubelet[1693]: I0904 17:40:06.621457 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a97e55b0-2ba6-4017-8fed-191c1e7e3525-xtables-lock\") pod \"kube-flannel-ds-ctcwz\" (UID: \"a97e55b0-2ba6-4017-8fed-191c1e7e3525\") " pod="kube-flannel/kube-flannel-ds-ctcwz" Sep 4 17:40:06.621584 kubelet[1693]: I0904 17:40:06.621476 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-25d87\" (UniqueName: \"kubernetes.io/projected/a97e55b0-2ba6-4017-8fed-191c1e7e3525-kube-api-access-25d87\") pod \"kube-flannel-ds-ctcwz\" (UID: \"a97e55b0-2ba6-4017-8fed-191c1e7e3525\") " pod="kube-flannel/kube-flannel-ds-ctcwz" Sep 4 17:40:06.621584 kubelet[1693]: I0904 17:40:06.621511 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0ca97486-cc3e-4d79-ac97-67dee8eeca18-xtables-lock\") pod \"kube-proxy-7gsn2\" (UID: \"0ca97486-cc3e-4d79-ac97-67dee8eeca18\") " pod="kube-system/kube-proxy-7gsn2" Sep 4 17:40:06.638509 kubelet[1693]: I0904 17:40:06.638476 1693 kuberuntime_manager.go:1528] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 4 17:40:06.638816 containerd[1436]: time="2024-09-04T17:40:06.638767886Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 4 17:40:06.639075 kubelet[1693]: I0904 17:40:06.639003 1693 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 4 17:40:06.803116 sudo[1564]: pam_unix(sudo:session): session closed for user root Sep 4 17:40:06.805550 sshd[1561]: pam_unix(sshd:session): session closed for user core Sep 4 17:40:06.808411 systemd[1]: sshd@4-10.0.0.156:22-10.0.0.1:32866.service: Deactivated successfully. Sep 4 17:40:06.810168 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 17:40:06.810840 systemd-logind[1412]: Session 5 logged out. Waiting for processes to exit. Sep 4 17:40:06.812722 systemd-logind[1412]: Removed session 5. Sep 4 17:40:06.865116 kubelet[1693]: E0904 17:40:06.864102 1693 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" Sep 4 17:40:06.867387 containerd[1436]: time="2024-09-04T17:40:06.867348152Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7gsn2,Uid:0ca97486-cc3e-4d79-ac97-67dee8eeca18,Namespace:kube-system,Attempt:0,}" Sep 4 17:40:07.003195 kubelet[1693]: E0904 17:40:07.003055 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:07.507728 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2951374527.mount: Deactivated successfully. Sep 4 17:40:07.513519 containerd[1436]: time="2024-09-04T17:40:07.513472121Z" 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\"}" Sep 4 17:40:07.515025 containerd[1436]: time="2024-09-04T17:40:07.514989389Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Sep 4 17:40:07.515940 containerd[1436]: time="2024-09-04T17:40:07.515887730Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 17:40:07.518739 containerd[1436]: time="2024-09-04T17:40:07.518477049Z" 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\"}" Sep 4 17:40:07.519271 containerd[1436]: time="2024-09-04T17:40:07.519243867Z" 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 651.79741ms" Sep 4 17:40:07.609365 containerd[1436]: time="2024-09-04T17:40:07.609248490Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:40:07.609365 containerd[1436]: time="2024-09-04T17:40:07.609298621Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:07.609586 containerd[1436]: time="2024-09-04T17:40:07.609370679Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:40:07.609586 containerd[1436]: time="2024-09-04T17:40:07.609403133Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:07.693305 systemd[1]: Started cri-containerd-f5aa663abc5484d118892d2e078d750f68082a1e472759e23948b50ad5156672.scope - libcontainer container f5aa663abc5484d118892d2e078d750f68082a1e472759e23948b50ad5156672. Sep 4 17:40:07.718291 containerd[1436]: time="2024-09-04T17:40:07.718246738Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7gsn2,Uid:0ca97486-cc3e-4d79-ac97-67dee8eeca18,Namespace:kube-system,Attempt:0,} returns sandbox id \"f5aa663abc5484d118892d2e078d750f68082a1e472759e23948b50ad5156672\"" Sep 4 17:40:07.719345 kubelet[1693]: E0904 17:40:07.719303 1693 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" Sep 4 17:40:07.720727 containerd[1436]: time="2024-09-04T17:40:07.720356081Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.13\"" Sep 4 17:40:07.774136 kubelet[1693]: E0904 17:40:07.774030 1693 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" Sep 4 17:40:07.775238 containerd[1436]: time="2024-09-04T17:40:07.775204412Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ctcwz,Uid:a97e55b0-2ba6-4017-8fed-191c1e7e3525,Namespace:kube-flannel,Attempt:0,}" Sep 4 17:40:07.803950 containerd[1436]: time="2024-09-04T17:40:07.803854953Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:40:07.804074 containerd[1436]: time="2024-09-04T17:40:07.803970587Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:07.804074 containerd[1436]: time="2024-09-04T17:40:07.804003001Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:40:07.804074 containerd[1436]: time="2024-09-04T17:40:07.804043201Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:07.823999 systemd[1]: Started cri-containerd-a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3.scope - libcontainer container a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3. Sep 4 17:40:07.851842 containerd[1436]: time="2024-09-04T17:40:07.851789375Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ctcwz,Uid:a97e55b0-2ba6-4017-8fed-191c1e7e3525,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3\"" Sep 4 17:40:07.852616 kubelet[1693]: E0904 17:40:07.852594 1693 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" Sep 4 17:40:08.004082 kubelet[1693]: E0904 17:40:08.004020 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:08.733763 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1765458549.mount: Deactivated successfully. Sep 4 17:40:09.004411 kubelet[1693]: E0904 17:40:09.004301 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:09.990088 containerd[1436]: time="2024-09-04T17:40:09.990032610Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.28.13\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:09.990820 containerd[1436]: time="2024-09-04T17:40:09.990556182Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.28.13: active requests=0, bytes read=24977932" Sep 4 17:40:09.991313 containerd[1436]: time="2024-09-04T17:40:09.991276903Z" level=info msg="ImageCreate event name:\"sha256:28cc84306a40b12ede33c1df2d3219e0061b4d0e5309eb874034dd77e9154393\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:09.993292 containerd[1436]: time="2024-09-04T17:40:09.993243589Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:537633f399f87ce85d44fc8471ece97a83632198f99b3f7e08770beca95e9fa1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:09.994243 containerd[1436]: time="2024-09-04T17:40:09.993765808Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.28.13\" with image id \"sha256:28cc84306a40b12ede33c1df2d3219e0061b4d0e5309eb874034dd77e9154393\", repo tag \"registry.k8s.io/kube-proxy:v1.28.13\", repo digest \"registry.k8s.io/kube-proxy@sha256:537633f399f87ce85d44fc8471ece97a83632198f99b3f7e08770beca95e9fa1\", size \"24976949\" in 2.273360177s" Sep 4 17:40:09.994243 containerd[1436]: time="2024-09-04T17:40:09.993819323Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.13\" returns image reference \"sha256:28cc84306a40b12ede33c1df2d3219e0061b4d0e5309eb874034dd77e9154393\"" Sep 4 17:40:09.994830 containerd[1436]: time="2024-09-04T17:40:09.994789397Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 4 17:40:09.995939 containerd[1436]: time="2024-09-04T17:40:09.995906209Z" level=info msg="CreateContainer within sandbox \"f5aa663abc5484d118892d2e078d750f68082a1e472759e23948b50ad5156672\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 4 17:40:10.004972 kubelet[1693]: E0904 17:40:10.004946 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:10.007405 containerd[1436]: time="2024-09-04T17:40:10.007371342Z" level=info msg="CreateContainer within sandbox \"f5aa663abc5484d118892d2e078d750f68082a1e472759e23948b50ad5156672\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"09c00854ec1d9dff5282753eb548b1e734c01b0d1e579b83a8d9311f281a561d\"" Sep 4 17:40:10.008151 containerd[1436]: time="2024-09-04T17:40:10.008127143Z" level=info msg="StartContainer for \"09c00854ec1d9dff5282753eb548b1e734c01b0d1e579b83a8d9311f281a561d\"" Sep 4 17:40:10.036507 systemd[1]: Started cri-containerd-09c00854ec1d9dff5282753eb548b1e734c01b0d1e579b83a8d9311f281a561d.scope - libcontainer container 09c00854ec1d9dff5282753eb548b1e734c01b0d1e579b83a8d9311f281a561d. Sep 4 17:40:10.063874 containerd[1436]: time="2024-09-04T17:40:10.063830060Z" level=info msg="StartContainer for \"09c00854ec1d9dff5282753eb548b1e734c01b0d1e579b83a8d9311f281a561d\" returns successfully" Sep 4 17:40:10.138209 kubelet[1693]: E0904 17:40:10.138182 1693 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" Sep 4 17:40:10.146376 kubelet[1693]: I0904 17:40:10.146345 1693 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-7gsn2" podStartSLOduration=1.872043591 podCreationTimestamp="2024-09-04 17:40:06 +0000 UTC" firstStartedPulling="2024-09-04 17:40:07.719941768 +0000 UTC m=+3.423029614" lastFinishedPulling="2024-09-04 17:40:09.994201607 +0000 UTC m=+5.697289414" observedRunningTime="2024-09-04 17:40:10.145998531 +0000 UTC m=+5.849086417" watchObservedRunningTime="2024-09-04 17:40:10.146303391 +0000 UTC m=+5.849391238" Sep 4 17:40:11.005771 kubelet[1693]: E0904 17:40:11.005698 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:11.030052 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2231578421.mount: Deactivated successfully. Sep 4 17:40:11.056264 containerd[1436]: time="2024-09-04T17:40:11.056217607Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:11.057743 containerd[1436]: time="2024-09-04T17:40:11.057664754Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Sep 4 17:40:11.058577 containerd[1436]: time="2024-09-04T17:40:11.058546762Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:11.060628 containerd[1436]: time="2024-09-04T17:40:11.060579245Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:11.061505 containerd[1436]: time="2024-09-04T17:40:11.061457548Z" 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.065858533s" Sep 4 17:40:11.061505 containerd[1436]: time="2024-09-04T17:40:11.061489856Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Sep 4 17:40:11.062967 containerd[1436]: time="2024-09-04T17:40:11.062939353Z" level=info msg="CreateContainer within sandbox \"a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 4 17:40:11.074045 containerd[1436]: time="2024-09-04T17:40:11.073928919Z" level=info msg="CreateContainer within sandbox \"a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb\"" Sep 4 17:40:11.074372 containerd[1436]: time="2024-09-04T17:40:11.074335902Z" level=info msg="StartContainer for \"954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb\"" Sep 4 17:40:11.098000 systemd[1]: Started cri-containerd-954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb.scope - libcontainer container 954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb. Sep 4 17:40:11.125233 systemd[1]: cri-containerd-954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb.scope: Deactivated successfully. Sep 4 17:40:11.125657 containerd[1436]: time="2024-09-04T17:40:11.125623239Z" level=info msg="StartContainer for \"954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb\" returns successfully" Sep 4 17:40:11.142238 kubelet[1693]: E0904 17:40:11.141878 1693 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" Sep 4 17:40:11.142238 kubelet[1693]: E0904 17:40:11.141887 1693 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" Sep 4 17:40:11.268722 containerd[1436]: time="2024-09-04T17:40:11.268584323Z" level=info msg="shim disconnected" id=954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb namespace=k8s.io Sep 4 17:40:11.268949 containerd[1436]: time="2024-09-04T17:40:11.268928481Z" level=warning msg="cleaning up after shim disconnected" id=954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb namespace=k8s.io Sep 4 17:40:11.269063 containerd[1436]: time="2024-09-04T17:40:11.269046242Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 17:40:12.004621 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-954e9178413e5857d23827214a1c17a9426c5a5210cc041161b34bdeaa498ffb-rootfs.mount: Deactivated successfully. Sep 4 17:40:12.006431 kubelet[1693]: E0904 17:40:12.006398 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:12.144036 kubelet[1693]: E0904 17:40:12.144001 1693 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" Sep 4 17:40:12.145013 containerd[1436]: time="2024-09-04T17:40:12.144975868Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 4 17:40:13.007184 kubelet[1693]: E0904 17:40:13.007137 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:13.196277 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3039603344.mount: Deactivated successfully. Sep 4 17:40:13.753674 containerd[1436]: time="2024-09-04T17:40:13.753625028Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:13.754568 containerd[1436]: time="2024-09-04T17:40:13.754344906Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Sep 4 17:40:13.755376 containerd[1436]: time="2024-09-04T17:40:13.755339488Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:13.758896 containerd[1436]: time="2024-09-04T17:40:13.758838668Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:13.759975 containerd[1436]: time="2024-09-04T17:40:13.759945979Z" 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.614928654s" Sep 4 17:40:13.760019 containerd[1436]: time="2024-09-04T17:40:13.759987290Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Sep 4 17:40:13.761989 containerd[1436]: time="2024-09-04T17:40:13.761953286Z" level=info msg="CreateContainer within sandbox \"a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 4 17:40:13.772932 containerd[1436]: time="2024-09-04T17:40:13.772882801Z" level=info msg="CreateContainer within sandbox \"a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4\"" Sep 4 17:40:13.774377 containerd[1436]: time="2024-09-04T17:40:13.773484367Z" level=info msg="StartContainer for \"91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4\"" Sep 4 17:40:13.805577 systemd[1]: Started cri-containerd-91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4.scope - libcontainer container 91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4. Sep 4 17:40:13.827507 containerd[1436]: time="2024-09-04T17:40:13.827467013Z" level=info msg="StartContainer for \"91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4\" returns successfully" Sep 4 17:40:13.833030 systemd[1]: cri-containerd-91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4.scope: Deactivated successfully. Sep 4 17:40:13.869076 kubelet[1693]: I0904 17:40:13.869047 1693 kubelet_node_status.go:493] "Fast updating node status as it just became ready" Sep 4 17:40:13.958669 containerd[1436]: time="2024-09-04T17:40:13.958399603Z" level=info msg="shim disconnected" id=91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4 namespace=k8s.io Sep 4 17:40:13.958669 containerd[1436]: time="2024-09-04T17:40:13.958455628Z" level=warning msg="cleaning up after shim disconnected" id=91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4 namespace=k8s.io Sep 4 17:40:13.958669 containerd[1436]: time="2024-09-04T17:40:13.958465079Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 17:40:13.972863 containerd[1436]: time="2024-09-04T17:40:13.972334955Z" level=warning msg="cleanup warnings time=\"2024-09-04T17:40:13Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Sep 4 17:40:14.008217 kubelet[1693]: E0904 17:40:14.008088 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:14.110111 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-91fc29726f8d18fee3ae5e08b3d381cea35f157b7a78ced3450d7b3efa5547c4-rootfs.mount: Deactivated successfully. Sep 4 17:40:14.149181 kubelet[1693]: E0904 17:40:14.149158 1693 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" Sep 4 17:40:14.150745 containerd[1436]: time="2024-09-04T17:40:14.150691151Z" level=info msg="CreateContainer within sandbox \"a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 4 17:40:14.173172 containerd[1436]: time="2024-09-04T17:40:14.173122117Z" level=info msg="CreateContainer within sandbox \"a1335adbe7a98a1ee70025038818f4ec3bd8c92e4ffc840f774787183b5dc6e3\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"6d9470aa3417b6512b40e12df5b59d268e36a23f2059fed832c5397c400a841b\"" Sep 4 17:40:14.173864 containerd[1436]: time="2024-09-04T17:40:14.173834735Z" level=info msg="StartContainer for \"6d9470aa3417b6512b40e12df5b59d268e36a23f2059fed832c5397c400a841b\"" Sep 4 17:40:14.201963 systemd[1]: Started cri-containerd-6d9470aa3417b6512b40e12df5b59d268e36a23f2059fed832c5397c400a841b.scope - libcontainer container 6d9470aa3417b6512b40e12df5b59d268e36a23f2059fed832c5397c400a841b. Sep 4 17:40:14.227831 containerd[1436]: time="2024-09-04T17:40:14.227253075Z" level=info msg="StartContainer for \"6d9470aa3417b6512b40e12df5b59d268e36a23f2059fed832c5397c400a841b\" returns successfully" Sep 4 17:40:15.009399 kubelet[1693]: E0904 17:40:15.009211 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:15.110278 systemd[1]: run-containerd-runc-k8s.io-6d9470aa3417b6512b40e12df5b59d268e36a23f2059fed832c5397c400a841b-runc.uJdfqa.mount: Deactivated successfully. Sep 4 17:40:15.151964 kubelet[1693]: E0904 17:40:15.151894 1693 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" Sep 4 17:40:15.310795 systemd-networkd[1375]: flannel.1: Link UP Sep 4 17:40:15.310823 systemd-networkd[1375]: flannel.1: Gained carrier Sep 4 17:40:16.009532 kubelet[1693]: E0904 17:40:16.009485 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:16.153672 kubelet[1693]: E0904 17:40:16.153632 1693 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" Sep 4 17:40:16.738985 systemd-networkd[1375]: flannel.1: Gained IPv6LL Sep 4 17:40:17.010405 kubelet[1693]: E0904 17:40:17.010239 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:17.681098 kubelet[1693]: I0904 17:40:17.681056 1693 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-ctcwz" podStartSLOduration=5.774786044 podCreationTimestamp="2024-09-04 17:40:06 +0000 UTC" firstStartedPulling="2024-09-04 17:40:07.853976536 +0000 UTC m=+3.557064383" lastFinishedPulling="2024-09-04 17:40:13.760203098 +0000 UTC m=+9.463290945" observedRunningTime="2024-09-04 17:40:15.162924984 +0000 UTC m=+10.866012831" watchObservedRunningTime="2024-09-04 17:40:17.681012606 +0000 UTC m=+13.384100453" Sep 4 17:40:17.681248 kubelet[1693]: I0904 17:40:17.681191 1693 topology_manager.go:215] "Topology Admit Handler" podUID="5bf0bf62-cd5f-4f7d-9e00-84228e5d7781" podNamespace="default" podName="nginx-deployment-6d5f899847-hjjjj" Sep 4 17:40:17.686464 systemd[1]: Created slice kubepods-besteffort-pod5bf0bf62_cd5f_4f7d_9e00_84228e5d7781.slice - libcontainer container kubepods-besteffort-pod5bf0bf62_cd5f_4f7d_9e00_84228e5d7781.slice. Sep 4 17:40:17.689300 kubelet[1693]: I0904 17:40:17.689271 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wsm2z\" (UniqueName: \"kubernetes.io/projected/5bf0bf62-cd5f-4f7d-9e00-84228e5d7781-kube-api-access-wsm2z\") pod \"nginx-deployment-6d5f899847-hjjjj\" (UID: \"5bf0bf62-cd5f-4f7d-9e00-84228e5d7781\") " pod="default/nginx-deployment-6d5f899847-hjjjj" Sep 4 17:40:17.990945 containerd[1436]: time="2024-09-04T17:40:17.990832223Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-hjjjj,Uid:5bf0bf62-cd5f-4f7d-9e00-84228e5d7781,Namespace:default,Attempt:0,}" Sep 4 17:40:18.010540 kubelet[1693]: E0904 17:40:18.010495 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:18.033715 systemd-networkd[1375]: cni0: Link UP Sep 4 17:40:18.033720 systemd-networkd[1375]: cni0: Gained carrier Sep 4 17:40:18.034078 systemd-networkd[1375]: cni0: Lost carrier Sep 4 17:40:18.039853 systemd-networkd[1375]: veth04415851: Link UP Sep 4 17:40:18.041583 kernel: cni0: port 1(veth04415851) entered blocking state Sep 4 17:40:18.041674 kernel: cni0: port 1(veth04415851) entered disabled state Sep 4 17:40:18.041692 kernel: veth04415851: entered allmulticast mode Sep 4 17:40:18.043465 kernel: veth04415851: entered promiscuous mode Sep 4 17:40:18.043548 kernel: cni0: port 1(veth04415851) entered blocking state Sep 4 17:40:18.043568 kernel: cni0: port 1(veth04415851) entered forwarding state Sep 4 17:40:18.048747 kernel: cni0: port 1(veth04415851) entered disabled state Sep 4 17:40:18.059193 kernel: cni0: port 1(veth04415851) entered blocking state Sep 4 17:40:18.059350 kernel: cni0: port 1(veth04415851) entered forwarding state Sep 4 17:40:18.059396 systemd-networkd[1375]: veth04415851: Gained carrier Sep 4 17:40:18.059833 systemd-networkd[1375]: cni0: Gained carrier Sep 4 17:40:18.061532 containerd[1436]: 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"} Sep 4 17:40:18.061532 containerd[1436]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:40:18.092019 containerd[1436]: {"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-09-04T17:40:18.091554626Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:40:18.092019 containerd[1436]: time="2024-09-04T17:40:18.091705785Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:18.092019 containerd[1436]: time="2024-09-04T17:40:18.091733381Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:40:18.092019 containerd[1436]: time="2024-09-04T17:40:18.091748198Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:18.103210 systemd[1]: run-containerd-runc-k8s.io-7af3be51d8769ea5b0829eda02605233205c88bb1e7fff1a2d66083a700bd8bb-runc.NIeB4h.mount: Deactivated successfully. Sep 4 17:40:18.114014 systemd[1]: Started cri-containerd-7af3be51d8769ea5b0829eda02605233205c88bb1e7fff1a2d66083a700bd8bb.scope - libcontainer container 7af3be51d8769ea5b0829eda02605233205c88bb1e7fff1a2d66083a700bd8bb. Sep 4 17:40:18.124279 systemd-resolved[1307]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 17:40:18.140031 containerd[1436]: time="2024-09-04T17:40:18.139991636Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-hjjjj,Uid:5bf0bf62-cd5f-4f7d-9e00-84228e5d7781,Namespace:default,Attempt:0,} returns sandbox id \"7af3be51d8769ea5b0829eda02605233205c88bb1e7fff1a2d66083a700bd8bb\"" Sep 4 17:40:18.141397 containerd[1436]: time="2024-09-04T17:40:18.141292721Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 17:40:19.011105 kubelet[1693]: E0904 17:40:19.010770 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:19.555400 systemd-networkd[1375]: veth04415851: Gained IPv6LL Sep 4 17:40:19.795966 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3032905096.mount: Deactivated successfully. Sep 4 17:40:19.939002 systemd-networkd[1375]: cni0: Gained IPv6LL Sep 4 17:40:20.011760 kubelet[1693]: E0904 17:40:20.011716 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:20.479211 containerd[1436]: time="2024-09-04T17:40:20.479164895Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:20.480020 containerd[1436]: time="2024-09-04T17:40:20.479631526Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67679186" Sep 4 17:40:20.480793 containerd[1436]: time="2024-09-04T17:40:20.480715922Z" level=info msg="ImageCreate event name:\"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:20.484620 containerd[1436]: time="2024-09-04T17:40:20.484577966Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:337a19c298c7ca0845a051d89520d044d67a9402e4c33db4f541a93279763a1d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:20.485587 containerd[1436]: time="2024-09-04T17:40:20.485550259Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:337a19c298c7ca0845a051d89520d044d67a9402e4c33db4f541a93279763a1d\", size \"67679064\" in 2.34420474s" Sep 4 17:40:20.485636 containerd[1436]: time="2024-09-04T17:40:20.485586415Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\"" Sep 4 17:40:20.487159 containerd[1436]: time="2024-09-04T17:40:20.487128173Z" level=info msg="CreateContainer within sandbox \"7af3be51d8769ea5b0829eda02605233205c88bb1e7fff1a2d66083a700bd8bb\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 4 17:40:20.495668 containerd[1436]: time="2024-09-04T17:40:20.495621843Z" level=info msg="CreateContainer within sandbox \"7af3be51d8769ea5b0829eda02605233205c88bb1e7fff1a2d66083a700bd8bb\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"591c9fef56fab63a0d8cef7ed69d249d1ac63ccd49e76c167d2f98d1c0ee5ef2\"" Sep 4 17:40:20.496841 containerd[1436]: time="2024-09-04T17:40:20.496164500Z" level=info msg="StartContainer for \"591c9fef56fab63a0d8cef7ed69d249d1ac63ccd49e76c167d2f98d1c0ee5ef2\"" Sep 4 17:40:20.497409 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1545051178.mount: Deactivated successfully. Sep 4 17:40:20.524988 systemd[1]: Started cri-containerd-591c9fef56fab63a0d8cef7ed69d249d1ac63ccd49e76c167d2f98d1c0ee5ef2.scope - libcontainer container 591c9fef56fab63a0d8cef7ed69d249d1ac63ccd49e76c167d2f98d1c0ee5ef2. Sep 4 17:40:20.573929 containerd[1436]: time="2024-09-04T17:40:20.573870467Z" level=info msg="StartContainer for \"591c9fef56fab63a0d8cef7ed69d249d1ac63ccd49e76c167d2f98d1c0ee5ef2\" returns successfully" Sep 4 17:40:21.012459 kubelet[1693]: E0904 17:40:21.012419 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:21.174444 kubelet[1693]: I0904 17:40:21.174347 1693 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-hjjjj" podStartSLOduration=1.829337856 podCreationTimestamp="2024-09-04 17:40:17 +0000 UTC" firstStartedPulling="2024-09-04 17:40:18.141014445 +0000 UTC m=+13.844102291" lastFinishedPulling="2024-09-04 17:40:20.485988324 +0000 UTC m=+16.189076171" observedRunningTime="2024-09-04 17:40:21.17417732 +0000 UTC m=+16.877265127" watchObservedRunningTime="2024-09-04 17:40:21.174311736 +0000 UTC m=+16.877399583" Sep 4 17:40:22.012928 kubelet[1693]: E0904 17:40:22.012868 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:23.013421 kubelet[1693]: E0904 17:40:23.013366 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:24.014124 kubelet[1693]: E0904 17:40:24.014068 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:24.107183 kubelet[1693]: I0904 17:40:24.106794 1693 topology_manager.go:215] "Topology Admit Handler" podUID="eba713b3-9308-4121-be7e-231bb27a8369" podNamespace="default" podName="nfs-server-provisioner-0" Sep 4 17:40:24.115215 systemd[1]: Created slice kubepods-besteffort-podeba713b3_9308_4121_be7e_231bb27a8369.slice - libcontainer container kubepods-besteffort-podeba713b3_9308_4121_be7e_231bb27a8369.slice. Sep 4 17:40:24.124106 kubelet[1693]: I0904 17:40:24.123984 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/eba713b3-9308-4121-be7e-231bb27a8369-data\") pod \"nfs-server-provisioner-0\" (UID: \"eba713b3-9308-4121-be7e-231bb27a8369\") " pod="default/nfs-server-provisioner-0" Sep 4 17:40:24.124106 kubelet[1693]: I0904 17:40:24.124032 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7tgmr\" (UniqueName: \"kubernetes.io/projected/eba713b3-9308-4121-be7e-231bb27a8369-kube-api-access-7tgmr\") pod \"nfs-server-provisioner-0\" (UID: \"eba713b3-9308-4121-be7e-231bb27a8369\") " pod="default/nfs-server-provisioner-0" Sep 4 17:40:24.422921 containerd[1436]: time="2024-09-04T17:40:24.422790250Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:eba713b3-9308-4121-be7e-231bb27a8369,Namespace:default,Attempt:0,}" Sep 4 17:40:24.478501 systemd-networkd[1375]: vethfec94e06: Link UP Sep 4 17:40:24.485097 kernel: cni0: port 2(vethfec94e06) entered blocking state Sep 4 17:40:24.485259 kernel: cni0: port 2(vethfec94e06) entered disabled state Sep 4 17:40:24.485282 kernel: vethfec94e06: entered allmulticast mode Sep 4 17:40:24.486285 kernel: vethfec94e06: entered promiscuous mode Sep 4 17:40:24.492459 kernel: cni0: port 2(vethfec94e06) entered blocking state Sep 4 17:40:24.492595 kernel: cni0: port 2(vethfec94e06) entered forwarding state Sep 4 17:40:24.492708 systemd-networkd[1375]: vethfec94e06: Gained carrier Sep 4 17:40:24.498206 containerd[1436]: 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"} Sep 4 17:40:24.498206 containerd[1436]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:40:24.515329 containerd[1436]: {"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-09-04T17:40:24.515184438Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:40:24.515329 containerd[1436]: time="2024-09-04T17:40:24.515258305Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:24.515329 containerd[1436]: time="2024-09-04T17:40:24.515280809Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:40:24.516423 containerd[1436]: time="2024-09-04T17:40:24.515872426Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:24.536993 systemd[1]: Started cri-containerd-8ff5c536321ec54a8c0a2129f6eeefa1ea7d3d4b76daa2e26d41d8947c8b0c6b.scope - libcontainer container 8ff5c536321ec54a8c0a2129f6eeefa1ea7d3d4b76daa2e26d41d8947c8b0c6b. Sep 4 17:40:24.548141 systemd-resolved[1307]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 17:40:24.564826 containerd[1436]: time="2024-09-04T17:40:24.564771767Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:eba713b3-9308-4121-be7e-231bb27a8369,Namespace:default,Attempt:0,} returns sandbox id \"8ff5c536321ec54a8c0a2129f6eeefa1ea7d3d4b76daa2e26d41d8947c8b0c6b\"" Sep 4 17:40:24.566244 containerd[1436]: time="2024-09-04T17:40:24.566223329Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 4 17:40:25.002222 kubelet[1693]: E0904 17:40:25.002171 1693 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:25.014510 kubelet[1693]: E0904 17:40:25.014463 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:25.762992 systemd-networkd[1375]: vethfec94e06: Gained IPv6LL Sep 4 17:40:26.015618 kubelet[1693]: E0904 17:40:26.015512 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:26.169328 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2778577834.mount: Deactivated successfully. Sep 4 17:40:27.016307 kubelet[1693]: E0904 17:40:27.016261 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:27.628260 containerd[1436]: time="2024-09-04T17:40:27.628204371Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:27.629025 containerd[1436]: time="2024-09-04T17:40:27.628995912Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 4 17:40:27.629647 containerd[1436]: time="2024-09-04T17:40:27.629614056Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:27.632979 containerd[1436]: time="2024-09-04T17:40:27.632473726Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:27.634386 containerd[1436]: time="2024-09-04T17:40:27.634344550Z" 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.068092s" Sep 4 17:40:27.634451 containerd[1436]: time="2024-09-04T17:40:27.634387729Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 4 17:40:27.636339 containerd[1436]: time="2024-09-04T17:40:27.636311127Z" level=info msg="CreateContainer within sandbox \"8ff5c536321ec54a8c0a2129f6eeefa1ea7d3d4b76daa2e26d41d8947c8b0c6b\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 4 17:40:27.649259 containerd[1436]: time="2024-09-04T17:40:27.649210987Z" level=info msg="CreateContainer within sandbox \"8ff5c536321ec54a8c0a2129f6eeefa1ea7d3d4b76daa2e26d41d8947c8b0c6b\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"b0aa652ddbc8c1b2aef11d879992772c760d1780e25497c7b2c39d1a8b45f862\"" Sep 4 17:40:27.650968 containerd[1436]: time="2024-09-04T17:40:27.649879747Z" level=info msg="StartContainer for \"b0aa652ddbc8c1b2aef11d879992772c760d1780e25497c7b2c39d1a8b45f862\"" Sep 4 17:40:27.668982 systemd[1]: run-containerd-runc-k8s.io-b0aa652ddbc8c1b2aef11d879992772c760d1780e25497c7b2c39d1a8b45f862-runc.r6d0gk.mount: Deactivated successfully. Sep 4 17:40:27.682004 systemd[1]: Started cri-containerd-b0aa652ddbc8c1b2aef11d879992772c760d1780e25497c7b2c39d1a8b45f862.scope - libcontainer container b0aa652ddbc8c1b2aef11d879992772c760d1780e25497c7b2c39d1a8b45f862. Sep 4 17:40:27.720768 containerd[1436]: time="2024-09-04T17:40:27.718011185Z" level=info msg="StartContainer for \"b0aa652ddbc8c1b2aef11d879992772c760d1780e25497c7b2c39d1a8b45f862\" returns successfully" Sep 4 17:40:28.016753 kubelet[1693]: E0904 17:40:28.016625 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:29.017394 kubelet[1693]: E0904 17:40:29.017344 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:30.017792 kubelet[1693]: E0904 17:40:30.017757 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:31.018599 kubelet[1693]: E0904 17:40:31.018553 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:32.019035 kubelet[1693]: E0904 17:40:32.018999 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:33.019149 kubelet[1693]: E0904 17:40:33.019096 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:34.019966 kubelet[1693]: E0904 17:40:34.019875 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:35.020898 kubelet[1693]: E0904 17:40:35.020861 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:36.021781 kubelet[1693]: E0904 17:40:36.021735 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:37.022158 kubelet[1693]: E0904 17:40:37.022115 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:37.556334 kubelet[1693]: I0904 17:40:37.556295 1693 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.487493933 podCreationTimestamp="2024-09-04 17:40:24 +0000 UTC" firstStartedPulling="2024-09-04 17:40:24.56584428 +0000 UTC m=+20.268932087" lastFinishedPulling="2024-09-04 17:40:27.634604625 +0000 UTC m=+23.337692472" observedRunningTime="2024-09-04 17:40:28.194249329 +0000 UTC m=+23.897337176" watchObservedRunningTime="2024-09-04 17:40:37.556254318 +0000 UTC m=+33.259342125" Sep 4 17:40:37.556556 kubelet[1693]: I0904 17:40:37.556531 1693 topology_manager.go:215] "Topology Admit Handler" podUID="e7d787fd-335f-4390-8690-1f997c6267b4" podNamespace="default" podName="test-pod-1" Sep 4 17:40:37.561102 systemd[1]: Created slice kubepods-besteffort-pode7d787fd_335f_4390_8690_1f997c6267b4.slice - libcontainer container kubepods-besteffort-pode7d787fd_335f_4390_8690_1f997c6267b4.slice. Sep 4 17:40:37.690106 kubelet[1693]: I0904 17:40:37.690006 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-d40067c2-0bac-427b-95b2-d6197c92d1a4\" (UniqueName: \"kubernetes.io/nfs/e7d787fd-335f-4390-8690-1f997c6267b4-pvc-d40067c2-0bac-427b-95b2-d6197c92d1a4\") pod \"test-pod-1\" (UID: \"e7d787fd-335f-4390-8690-1f997c6267b4\") " pod="default/test-pod-1" Sep 4 17:40:37.690106 kubelet[1693]: I0904 17:40:37.690052 1693 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hfjjf\" (UniqueName: \"kubernetes.io/projected/e7d787fd-335f-4390-8690-1f997c6267b4-kube-api-access-hfjjf\") pod \"test-pod-1\" (UID: \"e7d787fd-335f-4390-8690-1f997c6267b4\") " pod="default/test-pod-1" Sep 4 17:40:37.810826 kernel: FS-Cache: Loaded Sep 4 17:40:37.835943 kernel: RPC: Registered named UNIX socket transport module. Sep 4 17:40:37.836009 kernel: RPC: Registered udp transport module. Sep 4 17:40:37.836025 kernel: RPC: Registered tcp transport module. Sep 4 17:40:37.837002 kernel: RPC: Registered tcp-with-tls transport module. Sep 4 17:40:37.837024 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 4 17:40:38.013254 kernel: NFS: Registering the id_resolver key type Sep 4 17:40:38.013371 kernel: Key type id_resolver registered Sep 4 17:40:38.013390 kernel: Key type id_legacy registered Sep 4 17:40:38.022535 kubelet[1693]: E0904 17:40:38.022479 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:38.035750 nfsidmap[2715]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 17:40:38.038752 nfsidmap[2718]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 17:40:38.165143 containerd[1436]: time="2024-09-04T17:40:38.165065484Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e7d787fd-335f-4390-8690-1f997c6267b4,Namespace:default,Attempt:0,}" Sep 4 17:40:38.181166 systemd-networkd[1375]: vethd080fd8e: Link UP Sep 4 17:40:38.183247 kernel: cni0: port 3(vethd080fd8e) entered blocking state Sep 4 17:40:38.183294 kernel: cni0: port 3(vethd080fd8e) entered disabled state Sep 4 17:40:38.183321 kernel: vethd080fd8e: entered allmulticast mode Sep 4 17:40:38.183945 kernel: vethd080fd8e: entered promiscuous mode Sep 4 17:40:38.189175 kernel: cni0: port 3(vethd080fd8e) entered blocking state Sep 4 17:40:38.189243 kernel: cni0: port 3(vethd080fd8e) entered forwarding state Sep 4 17:40:38.189568 systemd-networkd[1375]: vethd080fd8e: Gained carrier Sep 4 17:40:38.191524 containerd[1436]: 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)(0x400001a938), "name":"cbr0", "type":"bridge"} Sep 4 17:40:38.191524 containerd[1436]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:40:38.207417 containerd[1436]: {"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-09-04T17:40:38.207220295Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:40:38.207417 containerd[1436]: time="2024-09-04T17:40:38.207274576Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:38.207417 containerd[1436]: time="2024-09-04T17:40:38.207287456Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:40:38.207417 containerd[1436]: time="2024-09-04T17:40:38.207296256Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:40:38.226974 systemd[1]: Started cri-containerd-75ba9b47f02e284cdca73b4a6417db5998cc2f0a51af99baa97507a1abf9fe27.scope - libcontainer container 75ba9b47f02e284cdca73b4a6417db5998cc2f0a51af99baa97507a1abf9fe27. Sep 4 17:40:38.238245 systemd-resolved[1307]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 17:40:38.255188 containerd[1436]: time="2024-09-04T17:40:38.255101623Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e7d787fd-335f-4390-8690-1f997c6267b4,Namespace:default,Attempt:0,} returns sandbox id \"75ba9b47f02e284cdca73b4a6417db5998cc2f0a51af99baa97507a1abf9fe27\"" Sep 4 17:40:38.261688 containerd[1436]: time="2024-09-04T17:40:38.261438589Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 17:40:38.532918 containerd[1436]: time="2024-09-04T17:40:38.532654141Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:40:38.533584 containerd[1436]: time="2024-09-04T17:40:38.533540553Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 4 17:40:38.536484 containerd[1436]: time="2024-09-04T17:40:38.536444232Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:337a19c298c7ca0845a051d89520d044d67a9402e4c33db4f541a93279763a1d\", size \"67679064\" in 274.954482ms" Sep 4 17:40:38.536527 containerd[1436]: time="2024-09-04T17:40:38.536489033Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\"" Sep 4 17:40:38.537925 containerd[1436]: time="2024-09-04T17:40:38.537899132Z" level=info msg="CreateContainer within sandbox \"75ba9b47f02e284cdca73b4a6417db5998cc2f0a51af99baa97507a1abf9fe27\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 4 17:40:38.547751 containerd[1436]: time="2024-09-04T17:40:38.547710585Z" level=info msg="CreateContainer within sandbox \"75ba9b47f02e284cdca73b4a6417db5998cc2f0a51af99baa97507a1abf9fe27\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"55164c6fccef4ea17b761a3154c454eff3fa58f5e12343256f04b53d4850aae8\"" Sep 4 17:40:38.548174 containerd[1436]: time="2024-09-04T17:40:38.548144271Z" level=info msg="StartContainer for \"55164c6fccef4ea17b761a3154c454eff3fa58f5e12343256f04b53d4850aae8\"" Sep 4 17:40:38.572001 systemd[1]: Started cri-containerd-55164c6fccef4ea17b761a3154c454eff3fa58f5e12343256f04b53d4850aae8.scope - libcontainer container 55164c6fccef4ea17b761a3154c454eff3fa58f5e12343256f04b53d4850aae8. Sep 4 17:40:38.603257 containerd[1436]: time="2024-09-04T17:40:38.603206176Z" level=info msg="StartContainer for \"55164c6fccef4ea17b761a3154c454eff3fa58f5e12343256f04b53d4850aae8\" returns successfully" Sep 4 17:40:39.024664 kubelet[1693]: E0904 17:40:39.024620 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:39.203095 kubelet[1693]: I0904 17:40:39.203049 1693 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.927415268 podCreationTimestamp="2024-09-04 17:40:24 +0000 UTC" firstStartedPulling="2024-09-04 17:40:38.261139585 +0000 UTC m=+33.964227432" lastFinishedPulling="2024-09-04 17:40:38.536738356 +0000 UTC m=+34.239826203" observedRunningTime="2024-09-04 17:40:39.202850117 +0000 UTC m=+34.905937964" watchObservedRunningTime="2024-09-04 17:40:39.203014039 +0000 UTC m=+34.906101886" Sep 4 17:40:39.523370 systemd-networkd[1375]: vethd080fd8e: Gained IPv6LL Sep 4 17:40:40.028618 kubelet[1693]: E0904 17:40:40.028543 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:41.029730 kubelet[1693]: E0904 17:40:41.029665 1693 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:40:41.063386 update_engine[1420]: I0904 17:40:41.062788 1420 update_attempter.cc:509] Updating boot flags... Sep 4 17:40:41.090355 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 35 scanned by (udev-worker) (2745) Sep 4 17:40:41.118906 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 35 scanned by (udev-worker) (2745)