Sep 4 17:36:03.924019 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 4 17:36:03.924040 kernel: Linux version 6.6.48-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Wed Sep 4 15:58:01 -00 2024 Sep 4 17:36:03.924050 kernel: KASLR enabled Sep 4 17:36:03.924056 kernel: efi: EFI v2.7 by EDK II Sep 4 17:36:03.924062 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb900018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Sep 4 17:36:03.924067 kernel: random: crng init done Sep 4 17:36:03.924074 kernel: ACPI: Early table checksum verification disabled Sep 4 17:36:03.924081 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Sep 4 17:36:03.924087 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 4 17:36:03.924094 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924100 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924106 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924112 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924118 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924126 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924133 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924140 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924146 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:36:03.924153 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 4 17:36:03.924159 kernel: NUMA: Failed to initialise from firmware Sep 4 17:36:03.924166 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 17:36:03.924172 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Sep 4 17:36:03.924178 kernel: Zone ranges: Sep 4 17:36:03.924184 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 17:36:03.924191 kernel: DMA32 empty Sep 4 17:36:03.924198 kernel: Normal empty Sep 4 17:36:03.924205 kernel: Movable zone start for each node Sep 4 17:36:03.924211 kernel: Early memory node ranges Sep 4 17:36:03.924217 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Sep 4 17:36:03.924224 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Sep 4 17:36:03.924230 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Sep 4 17:36:03.924245 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 4 17:36:03.924252 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 4 17:36:03.924259 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 4 17:36:03.924265 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 4 17:36:03.924271 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 17:36:03.924278 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 4 17:36:03.924286 kernel: psci: probing for conduit method from ACPI. Sep 4 17:36:03.924293 kernel: psci: PSCIv1.1 detected in firmware. Sep 4 17:36:03.924300 kernel: psci: Using standard PSCI v0.2 function IDs Sep 4 17:36:03.924309 kernel: psci: Trusted OS migration not required Sep 4 17:36:03.924315 kernel: psci: SMC Calling Convention v1.1 Sep 4 17:36:03.924322 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 4 17:36:03.924331 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 4 17:36:03.924338 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 4 17:36:03.924345 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 4 17:36:03.924352 kernel: Detected PIPT I-cache on CPU0 Sep 4 17:36:03.924358 kernel: CPU features: detected: GIC system register CPU interface Sep 4 17:36:03.924365 kernel: CPU features: detected: Hardware dirty bit management Sep 4 17:36:03.924372 kernel: CPU features: detected: Spectre-v4 Sep 4 17:36:03.924379 kernel: CPU features: detected: Spectre-BHB Sep 4 17:36:03.924385 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 4 17:36:03.924392 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 4 17:36:03.924401 kernel: CPU features: detected: ARM erratum 1418040 Sep 4 17:36:03.924408 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 4 17:36:03.924414 kernel: alternatives: applying boot alternatives Sep 4 17:36:03.924422 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=28a986328b36e7de6a755f88bb335afbeb3e3932bc9a20c5f8e57b952c2d23a9 Sep 4 17:36:03.924429 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 17:36:03.924436 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 17:36:03.924443 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 17:36:03.924450 kernel: Fallback order for Node 0: 0 Sep 4 17:36:03.924457 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Sep 4 17:36:03.924463 kernel: Policy zone: DMA Sep 4 17:36:03.924470 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 17:36:03.924478 kernel: software IO TLB: area num 4. Sep 4 17:36:03.924485 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Sep 4 17:36:03.924493 kernel: Memory: 2386596K/2572288K available (10240K kernel code, 2184K rwdata, 8084K rodata, 39296K init, 897K bss, 185692K reserved, 0K cma-reserved) Sep 4 17:36:03.924509 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 4 17:36:03.924516 kernel: trace event string verifier disabled Sep 4 17:36:03.924523 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 17:36:03.924530 kernel: rcu: RCU event tracing is enabled. Sep 4 17:36:03.924537 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 4 17:36:03.924544 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 17:36:03.924551 kernel: Tracing variant of Tasks RCU enabled. Sep 4 17:36:03.924558 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 17:36:03.924565 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 4 17:36:03.924573 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 4 17:36:03.924580 kernel: GICv3: 256 SPIs implemented Sep 4 17:36:03.924587 kernel: GICv3: 0 Extended SPIs implemented Sep 4 17:36:03.924593 kernel: Root IRQ handler: gic_handle_irq Sep 4 17:36:03.924600 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 4 17:36:03.924607 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 4 17:36:03.924614 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 4 17:36:03.924621 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Sep 4 17:36:03.924629 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Sep 4 17:36:03.924635 kernel: GICv3: using LPI property table @0x00000000400f0000 Sep 4 17:36:03.924642 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Sep 4 17:36:03.924650 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 17:36:03.924670 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:36:03.924677 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 4 17:36:03.924684 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 4 17:36:03.924692 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 4 17:36:03.924699 kernel: arm-pv: using stolen time PV Sep 4 17:36:03.924706 kernel: Console: colour dummy device 80x25 Sep 4 17:36:03.924720 kernel: ACPI: Core revision 20230628 Sep 4 17:36:03.924727 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 4 17:36:03.924734 kernel: pid_max: default: 32768 minimum: 301 Sep 4 17:36:03.924743 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 4 17:36:03.924751 kernel: landlock: Up and running. Sep 4 17:36:03.924758 kernel: SELinux: Initializing. Sep 4 17:36:03.924765 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 17:36:03.924772 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 17:36:03.924779 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:36:03.924787 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:36:03.924794 kernel: rcu: Hierarchical SRCU implementation. Sep 4 17:36:03.924802 kernel: rcu: Max phase no-delay instances is 400. Sep 4 17:36:03.924810 kernel: Platform MSI: ITS@0x8080000 domain created Sep 4 17:36:03.924817 kernel: PCI/MSI: ITS@0x8080000 domain created Sep 4 17:36:03.924824 kernel: Remapping and enabling EFI services. Sep 4 17:36:03.924831 kernel: smp: Bringing up secondary CPUs ... Sep 4 17:36:03.924838 kernel: Detected PIPT I-cache on CPU1 Sep 4 17:36:03.924845 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 4 17:36:03.924852 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Sep 4 17:36:03.924860 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:36:03.924867 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 4 17:36:03.924874 kernel: Detected PIPT I-cache on CPU2 Sep 4 17:36:03.924882 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 4 17:36:03.924890 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Sep 4 17:36:03.924901 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:36:03.924910 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 4 17:36:03.924918 kernel: Detected PIPT I-cache on CPU3 Sep 4 17:36:03.924925 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 4 17:36:03.924933 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Sep 4 17:36:03.924940 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 17:36:03.924947 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 4 17:36:03.924956 kernel: smp: Brought up 1 node, 4 CPUs Sep 4 17:36:03.924963 kernel: SMP: Total of 4 processors activated. Sep 4 17:36:03.924970 kernel: CPU features: detected: 32-bit EL0 Support Sep 4 17:36:03.924978 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 4 17:36:03.924985 kernel: CPU features: detected: Common not Private translations Sep 4 17:36:03.924993 kernel: CPU features: detected: CRC32 instructions Sep 4 17:36:03.925000 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 4 17:36:03.925007 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 4 17:36:03.925016 kernel: CPU features: detected: LSE atomic instructions Sep 4 17:36:03.925024 kernel: CPU features: detected: Privileged Access Never Sep 4 17:36:03.925035 kernel: CPU features: detected: RAS Extension Support Sep 4 17:36:03.925043 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 4 17:36:03.925050 kernel: CPU: All CPU(s) started at EL1 Sep 4 17:36:03.925057 kernel: alternatives: applying system-wide alternatives Sep 4 17:36:03.925065 kernel: devtmpfs: initialized Sep 4 17:36:03.925072 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 17:36:03.925080 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 4 17:36:03.925089 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 17:36:03.925096 kernel: SMBIOS 3.0.0 present. Sep 4 17:36:03.925103 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Sep 4 17:36:03.925111 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 17:36:03.925118 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 4 17:36:03.925126 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 4 17:36:03.925133 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 4 17:36:03.925141 kernel: audit: initializing netlink subsys (disabled) Sep 4 17:36:03.925148 kernel: audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 Sep 4 17:36:03.925156 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 17:36:03.925164 kernel: cpuidle: using governor menu Sep 4 17:36:03.925171 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 4 17:36:03.925179 kernel: ASID allocator initialised with 32768 entries Sep 4 17:36:03.925186 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 17:36:03.925193 kernel: Serial: AMBA PL011 UART driver Sep 4 17:36:03.925201 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 4 17:36:03.925208 kernel: Modules: 0 pages in range for non-PLT usage Sep 4 17:36:03.925215 kernel: Modules: 509056 pages in range for PLT usage Sep 4 17:36:03.925224 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 17:36:03.925231 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 17:36:03.925239 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 4 17:36:03.925246 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 4 17:36:03.925254 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 17:36:03.925261 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 17:36:03.925268 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 4 17:36:03.925275 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 4 17:36:03.925283 kernel: ACPI: Added _OSI(Module Device) Sep 4 17:36:03.925291 kernel: ACPI: Added _OSI(Processor Device) Sep 4 17:36:03.925299 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 4 17:36:03.925306 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 17:36:03.925314 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 17:36:03.925321 kernel: ACPI: Interpreter enabled Sep 4 17:36:03.925329 kernel: ACPI: Using GIC for interrupt routing Sep 4 17:36:03.925336 kernel: ACPI: MCFG table detected, 1 entries Sep 4 17:36:03.925344 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 4 17:36:03.925351 kernel: printk: console [ttyAMA0] enabled Sep 4 17:36:03.925360 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 4 17:36:03.925502 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 4 17:36:03.925588 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 4 17:36:03.925656 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 4 17:36:03.925730 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 4 17:36:03.925798 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 4 17:36:03.925808 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 4 17:36:03.925818 kernel: PCI host bridge to bus 0000:00 Sep 4 17:36:03.925891 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 4 17:36:03.925952 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 4 17:36:03.926012 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 4 17:36:03.926072 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 4 17:36:03.926152 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Sep 4 17:36:03.926228 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Sep 4 17:36:03.926304 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Sep 4 17:36:03.926373 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Sep 4 17:36:03.926440 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Sep 4 17:36:03.926519 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Sep 4 17:36:03.926588 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Sep 4 17:36:03.926654 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Sep 4 17:36:03.926723 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 4 17:36:03.926788 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 4 17:36:03.926849 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 4 17:36:03.926859 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 4 17:36:03.926867 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 4 17:36:03.926875 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 4 17:36:03.926882 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 4 17:36:03.926890 kernel: iommu: Default domain type: Translated Sep 4 17:36:03.926897 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 4 17:36:03.926907 kernel: efivars: Registered efivars operations Sep 4 17:36:03.926914 kernel: vgaarb: loaded Sep 4 17:36:03.926921 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 4 17:36:03.926929 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 17:36:03.926936 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 17:36:03.926944 kernel: pnp: PnP ACPI init Sep 4 17:36:03.927023 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 4 17:36:03.927034 kernel: pnp: PnP ACPI: found 1 devices Sep 4 17:36:03.927043 kernel: NET: Registered PF_INET protocol family Sep 4 17:36:03.927051 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 17:36:03.927058 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 17:36:03.927066 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 17:36:03.927073 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 17:36:03.927081 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 17:36:03.927088 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 17:36:03.927096 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 17:36:03.927103 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 17:36:03.927113 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 17:36:03.927120 kernel: PCI: CLS 0 bytes, default 64 Sep 4 17:36:03.927127 kernel: kvm [1]: HYP mode not available Sep 4 17:36:03.927135 kernel: Initialise system trusted keyrings Sep 4 17:36:03.927142 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 17:36:03.927149 kernel: Key type asymmetric registered Sep 4 17:36:03.927157 kernel: Asymmetric key parser 'x509' registered Sep 4 17:36:03.927164 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 4 17:36:03.927171 kernel: io scheduler mq-deadline registered Sep 4 17:36:03.927180 kernel: io scheduler kyber registered Sep 4 17:36:03.927188 kernel: io scheduler bfq registered Sep 4 17:36:03.927195 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 4 17:36:03.927202 kernel: ACPI: button: Power Button [PWRB] Sep 4 17:36:03.927210 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 4 17:36:03.927278 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 4 17:36:03.927288 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 17:36:03.927296 kernel: thunder_xcv, ver 1.0 Sep 4 17:36:03.927303 kernel: thunder_bgx, ver 1.0 Sep 4 17:36:03.927312 kernel: nicpf, ver 1.0 Sep 4 17:36:03.927319 kernel: nicvf, ver 1.0 Sep 4 17:36:03.927392 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 4 17:36:03.927456 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-09-04T17:36:03 UTC (1725471363) Sep 4 17:36:03.927466 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 17:36:03.927474 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Sep 4 17:36:03.927481 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 4 17:36:03.927489 kernel: watchdog: Hard watchdog permanently disabled Sep 4 17:36:03.927509 kernel: NET: Registered PF_INET6 protocol family Sep 4 17:36:03.927539 kernel: Segment Routing with IPv6 Sep 4 17:36:03.927555 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 17:36:03.927562 kernel: NET: Registered PF_PACKET protocol family Sep 4 17:36:03.927570 kernel: Key type dns_resolver registered Sep 4 17:36:03.927577 kernel: registered taskstats version 1 Sep 4 17:36:03.927585 kernel: Loading compiled-in X.509 certificates Sep 4 17:36:03.927592 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.48-flatcar: 6782952639b29daf968f5d0c3e73fb25e5af1d5e' Sep 4 17:36:03.927599 kernel: Key type .fscrypt registered Sep 4 17:36:03.927609 kernel: Key type fscrypt-provisioning registered Sep 4 17:36:03.927617 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 17:36:03.927624 kernel: ima: Allocated hash algorithm: sha1 Sep 4 17:36:03.927632 kernel: ima: No architecture policies found Sep 4 17:36:03.927639 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 4 17:36:03.927647 kernel: clk: Disabling unused clocks Sep 4 17:36:03.927654 kernel: Freeing unused kernel memory: 39296K Sep 4 17:36:03.927661 kernel: Run /init as init process Sep 4 17:36:03.927669 kernel: with arguments: Sep 4 17:36:03.927677 kernel: /init Sep 4 17:36:03.927685 kernel: with environment: Sep 4 17:36:03.927692 kernel: HOME=/ Sep 4 17:36:03.927699 kernel: TERM=linux Sep 4 17:36:03.927706 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 17:36:03.927722 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:36:03.927731 systemd[1]: Detected virtualization kvm. Sep 4 17:36:03.927739 systemd[1]: Detected architecture arm64. Sep 4 17:36:03.927748 systemd[1]: Running in initrd. Sep 4 17:36:03.927756 systemd[1]: No hostname configured, using default hostname. Sep 4 17:36:03.927764 systemd[1]: Hostname set to . Sep 4 17:36:03.927772 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:36:03.927779 systemd[1]: Queued start job for default target initrd.target. Sep 4 17:36:03.927787 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:36:03.927795 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:36:03.927803 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 17:36:03.927813 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:36:03.927821 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 17:36:03.927829 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 17:36:03.927839 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:36:03.927847 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 17:36:03.927855 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:36:03.927864 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:36:03.927872 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:36:03.927880 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:36:03.927888 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:36:03.927896 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:36:03.927904 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:36:03.927912 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:36:03.927920 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 17:36:03.927928 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 4 17:36:03.927937 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:36:03.927945 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:36:03.927953 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:36:03.927961 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:36:03.927969 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 17:36:03.927977 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:36:03.927985 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 17:36:03.927993 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 17:36:03.928001 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:36:03.928010 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:36:03.928018 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:36:03.928026 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 17:36:03.928034 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:36:03.928042 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 17:36:03.928072 systemd-journald[238]: Collecting audit messages is disabled. Sep 4 17:36:03.928091 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:36:03.928099 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 17:36:03.928109 kernel: Bridge firewalling registered Sep 4 17:36:03.928116 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:36:03.928124 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:36:03.928133 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 17:36:03.928141 systemd-journald[238]: Journal started Sep 4 17:36:03.928160 systemd-journald[238]: Runtime Journal (/run/log/journal/c9813ac07b8b413fb6e6032762a17514) is 5.9M, max 47.3M, 41.4M free. Sep 4 17:36:03.900195 systemd-modules-load[239]: Inserted module 'overlay' Sep 4 17:36:03.923287 systemd-modules-load[239]: Inserted module 'br_netfilter' Sep 4 17:36:03.931221 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:36:03.934957 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:36:03.936520 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:36:03.940746 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:36:03.943003 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 17:36:03.949169 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:36:03.950383 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:36:03.952637 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 17:36:03.960661 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:36:03.961864 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:36:03.964396 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 17:36:03.977905 dracut-cmdline[279]: dracut-dracut-053 Sep 4 17:36:03.980415 dracut-cmdline[279]: 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=28a986328b36e7de6a755f88bb335afbeb3e3932bc9a20c5f8e57b952c2d23a9 Sep 4 17:36:03.988117 systemd-resolved[271]: Positive Trust Anchors: Sep 4 17:36:03.988135 systemd-resolved[271]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:36:03.988167 systemd-resolved[271]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 4 17:36:03.993323 systemd-resolved[271]: Defaulting to hostname 'linux'. Sep 4 17:36:03.994360 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:36:03.996179 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:36:04.052525 kernel: SCSI subsystem initialized Sep 4 17:36:04.059511 kernel: Loading iSCSI transport class v2.0-870. Sep 4 17:36:04.065528 kernel: iscsi: registered transport (tcp) Sep 4 17:36:04.080532 kernel: iscsi: registered transport (qla4xxx) Sep 4 17:36:04.080557 kernel: QLogic iSCSI HBA Driver Sep 4 17:36:04.124334 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 17:36:04.135644 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 17:36:04.155222 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 17:36:04.155275 kernel: device-mapper: uevent: version 1.0.3 Sep 4 17:36:04.155300 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 17:36:04.207532 kernel: raid6: neonx8 gen() 15750 MB/s Sep 4 17:36:04.224532 kernel: raid6: neonx4 gen() 15710 MB/s Sep 4 17:36:04.241526 kernel: raid6: neonx2 gen() 13224 MB/s Sep 4 17:36:04.258519 kernel: raid6: neonx1 gen() 10505 MB/s Sep 4 17:36:04.275523 kernel: raid6: int64x8 gen() 6962 MB/s Sep 4 17:36:04.292517 kernel: raid6: int64x4 gen() 7344 MB/s Sep 4 17:36:04.309519 kernel: raid6: int64x2 gen() 6121 MB/s Sep 4 17:36:04.326523 kernel: raid6: int64x1 gen() 5055 MB/s Sep 4 17:36:04.326569 kernel: raid6: using algorithm neonx8 gen() 15750 MB/s Sep 4 17:36:04.343517 kernel: raid6: .... xor() 11912 MB/s, rmw enabled Sep 4 17:36:04.343576 kernel: raid6: using neon recovery algorithm Sep 4 17:36:04.348508 kernel: xor: measuring software checksum speed Sep 4 17:36:04.349843 kernel: 8regs : 19859 MB/sec Sep 4 17:36:04.349858 kernel: 32regs : 19659 MB/sec Sep 4 17:36:04.350699 kernel: arm64_neon : 27224 MB/sec Sep 4 17:36:04.350717 kernel: xor: using function: arm64_neon (27224 MB/sec) Sep 4 17:36:04.407525 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 17:36:04.419360 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:36:04.428703 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:36:04.440118 systemd-udevd[463]: Using default interface naming scheme 'v255'. Sep 4 17:36:04.443277 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:36:04.446178 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 17:36:04.461719 dracut-pre-trigger[473]: rd.md=0: removing MD RAID activation Sep 4 17:36:04.491437 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:36:04.500679 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:36:04.547994 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:36:04.555261 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 17:36:04.579538 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 17:36:04.580805 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:36:04.582321 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:36:04.584293 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:36:04.592907 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 17:36:04.602427 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:36:04.613186 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 4 17:36:04.613351 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 4 17:36:04.618742 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 4 17:36:04.618781 kernel: GPT:9289727 != 19775487 Sep 4 17:36:04.618792 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 4 17:36:04.618801 kernel: GPT:9289727 != 19775487 Sep 4 17:36:04.619510 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:36:04.620852 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 4 17:36:04.620873 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:36:04.619629 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:36:04.621840 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:36:04.622989 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:36:04.623130 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:36:04.625221 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:36:04.634957 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:36:04.640553 kernel: BTRFS: device fsid 3e706a0f-a579-4862-bc52-e66e95e66d87 devid 1 transid 42 /dev/vda3 scanned by (udev-worker) (518) Sep 4 17:36:04.640591 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (525) Sep 4 17:36:04.651524 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:36:04.656677 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 4 17:36:04.661860 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 4 17:36:04.665654 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 4 17:36:04.666607 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:36:04.671658 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 17:36:04.684672 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 17:36:04.686279 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:36:04.690488 disk-uuid[551]: Primary Header is updated. Sep 4 17:36:04.690488 disk-uuid[551]: Secondary Entries is updated. Sep 4 17:36:04.690488 disk-uuid[551]: Secondary Header is updated. Sep 4 17:36:04.693518 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:36:04.714679 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:36:05.706435 disk-uuid[552]: The operation has completed successfully. Sep 4 17:36:05.707465 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:36:05.729383 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 17:36:05.729474 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 17:36:05.769699 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 17:36:05.772829 sh[575]: Success Sep 4 17:36:05.791521 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 4 17:36:05.831990 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 17:36:05.833425 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 17:36:05.837525 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 17:36:05.845012 kernel: BTRFS info (device dm-0): first mount of filesystem 3e706a0f-a579-4862-bc52-e66e95e66d87 Sep 4 17:36:05.845054 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:36:05.846143 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 17:36:05.846764 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 17:36:05.846777 kernel: BTRFS info (device dm-0): using free space tree Sep 4 17:36:05.850710 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 17:36:05.851812 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 17:36:05.860648 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 17:36:05.861973 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 17:36:05.870000 kernel: BTRFS info (device vda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 17:36:05.870038 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:36:05.870553 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:36:05.874017 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:36:05.880201 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 4 17:36:05.881578 kernel: BTRFS info (device vda6): last unmount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 17:36:05.886332 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 17:36:05.893659 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 17:36:05.954720 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:36:05.964709 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:36:05.994033 systemd-networkd[764]: lo: Link UP Sep 4 17:36:05.994044 systemd-networkd[764]: lo: Gained carrier Sep 4 17:36:05.994780 systemd-networkd[764]: Enumeration completed Sep 4 17:36:05.994930 ignition[667]: Ignition 2.19.0 Sep 4 17:36:05.994877 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:36:05.994936 ignition[667]: Stage: fetch-offline Sep 4 17:36:05.995259 systemd-networkd[764]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:36:05.994975 ignition[667]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:36:05.995262 systemd-networkd[764]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:36:05.994983 ignition[667]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:36:05.996195 systemd-networkd[764]: eth0: Link UP Sep 4 17:36:05.995143 ignition[667]: parsed url from cmdline: "" Sep 4 17:36:05.996198 systemd-networkd[764]: eth0: Gained carrier Sep 4 17:36:05.995146 ignition[667]: no config URL provided Sep 4 17:36:05.996206 systemd-networkd[764]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:36:05.995151 ignition[667]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 17:36:05.997306 systemd[1]: Reached target network.target - Network. Sep 4 17:36:05.995157 ignition[667]: no config at "/usr/lib/ignition/user.ign" Sep 4 17:36:05.995178 ignition[667]: op(1): [started] loading QEMU firmware config module Sep 4 17:36:05.995182 ignition[667]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 4 17:36:06.003743 ignition[667]: op(1): [finished] loading QEMU firmware config module Sep 4 17:36:06.016488 ignition[667]: parsing config with SHA512: 84db2dd2313e973ae83ca23040b4bb3452a91a3209a3a13c11412ba6c226d334f64b3c6608759e9c2311641c8eb7ab2dcbfd27d6bdba794d729a3074dcf1d785 Sep 4 17:36:06.016578 systemd-networkd[764]: eth0: DHCPv4 address 10.0.0.110/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 17:36:06.019410 unknown[667]: fetched base config from "system" Sep 4 17:36:06.019419 unknown[667]: fetched user config from "qemu" Sep 4 17:36:06.019731 ignition[667]: fetch-offline: fetch-offline passed Sep 4 17:36:06.021591 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:36:06.019794 ignition[667]: Ignition finished successfully Sep 4 17:36:06.022850 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 4 17:36:06.030724 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 17:36:06.041022 ignition[772]: Ignition 2.19.0 Sep 4 17:36:06.041032 ignition[772]: Stage: kargs Sep 4 17:36:06.041202 ignition[772]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:36:06.041211 ignition[772]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:36:06.041880 ignition[772]: kargs: kargs passed Sep 4 17:36:06.041925 ignition[772]: Ignition finished successfully Sep 4 17:36:06.046184 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 17:36:06.048267 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 17:36:06.062671 ignition[780]: Ignition 2.19.0 Sep 4 17:36:06.062682 ignition[780]: Stage: disks Sep 4 17:36:06.062868 ignition[780]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:36:06.062879 ignition[780]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:36:06.065001 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 17:36:06.063605 ignition[780]: disks: disks passed Sep 4 17:36:06.065954 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 17:36:06.063650 ignition[780]: Ignition finished successfully Sep 4 17:36:06.067815 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 17:36:06.069219 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:36:06.070447 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:36:06.072337 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:36:06.079663 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 17:36:06.091238 systemd-fsck[790]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 4 17:36:06.097528 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 17:36:06.109629 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 17:36:06.156526 kernel: EXT4-fs (vda9): mounted filesystem 901d46b0-2319-4536-8a6d-46889db73e8c r/w with ordered data mode. Quota mode: none. Sep 4 17:36:06.156541 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 17:36:06.157790 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 17:36:06.167715 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:36:06.170259 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 17:36:06.171741 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 4 17:36:06.175974 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (798) Sep 4 17:36:06.171788 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:36:06.171814 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:36:06.181379 kernel: BTRFS info (device vda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 17:36:06.181403 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:36:06.181413 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:36:06.179230 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 17:36:06.183348 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 17:36:06.185266 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:36:06.185486 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:36:06.241268 initrd-setup-root[822]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 17:36:06.244832 initrd-setup-root[829]: cut: /sysroot/etc/group: No such file or directory Sep 4 17:36:06.248270 initrd-setup-root[836]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 17:36:06.252106 initrd-setup-root[843]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 17:36:06.330709 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 17:36:06.340583 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 17:36:06.341969 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 17:36:06.347518 kernel: BTRFS info (device vda6): last unmount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 17:36:06.365393 ignition[911]: INFO : Ignition 2.19.0 Sep 4 17:36:06.365393 ignition[911]: INFO : Stage: mount Sep 4 17:36:06.367613 ignition[911]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:36:06.367613 ignition[911]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:36:06.367613 ignition[911]: INFO : mount: mount passed Sep 4 17:36:06.367613 ignition[911]: INFO : Ignition finished successfully Sep 4 17:36:06.369252 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 17:36:06.388686 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 17:36:06.389619 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 17:36:06.844464 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 17:36:06.857715 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:36:06.869550 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (924) Sep 4 17:36:06.871869 kernel: BTRFS info (device vda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 17:36:06.871920 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:36:06.871933 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:36:06.874563 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:36:06.876022 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:36:06.897765 ignition[941]: INFO : Ignition 2.19.0 Sep 4 17:36:06.897765 ignition[941]: INFO : Stage: files Sep 4 17:36:06.899548 ignition[941]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:36:06.899548 ignition[941]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:36:06.899548 ignition[941]: DEBUG : files: compiled without relabeling support, skipping Sep 4 17:36:06.902171 ignition[941]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 17:36:06.902171 ignition[941]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 17:36:06.905402 ignition[941]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 17:36:06.906537 ignition[941]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 17:36:06.906537 ignition[941]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 17:36:06.905975 unknown[941]: wrote ssh authorized keys file for user: core Sep 4 17:36:06.909969 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 4 17:36:06.909969 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 4 17:36:06.913438 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:36:06.913438 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:36:06.913438 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Sep 4 17:36:06.913438 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Sep 4 17:36:06.913438 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Sep 4 17:36:06.913438 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1 Sep 4 17:36:07.240017 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 4 17:36:07.464051 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Sep 4 17:36:07.464051 ignition[941]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 4 17:36:07.466982 ignition[941]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 17:36:07.468518 ignition[941]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 17:36:07.468518 ignition[941]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 4 17:36:07.468518 ignition[941]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 4 17:36:07.501527 ignition[941]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 17:36:07.505530 ignition[941]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 17:36:07.507148 ignition[941]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 4 17:36:07.507148 ignition[941]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:36:07.507148 ignition[941]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:36:07.507148 ignition[941]: INFO : files: files passed Sep 4 17:36:07.507148 ignition[941]: INFO : Ignition finished successfully Sep 4 17:36:07.507306 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 17:36:07.519772 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 17:36:07.522670 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 17:36:07.524876 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 17:36:07.524958 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 17:36:07.532290 initrd-setup-root-after-ignition[969]: grep: /sysroot/oem/oem-release: No such file or directory Sep 4 17:36:07.536229 initrd-setup-root-after-ignition[971]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:36:07.536229 initrd-setup-root-after-ignition[971]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:36:07.539040 initrd-setup-root-after-ignition[975]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:36:07.538110 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:36:07.540878 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 17:36:07.542738 systemd-networkd[764]: eth0: Gained IPv6LL Sep 4 17:36:07.550704 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 17:36:07.570945 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 17:36:07.571062 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 17:36:07.572768 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 17:36:07.574299 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 17:36:07.575920 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 17:36:07.576648 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 17:36:07.592253 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:36:07.608002 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 17:36:07.616562 systemd[1]: Stopped target network.target - Network. Sep 4 17:36:07.617851 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:36:07.619305 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:36:07.621441 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 17:36:07.622692 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 17:36:07.622833 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:36:07.624988 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 17:36:07.626687 systemd[1]: Stopped target basic.target - Basic System. Sep 4 17:36:07.627912 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 17:36:07.629361 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:36:07.631313 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 17:36:07.633270 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 17:36:07.634837 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:36:07.636437 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 17:36:07.638160 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 17:36:07.639620 systemd[1]: Stopped target swap.target - Swaps. Sep 4 17:36:07.641046 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 17:36:07.641172 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:36:07.643361 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:36:07.644799 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:36:07.646261 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 17:36:07.646358 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:36:07.648142 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 17:36:07.648253 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 17:36:07.650513 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 17:36:07.650626 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:36:07.652059 systemd[1]: Stopped target paths.target - Path Units. Sep 4 17:36:07.653375 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 17:36:07.656621 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:36:07.657783 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 17:36:07.659627 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 17:36:07.660941 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 17:36:07.661025 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:36:07.662157 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 17:36:07.662231 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:36:07.663348 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 17:36:07.663449 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:36:07.664728 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 17:36:07.664826 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 17:36:07.676732 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 17:36:07.677611 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 17:36:07.677750 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:36:07.679977 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 17:36:07.681897 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 17:36:07.683454 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 17:36:07.684660 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 17:36:07.684786 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:36:07.686343 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 17:36:07.686492 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:36:07.691816 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 17:36:07.696855 ignition[996]: INFO : Ignition 2.19.0 Sep 4 17:36:07.696855 ignition[996]: INFO : Stage: umount Sep 4 17:36:07.696855 ignition[996]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:36:07.696855 ignition[996]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 17:36:07.691916 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 17:36:07.704972 ignition[996]: INFO : umount: umount passed Sep 4 17:36:07.704972 ignition[996]: INFO : Ignition finished successfully Sep 4 17:36:07.692574 systemd-networkd[764]: eth0: DHCPv6 lease lost Sep 4 17:36:07.697586 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 17:36:07.698191 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 17:36:07.699967 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 17:36:07.701692 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 17:36:07.701789 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 17:36:07.704971 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 17:36:07.705067 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 17:36:07.706820 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 17:36:07.706895 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 17:36:07.710125 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 17:36:07.710159 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:36:07.711607 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 17:36:07.711659 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 17:36:07.713270 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 17:36:07.713314 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 17:36:07.714779 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 17:36:07.714818 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 17:36:07.716275 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 17:36:07.716318 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 17:36:07.717829 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 17:36:07.717873 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 17:36:07.730634 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 17:36:07.731290 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 17:36:07.731349 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:36:07.733179 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 17:36:07.733220 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:36:07.734489 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 17:36:07.734542 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 17:36:07.736512 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 17:36:07.736561 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 17:36:07.738832 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:36:07.749936 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 17:36:07.750132 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:36:07.752375 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 17:36:07.752478 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 17:36:07.755238 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 17:36:07.755295 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 17:36:07.756435 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 17:36:07.756472 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:36:07.757859 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 17:36:07.757903 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:36:07.759796 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 17:36:07.759839 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 17:36:07.761738 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:36:07.761791 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:36:07.771976 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 17:36:07.773032 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 17:36:07.773094 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:36:07.774959 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:36:07.775001 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:36:07.777050 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 17:36:07.778524 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 17:36:07.780524 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 17:36:07.782549 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 17:36:07.792821 systemd[1]: Switching root. Sep 4 17:36:07.823560 systemd-journald[238]: Journal stopped Sep 4 17:36:08.560561 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Sep 4 17:36:08.560617 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 17:36:08.560636 kernel: SELinux: policy capability open_perms=1 Sep 4 17:36:08.560647 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 17:36:08.560656 kernel: SELinux: policy capability always_check_network=0 Sep 4 17:36:08.560670 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 17:36:08.560682 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 17:36:08.560699 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 17:36:08.560713 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 17:36:08.560723 kernel: audit: type=1403 audit(1725471367.956:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 17:36:08.560734 systemd[1]: Successfully loaded SELinux policy in 35.102ms. Sep 4 17:36:08.560753 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 10.259ms. Sep 4 17:36:08.560765 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:36:08.560776 systemd[1]: Detected virtualization kvm. Sep 4 17:36:08.560786 systemd[1]: Detected architecture arm64. Sep 4 17:36:08.560797 systemd[1]: Detected first boot. Sep 4 17:36:08.560807 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:36:08.560818 zram_generator::config[1040]: No configuration found. Sep 4 17:36:08.560829 systemd[1]: Populated /etc with preset unit settings. Sep 4 17:36:08.560841 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 17:36:08.560852 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 17:36:08.560865 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 17:36:08.560876 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 17:36:08.560886 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 17:36:08.560898 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 17:36:08.560909 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 17:36:08.560920 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 17:36:08.560932 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 17:36:08.560943 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 17:36:08.560953 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 17:36:08.560963 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:36:08.560974 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:36:08.560985 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 17:36:08.560997 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 17:36:08.561008 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 17:36:08.561018 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:36:08.561029 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 4 17:36:08.561039 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:36:08.561050 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 17:36:08.561060 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 17:36:08.561075 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 17:36:08.561087 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 17:36:08.561098 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:36:08.561109 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:36:08.561119 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:36:08.561129 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:36:08.561140 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 17:36:08.561150 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 17:36:08.561162 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:36:08.561173 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:36:08.561184 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:36:08.561195 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 17:36:08.561205 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 17:36:08.561216 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 17:36:08.561226 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 17:36:08.561237 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 17:36:08.561247 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 17:36:08.561258 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 17:36:08.561269 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:36:08.561282 systemd[1]: Reached target machines.target - Containers. Sep 4 17:36:08.561292 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 17:36:08.561303 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:36:08.561314 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:36:08.561325 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 17:36:08.561340 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:36:08.561351 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:36:08.561361 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:36:08.561374 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 17:36:08.561385 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:36:08.561397 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 17:36:08.561407 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 17:36:08.561418 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 17:36:08.561428 kernel: fuse: init (API version 7.39) Sep 4 17:36:08.561439 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 17:36:08.561449 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 17:36:08.561460 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:36:08.561472 kernel: loop: module loaded Sep 4 17:36:08.561482 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:36:08.561493 kernel: ACPI: bus type drm_connector registered Sep 4 17:36:08.561515 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 17:36:08.561527 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 17:36:08.561537 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:36:08.561565 systemd-journald[1110]: Collecting audit messages is disabled. Sep 4 17:36:08.561586 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 17:36:08.561603 systemd[1]: Stopped verity-setup.service. Sep 4 17:36:08.561614 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 17:36:08.561624 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 17:36:08.561635 systemd-journald[1110]: Journal started Sep 4 17:36:08.561657 systemd-journald[1110]: Runtime Journal (/run/log/journal/c9813ac07b8b413fb6e6032762a17514) is 5.9M, max 47.3M, 41.4M free. Sep 4 17:36:08.361977 systemd[1]: Queued start job for default target multi-user.target. Sep 4 17:36:08.378865 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 4 17:36:08.379233 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 17:36:08.564857 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:36:08.565531 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 17:36:08.566689 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 17:36:08.567934 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 17:36:08.569182 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 17:36:08.572568 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 17:36:08.574249 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:36:08.575892 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 17:36:08.576042 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 17:36:08.577614 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:36:08.577764 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:36:08.579350 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:36:08.579523 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:36:08.580857 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:36:08.580999 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:36:08.583863 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 17:36:08.584012 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 17:36:08.585335 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:36:08.585482 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:36:08.586789 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:36:08.587962 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 17:36:08.589158 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 17:36:08.603103 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 17:36:08.608723 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 17:36:08.610683 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 17:36:08.611812 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 17:36:08.611850 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:36:08.613612 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 4 17:36:08.616633 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 17:36:08.618572 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 17:36:08.619641 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:36:08.621108 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 17:36:08.623241 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 17:36:08.624315 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:36:08.625710 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 17:36:08.627686 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:36:08.630107 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:36:08.632399 systemd-journald[1110]: Time spent on flushing to /var/log/journal/c9813ac07b8b413fb6e6032762a17514 is 25.144ms for 837 entries. Sep 4 17:36:08.632399 systemd-journald[1110]: System Journal (/var/log/journal/c9813ac07b8b413fb6e6032762a17514) is 8.0M, max 195.6M, 187.6M free. Sep 4 17:36:08.668984 systemd-journald[1110]: Received client request to flush runtime journal. Sep 4 17:36:08.669028 kernel: loop0: detected capacity change from 0 to 194096 Sep 4 17:36:08.633948 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 17:36:08.639061 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 17:36:08.643761 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:36:08.645288 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 17:36:08.646486 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 17:36:08.648012 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 17:36:08.654060 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 17:36:08.665518 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 17:36:08.666971 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 17:36:08.670571 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 4 17:36:08.674595 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 17:36:08.683850 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:36:08.686451 udevadm[1158]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 4 17:36:08.688521 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 17:36:08.692921 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 17:36:08.711248 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:36:08.713060 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 17:36:08.713801 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 4 17:36:08.729572 kernel: loop1: detected capacity change from 0 to 114288 Sep 4 17:36:08.744064 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. Sep 4 17:36:08.744082 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. Sep 4 17:36:08.749106 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:36:08.768526 kernel: loop2: detected capacity change from 0 to 65520 Sep 4 17:36:08.809527 kernel: loop3: detected capacity change from 0 to 194096 Sep 4 17:36:08.821535 kernel: loop4: detected capacity change from 0 to 114288 Sep 4 17:36:08.827755 kernel: loop5: detected capacity change from 0 to 65520 Sep 4 17:36:08.831173 (sd-merge)[1175]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 4 17:36:08.831610 (sd-merge)[1175]: Merged extensions into '/usr'. Sep 4 17:36:08.838200 systemd[1]: Reloading requested from client PID 1150 ('systemd-sysext') (unit systemd-sysext.service)... Sep 4 17:36:08.838218 systemd[1]: Reloading... Sep 4 17:36:08.887640 zram_generator::config[1197]: No configuration found. Sep 4 17:36:08.990812 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:36:09.013302 ldconfig[1145]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 17:36:09.026357 systemd[1]: Reloading finished in 187 ms. Sep 4 17:36:09.059548 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 17:36:09.060858 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 17:36:09.073675 systemd[1]: Starting ensure-sysext.service... Sep 4 17:36:09.075449 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 17:36:09.084034 systemd[1]: Reloading requested from client PID 1233 ('systemctl') (unit ensure-sysext.service)... Sep 4 17:36:09.084052 systemd[1]: Reloading... Sep 4 17:36:09.101193 systemd-tmpfiles[1234]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 17:36:09.101460 systemd-tmpfiles[1234]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 17:36:09.102237 systemd-tmpfiles[1234]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 17:36:09.102447 systemd-tmpfiles[1234]: ACLs are not supported, ignoring. Sep 4 17:36:09.102512 systemd-tmpfiles[1234]: ACLs are not supported, ignoring. Sep 4 17:36:09.110205 systemd-tmpfiles[1234]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:36:09.110218 systemd-tmpfiles[1234]: Skipping /boot Sep 4 17:36:09.118018 systemd-tmpfiles[1234]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:36:09.118036 systemd-tmpfiles[1234]: Skipping /boot Sep 4 17:36:09.138787 zram_generator::config[1262]: No configuration found. Sep 4 17:36:09.220244 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:36:09.257735 systemd[1]: Reloading finished in 173 ms. Sep 4 17:36:09.273221 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 17:36:09.288047 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 17:36:09.296601 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 17:36:09.298856 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 17:36:09.301079 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 17:36:09.304789 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:36:09.309814 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:36:09.314791 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 17:36:09.318303 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:36:09.322806 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:36:09.325849 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:36:09.337813 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:36:09.338699 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:36:09.341471 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:36:09.341643 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:36:09.342979 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:36:09.343107 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:36:09.350007 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:36:09.352009 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:36:09.355698 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:36:09.357569 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:36:09.366485 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 17:36:09.368300 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 17:36:09.369896 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:36:09.370045 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:36:09.371444 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:36:09.371602 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:36:09.372942 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:36:09.373062 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:36:09.373714 systemd-udevd[1301]: Using default interface naming scheme 'v255'. Sep 4 17:36:09.381905 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 17:36:09.387003 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:36:09.397970 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:36:09.407631 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:36:09.410855 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:36:09.416753 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:36:09.420171 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:36:09.422831 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 17:36:09.424550 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:36:09.426150 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 17:36:09.429158 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:36:09.429340 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:36:09.433111 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:36:09.434591 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:36:09.435962 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:36:09.436087 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:36:09.438397 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:36:09.438557 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:36:09.441102 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 17:36:09.446998 systemd[1]: Finished ensure-sysext.service. Sep 4 17:36:09.458356 augenrules[1360]: No rules Sep 4 17:36:09.461026 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 17:36:09.475521 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1355) Sep 4 17:36:09.475610 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1355) Sep 4 17:36:09.473637 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 4 17:36:09.476997 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:36:09.477874 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 42 scanned by (udev-worker) (1344) Sep 4 17:36:09.479667 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:36:09.479758 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:36:09.481545 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 4 17:36:09.482443 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:36:09.495535 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 17:36:09.543391 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 17:36:09.548254 systemd-networkd[1371]: lo: Link UP Sep 4 17:36:09.548269 systemd-networkd[1371]: lo: Gained carrier Sep 4 17:36:09.549104 systemd-networkd[1371]: Enumeration completed Sep 4 17:36:09.552441 systemd-resolved[1299]: Positive Trust Anchors: Sep 4 17:36:09.552453 systemd-resolved[1299]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:36:09.552485 systemd-resolved[1299]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 4 17:36:09.555739 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 17:36:09.557301 systemd-networkd[1371]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:36:09.557305 systemd-networkd[1371]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:36:09.560319 systemd-networkd[1371]: eth0: Link UP Sep 4 17:36:09.560326 systemd-networkd[1371]: eth0: Gained carrier Sep 4 17:36:09.560340 systemd-networkd[1371]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:36:09.560446 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:36:09.561602 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 4 17:36:09.563018 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 17:36:09.563168 systemd-resolved[1299]: Defaulting to hostname 'linux'. Sep 4 17:36:09.576151 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 17:36:09.577108 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:36:09.577628 systemd-networkd[1371]: eth0: DHCPv4 address 10.0.0.110/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 17:36:09.577963 systemd[1]: Reached target network.target - Network. Sep 4 17:36:09.578616 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:36:09.579585 systemd-timesyncd[1374]: Network configuration changed, trying to establish connection. Sep 4 17:36:09.583012 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 17:36:09.585491 systemd-timesyncd[1374]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 4 17:36:09.585670 systemd-timesyncd[1374]: Initial clock synchronization to Wed 2024-09-04 17:36:09.404147 UTC. Sep 4 17:36:09.610835 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:36:09.618783 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 17:36:09.621259 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 17:36:09.643909 lvm[1390]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:36:09.663404 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:36:09.681289 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 17:36:09.682517 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:36:09.683351 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:36:09.684237 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 17:36:09.685163 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:36:09.686264 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 17:36:09.687396 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 17:36:09.688620 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 17:36:09.689493 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 17:36:09.689533 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:36:09.690168 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:36:09.691755 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 17:36:09.693788 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 17:36:09.704509 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 17:36:09.706711 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 17:36:09.707982 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 17:36:09.708956 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:36:09.709669 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:36:09.710613 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:36:09.710645 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:36:09.711573 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 17:36:09.713329 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 17:36:09.716663 lvm[1399]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:36:09.717641 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 17:36:09.720458 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 17:36:09.723071 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:36:09.729715 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 17:36:09.732756 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 17:36:09.738612 jq[1402]: false Sep 4 17:36:09.736171 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 17:36:09.746297 extend-filesystems[1403]: Found loop3 Sep 4 17:36:09.746297 extend-filesystems[1403]: Found loop4 Sep 4 17:36:09.746297 extend-filesystems[1403]: Found loop5 Sep 4 17:36:09.746297 extend-filesystems[1403]: Found vda Sep 4 17:36:09.746297 extend-filesystems[1403]: Found vda1 Sep 4 17:36:09.746297 extend-filesystems[1403]: Found vda2 Sep 4 17:36:09.746297 extend-filesystems[1403]: Found vda3 Sep 4 17:36:09.746297 extend-filesystems[1403]: Found usr Sep 4 17:36:09.754824 extend-filesystems[1403]: Found vda4 Sep 4 17:36:09.754824 extend-filesystems[1403]: Found vda6 Sep 4 17:36:09.754824 extend-filesystems[1403]: Found vda7 Sep 4 17:36:09.754824 extend-filesystems[1403]: Found vda9 Sep 4 17:36:09.754824 extend-filesystems[1403]: Checking size of /dev/vda9 Sep 4 17:36:09.748035 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 17:36:09.752551 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 17:36:09.753031 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 17:36:09.756574 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 17:36:09.761815 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 17:36:09.763187 dbus-daemon[1401]: [system] SELinux support is enabled Sep 4 17:36:09.763525 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 17:36:09.766099 jq[1419]: true Sep 4 17:36:09.766451 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 17:36:09.768920 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 17:36:09.769133 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 17:36:09.770128 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 17:36:09.770283 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 17:36:09.771708 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 17:36:09.771883 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 17:36:09.773623 extend-filesystems[1403]: Resized partition /dev/vda9 Sep 4 17:36:09.782088 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:36:09.782128 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 17:36:09.784166 extend-filesystems[1424]: resize2fs 1.47.1 (20-May-2024) Sep 4 17:36:09.785121 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:36:09.785141 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 17:36:09.792985 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 42 scanned by (udev-worker) (1341) Sep 4 17:36:09.794484 jq[1425]: true Sep 4 17:36:09.803965 (ntainerd)[1432]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 17:36:09.807549 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 4 17:36:09.810237 systemd-logind[1408]: Watching system buttons on /dev/input/event0 (Power Button) Sep 4 17:36:09.810448 systemd-logind[1408]: New seat seat0. Sep 4 17:36:09.814394 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 17:36:09.834919 update_engine[1416]: I0904 17:36:09.834715 1416 main.cc:92] Flatcar Update Engine starting Sep 4 17:36:09.840094 update_engine[1416]: I0904 17:36:09.839184 1416 update_check_scheduler.cc:74] Next update check in 10m17s Sep 4 17:36:09.839438 systemd[1]: Started update-engine.service - Update Engine. Sep 4 17:36:09.848787 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 17:36:09.864538 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 4 17:36:09.884028 extend-filesystems[1424]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 4 17:36:09.884028 extend-filesystems[1424]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 4 17:36:09.884028 extend-filesystems[1424]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 4 17:36:09.888616 extend-filesystems[1403]: Resized filesystem in /dev/vda9 Sep 4 17:36:09.885665 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 17:36:09.885889 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 17:36:09.892994 bash[1451]: Updated "/home/core/.ssh/authorized_keys" Sep 4 17:36:09.894290 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 17:36:09.897292 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 17:36:09.899987 locksmithd[1450]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 17:36:10.003556 containerd[1432]: time="2024-09-04T17:36:10.003441180Z" level=info msg="starting containerd" revision=8ccfc03e4e2b73c22899202ae09d0caf906d3863 version=v1.7.20 Sep 4 17:36:10.028836 containerd[1432]: time="2024-09-04T17:36:10.028780816Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:36:10.030312 containerd[1432]: time="2024-09-04T17:36:10.030262099Z" 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:36:10.030312 containerd[1432]: time="2024-09-04T17:36:10.030300414Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 17:36:10.030312 containerd[1432]: time="2024-09-04T17:36:10.030316287Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 17:36:10.030542 containerd[1432]: time="2024-09-04T17:36:10.030513025Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 17:36:10.030573 containerd[1432]: time="2024-09-04T17:36:10.030543404Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 17:36:10.030630 containerd[1432]: time="2024-09-04T17:36:10.030606664Z" 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:36:10.030630 containerd[1432]: time="2024-09-04T17:36:10.030623671Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:36:10.030807 containerd[1432]: time="2024-09-04T17:36:10.030781976Z" 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:36:10.030807 containerd[1432]: time="2024-09-04T17:36:10.030802933Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 17:36:10.030858 containerd[1432]: time="2024-09-04T17:36:10.030815835Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:36:10.030858 containerd[1432]: time="2024-09-04T17:36:10.030825687Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 17:36:10.030912 containerd[1432]: time="2024-09-04T17:36:10.030896493Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:36:10.031131 containerd[1432]: time="2024-09-04T17:36:10.031105273Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:36:10.031227 containerd[1432]: time="2024-09-04T17:36:10.031210445Z" 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:36:10.031248 containerd[1432]: time="2024-09-04T17:36:10.031228234Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 17:36:10.031322 containerd[1432]: time="2024-09-04T17:36:10.031304435Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 17:36:10.031364 containerd[1432]: time="2024-09-04T17:36:10.031351899Z" level=info msg="metadata content store policy set" policy=shared Sep 4 17:36:10.037862 containerd[1432]: time="2024-09-04T17:36:10.037811489Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 17:36:10.037981 containerd[1432]: time="2024-09-04T17:36:10.037883390Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 17:36:10.037981 containerd[1432]: time="2024-09-04T17:36:10.037904111Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 17:36:10.037981 containerd[1432]: time="2024-09-04T17:36:10.037920024Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 17:36:10.037981 containerd[1432]: time="2024-09-04T17:36:10.037933512Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 17:36:10.038119 containerd[1432]: time="2024-09-04T17:36:10.038096040Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 17:36:10.038362 containerd[1432]: time="2024-09-04T17:36:10.038346498Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 17:36:10.038465 containerd[1432]: time="2024-09-04T17:36:10.038449246Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 17:36:10.038490 containerd[1432]: time="2024-09-04T17:36:10.038470632Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 17:36:10.038531 containerd[1432]: time="2024-09-04T17:36:10.038492839Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 17:36:10.038551 containerd[1432]: time="2024-09-04T17:36:10.038535182Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038575 containerd[1432]: time="2024-09-04T17:36:10.038549218Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038575 containerd[1432]: time="2024-09-04T17:36:10.038564075Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038608 containerd[1432]: time="2024-09-04T17:36:10.038578345Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038608 containerd[1432]: time="2024-09-04T17:36:10.038593007Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038608 containerd[1432]: time="2024-09-04T17:36:10.038605831Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038656 containerd[1432]: time="2024-09-04T17:36:10.038618303Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038656 containerd[1432]: time="2024-09-04T17:36:10.038630032Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 17:36:10.038656 containerd[1432]: time="2024-09-04T17:36:10.038649620Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038706 containerd[1432]: time="2024-09-04T17:36:10.038661975Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038706 containerd[1432]: time="2024-09-04T17:36:10.038674720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038706 containerd[1432]: time="2024-09-04T17:36:10.038687388Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038706 containerd[1432]: time="2024-09-04T17:36:10.038699899Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038775 containerd[1432]: time="2024-09-04T17:36:10.038712840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038775 containerd[1432]: time="2024-09-04T17:36:10.038725469Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038775 containerd[1432]: time="2024-09-04T17:36:10.038744939Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038775 containerd[1432]: time="2024-09-04T17:36:10.038760696Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038854 containerd[1432]: time="2024-09-04T17:36:10.038777390Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038854 containerd[1432]: time="2024-09-04T17:36:10.038789276Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038854 containerd[1432]: time="2024-09-04T17:36:10.038801044Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038854 containerd[1432]: time="2024-09-04T17:36:10.038823056Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038854 containerd[1432]: time="2024-09-04T17:36:10.038839555Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 17:36:10.038938 containerd[1432]: time="2024-09-04T17:36:10.038859456Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038938 containerd[1432]: time="2024-09-04T17:36:10.038871068Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.038938 containerd[1432]: time="2024-09-04T17:36:10.038883149Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 17:36:10.039047 containerd[1432]: time="2024-09-04T17:36:10.039033634Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 17:36:10.039069 containerd[1432]: time="2024-09-04T17:36:10.039053809Z" 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:36:10.039069 containerd[1432]: time="2024-09-04T17:36:10.039065303Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 17:36:10.039105 containerd[1432]: time="2024-09-04T17:36:10.039078166Z" 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:36:10.039105 containerd[1432]: time="2024-09-04T17:36:10.039088136Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.039105 containerd[1432]: time="2024-09-04T17:36:10.039100686Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 17:36:10.039161 containerd[1432]: time="2024-09-04T17:36:10.039110734Z" level=info msg="NRI interface is disabled by configuration." Sep 4 17:36:10.039161 containerd[1432]: time="2024-09-04T17:36:10.039120939Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 17:36:10.039530 containerd[1432]: time="2024-09-04T17:36:10.039463979Z" 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:36:10.039645 containerd[1432]: time="2024-09-04T17:36:10.039545263Z" level=info msg="Connect containerd service" Sep 4 17:36:10.039645 containerd[1432]: time="2024-09-04T17:36:10.039575993Z" level=info msg="using legacy CRI server" Sep 4 17:36:10.039645 containerd[1432]: time="2024-09-04T17:36:10.039582523Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 17:36:10.039700 containerd[1432]: time="2024-09-04T17:36:10.039667090Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 17:36:10.040361 containerd[1432]: time="2024-09-04T17:36:10.040322558Z" 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:36:10.040751 containerd[1432]: time="2024-09-04T17:36:10.040555930Z" level=info msg="Start subscribing containerd event" Sep 4 17:36:10.040751 containerd[1432]: time="2024-09-04T17:36:10.040697971Z" level=info msg="Start recovering state" Sep 4 17:36:10.040847 containerd[1432]: time="2024-09-04T17:36:10.040828517Z" level=info msg="Start event monitor" Sep 4 17:36:10.040878 containerd[1432]: time="2024-09-04T17:36:10.040850255Z" level=info msg="Start snapshots syncer" Sep 4 17:36:10.040878 containerd[1432]: time="2024-09-04T17:36:10.040860616Z" level=info msg="Start cni network conf syncer for default" Sep 4 17:36:10.040878 containerd[1432]: time="2024-09-04T17:36:10.040869686Z" level=info msg="Start streaming server" Sep 4 17:36:10.041002 containerd[1432]: time="2024-09-04T17:36:10.040831019Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 17:36:10.041002 containerd[1432]: time="2024-09-04T17:36:10.040997339Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 17:36:10.041054 containerd[1432]: time="2024-09-04T17:36:10.041047032Z" level=info msg="containerd successfully booted in 0.039140s" Sep 4 17:36:10.041140 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 17:36:10.299502 sshd_keygen[1420]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 17:36:10.320065 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 17:36:10.330897 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 17:36:10.337838 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 17:36:10.338060 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 17:36:10.340795 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 17:36:10.356242 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 17:36:10.369869 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 17:36:10.372525 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 4 17:36:10.373520 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 17:36:11.509696 systemd-networkd[1371]: eth0: Gained IPv6LL Sep 4 17:36:11.512091 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 17:36:11.514095 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 17:36:11.525051 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 17:36:11.530039 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:36:11.534026 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 17:36:11.552160 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 17:36:11.552646 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 17:36:11.554275 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 17:36:11.555270 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 17:36:12.065279 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:36:12.066838 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 17:36:12.069789 (kubelet)[1505]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 4 17:36:12.072614 systemd[1]: Startup finished in 572ms (kernel) + 4.244s (initrd) + 4.160s (userspace) = 8.978s. Sep 4 17:36:12.558379 kubelet[1505]: E0904 17:36:12.558268 1505 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:36:12.560867 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 4 17:36:12.561024 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 4 17:36:16.489480 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 17:36:16.490723 systemd[1]: Started sshd@0-10.0.0.110:22-10.0.0.1:33936.service - OpenSSH per-connection server daemon (10.0.0.1:33936). Sep 4 17:36:16.552594 sshd[1519]: Accepted publickey for core from 10.0.0.1 port 33936 ssh2: RSA SHA256:bZG4GDjjdFyRf+7zZQ8+tZsxmoYB2474ukIYHB/jTWk Sep 4 17:36:16.555147 sshd[1519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:36:16.564328 systemd-logind[1408]: New session 1 of user core. Sep 4 17:36:16.565357 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 17:36:16.578775 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 17:36:16.588906 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 17:36:16.591156 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 17:36:16.597667 (systemd)[1523]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:36:16.682344 systemd[1523]: Queued start job for default target default.target. Sep 4 17:36:16.690533 systemd[1523]: Created slice app.slice - User Application Slice. Sep 4 17:36:16.690579 systemd[1523]: Reached target paths.target - Paths. Sep 4 17:36:16.690593 systemd[1523]: Reached target timers.target - Timers. Sep 4 17:36:16.691921 systemd[1523]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 17:36:16.702457 systemd[1523]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 17:36:16.702545 systemd[1523]: Reached target sockets.target - Sockets. Sep 4 17:36:16.702559 systemd[1523]: Reached target basic.target - Basic System. Sep 4 17:36:16.702599 systemd[1523]: Reached target default.target - Main User Target. Sep 4 17:36:16.702626 systemd[1523]: Startup finished in 99ms. Sep 4 17:36:16.702958 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 17:36:16.704590 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 17:36:16.763103 systemd[1]: Started sshd@1-10.0.0.110:22-10.0.0.1:33948.service - OpenSSH per-connection server daemon (10.0.0.1:33948). Sep 4 17:36:16.802665 sshd[1534]: Accepted publickey for core from 10.0.0.1 port 33948 ssh2: RSA SHA256:bZG4GDjjdFyRf+7zZQ8+tZsxmoYB2474ukIYHB/jTWk Sep 4 17:36:16.803628 sshd[1534]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:36:16.811281 systemd-logind[1408]: New session 2 of user core. Sep 4 17:36:16.819677 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 17:36:16.875999 sshd[1534]: pam_unix(sshd:session): session closed for user core Sep 4 17:36:16.890069 systemd[1]: sshd@1-10.0.0.110:22-10.0.0.1:33948.service: Deactivated successfully. Sep 4 17:36:16.891549 systemd[1]: session-2.scope: Deactivated successfully. Sep 4 17:36:16.894672 systemd-logind[1408]: Session 2 logged out. Waiting for processes to exit. Sep 4 17:36:16.896167 systemd[1]: Started sshd@2-10.0.0.110:22-10.0.0.1:33952.service - OpenSSH per-connection server daemon (10.0.0.1:33952). Sep 4 17:36:16.906116 systemd-logind[1408]: Removed session 2. Sep 4 17:36:16.946921 sshd[1541]: Accepted publickey for core from 10.0.0.1 port 33952 ssh2: RSA SHA256:bZG4GDjjdFyRf+7zZQ8+tZsxmoYB2474ukIYHB/jTWk Sep 4 17:36:16.948323 sshd[1541]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:36:16.954733 systemd-logind[1408]: New session 3 of user core. Sep 4 17:36:16.969694 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 17:36:17.018457 sshd[1541]: pam_unix(sshd:session): session closed for user core Sep 4 17:36:17.037255 systemd[1]: sshd@2-10.0.0.110:22-10.0.0.1:33952.service: Deactivated successfully. Sep 4 17:36:17.038677 systemd[1]: session-3.scope: Deactivated successfully. Sep 4 17:36:17.041705 systemd-logind[1408]: Session 3 logged out. Waiting for processes to exit. Sep 4 17:36:17.046831 systemd[1]: Started sshd@3-10.0.0.110:22-10.0.0.1:33958.service - OpenSSH per-connection server daemon (10.0.0.1:33958). Sep 4 17:36:17.048229 systemd-logind[1408]: Removed session 3. Sep 4 17:36:17.081402 sshd[1548]: Accepted publickey for core from 10.0.0.1 port 33958 ssh2: RSA SHA256:bZG4GDjjdFyRf+7zZQ8+tZsxmoYB2474ukIYHB/jTWk Sep 4 17:36:17.082707 sshd[1548]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:36:17.087556 systemd-logind[1408]: New session 4 of user core. Sep 4 17:36:17.094710 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 17:36:17.147729 sshd[1548]: pam_unix(sshd:session): session closed for user core Sep 4 17:36:17.156790 systemd[1]: sshd@3-10.0.0.110:22-10.0.0.1:33958.service: Deactivated successfully. Sep 4 17:36:17.158201 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 17:36:17.159327 systemd-logind[1408]: Session 4 logged out. Waiting for processes to exit. Sep 4 17:36:17.160424 systemd[1]: Started sshd@4-10.0.0.110:22-10.0.0.1:33974.service - OpenSSH per-connection server daemon (10.0.0.1:33974). Sep 4 17:36:17.161334 systemd-logind[1408]: Removed session 4. Sep 4 17:36:17.200290 sshd[1555]: Accepted publickey for core from 10.0.0.1 port 33974 ssh2: RSA SHA256:bZG4GDjjdFyRf+7zZQ8+tZsxmoYB2474ukIYHB/jTWk Sep 4 17:36:17.201696 sshd[1555]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:36:17.207542 systemd-logind[1408]: New session 5 of user core. Sep 4 17:36:17.218666 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 17:36:17.290245 sudo[1558]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 4 17:36:17.292457 sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 17:36:17.320121 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 17:36:17.337157 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 17:36:17.337353 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 17:36:17.970928 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:36:17.988961 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:36:18.008166 systemd[1]: Reloading requested from client PID 1608 ('systemctl') (unit session-5.scope)... Sep 4 17:36:18.008185 systemd[1]: Reloading... Sep 4 17:36:18.086581 zram_generator::config[1644]: No configuration found. Sep 4 17:36:18.269997 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:36:18.323747 systemd[1]: Reloading finished in 315 ms. Sep 4 17:36:18.363299 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:36:18.367790 systemd[1]: kubelet.service: Deactivated successfully. Sep 4 17:36:18.368581 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:36:18.370189 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:36:18.500898 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:36:18.506774 (kubelet)[1691]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 4 17:36:18.548957 kubelet[1691]: 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:36:18.548957 kubelet[1691]: 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:36:18.548957 kubelet[1691]: 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:36:18.548957 kubelet[1691]: I0904 17:36:18.548925 1691 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 4 17:36:19.710308 kubelet[1691]: I0904 17:36:19.710248 1691 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Sep 4 17:36:19.710308 kubelet[1691]: I0904 17:36:19.710292 1691 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 4 17:36:19.710820 kubelet[1691]: I0904 17:36:19.710516 1691 server.go:927] "Client rotation is on, will bootstrap in background" Sep 4 17:36:19.759722 kubelet[1691]: I0904 17:36:19.759677 1691 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 4 17:36:19.778582 kubelet[1691]: I0904 17:36:19.778440 1691 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 4 17:36:19.778910 kubelet[1691]: I0904 17:36:19.778862 1691 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 4 17:36:19.779269 kubelet[1691]: I0904 17:36:19.778893 1691 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.110","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Sep 4 17:36:19.779466 kubelet[1691]: I0904 17:36:19.779437 1691 topology_manager.go:138] "Creating topology manager with none policy" Sep 4 17:36:19.779466 kubelet[1691]: I0904 17:36:19.779460 1691 container_manager_linux.go:301] "Creating device plugin manager" Sep 4 17:36:19.780019 kubelet[1691]: I0904 17:36:19.779743 1691 state_mem.go:36] "Initialized new in-memory state store" Sep 4 17:36:19.780851 kubelet[1691]: I0904 17:36:19.780822 1691 kubelet.go:400] "Attempting to sync node with API server" Sep 4 17:36:19.780851 kubelet[1691]: I0904 17:36:19.780848 1691 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 4 17:36:19.781648 kubelet[1691]: I0904 17:36:19.781014 1691 kubelet.go:312] "Adding apiserver pod source" Sep 4 17:36:19.781648 kubelet[1691]: I0904 17:36:19.781203 1691 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 4 17:36:19.781648 kubelet[1691]: E0904 17:36:19.781429 1691 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:19.781648 kubelet[1691]: E0904 17:36:19.781603 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:19.782847 kubelet[1691]: I0904 17:36:19.782826 1691 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.20" apiVersion="v1" Sep 4 17:36:19.783361 kubelet[1691]: I0904 17:36:19.783334 1691 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 4 17:36:19.783450 kubelet[1691]: W0904 17:36:19.783437 1691 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 4 17:36:19.784294 kubelet[1691]: I0904 17:36:19.784271 1691 server.go:1264] "Started kubelet" Sep 4 17:36:19.787168 kubelet[1691]: I0904 17:36:19.787117 1691 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Sep 4 17:36:19.788753 kubelet[1691]: I0904 17:36:19.788587 1691 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 4 17:36:19.789296 kubelet[1691]: I0904 17:36:19.789254 1691 server.go:455] "Adding debug handlers to kubelet server" Sep 4 17:36:19.790334 kubelet[1691]: I0904 17:36:19.787175 1691 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 4 17:36:19.792915 kubelet[1691]: I0904 17:36:19.790406 1691 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 4 17:36:19.792915 kubelet[1691]: E0904 17:36:19.792816 1691 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 4 17:36:19.792915 kubelet[1691]: E0904 17:36:19.792866 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:19.793030 kubelet[1691]: I0904 17:36:19.792957 1691 volume_manager.go:291] "Starting Kubelet Volume Manager" Sep 4 17:36:19.793271 kubelet[1691]: I0904 17:36:19.793231 1691 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Sep 4 17:36:19.793957 kubelet[1691]: I0904 17:36:19.793924 1691 factory.go:221] Registration of the systemd container factory successfully Sep 4 17:36:19.794131 kubelet[1691]: I0904 17:36:19.794107 1691 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 4 17:36:19.794371 kubelet[1691]: I0904 17:36:19.794341 1691 reconciler.go:26] "Reconciler: start to sync state" Sep 4 17:36:19.795687 kubelet[1691]: I0904 17:36:19.795656 1691 factory.go:221] Registration of the containerd container factory successfully Sep 4 17:36:19.808705 kubelet[1691]: W0904 17:36:19.808672 1691 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Sep 4 17:36:19.809736 kubelet[1691]: E0904 17:36:19.808835 1691 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Sep 4 17:36:19.809736 kubelet[1691]: W0904 17:36:19.809012 1691 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.110" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Sep 4 17:36:19.809736 kubelet[1691]: E0904 17:36:19.809074 1691 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.110" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Sep 4 17:36:19.809736 kubelet[1691]: E0904 17:36:19.809163 1691 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.110\" 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:36:19.809736 kubelet[1691]: W0904 17:36:19.809356 1691 reflector.go:547] k8s.io/client-go/informers/factory.go:160: 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:36:19.809736 kubelet[1691]: E0904 17:36:19.809375 1691 reflector.go:150] k8s.io/client-go/informers/factory.go:160: 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:36:19.811207 kubelet[1691]: E0904 17:36:19.808950 1691 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.110.17f21b13f6247d98 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.110,UID:10.0.0.110,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.110,},FirstTimestamp:2024-09-04 17:36:19.78423644 +0000 UTC m=+1.273986454,LastTimestamp:2024-09-04 17:36:19.78423644 +0000 UTC m=+1.273986454,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.110,}" Sep 4 17:36:19.811999 kubelet[1691]: I0904 17:36:19.811969 1691 cpu_manager.go:214] "Starting CPU manager" policy="none" Sep 4 17:36:19.811999 kubelet[1691]: I0904 17:36:19.811989 1691 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Sep 4 17:36:19.812160 kubelet[1691]: I0904 17:36:19.812007 1691 state_mem.go:36] "Initialized new in-memory state store" Sep 4 17:36:19.813985 kubelet[1691]: E0904 17:36:19.813873 1691 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.110.17f21b13f6a73306 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.110,UID:10.0.0.110,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.110,},FirstTimestamp:2024-09-04 17:36:19.792802566 +0000 UTC m=+1.282552580,LastTimestamp:2024-09-04 17:36:19.792802566 +0000 UTC m=+1.282552580,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.110,}" Sep 4 17:36:19.817140 kubelet[1691]: I0904 17:36:19.817101 1691 policy_none.go:49] "None policy: Start" Sep 4 17:36:19.817851 kubelet[1691]: I0904 17:36:19.817804 1691 memory_manager.go:170] "Starting memorymanager" policy="None" Sep 4 17:36:19.817851 kubelet[1691]: I0904 17:36:19.817853 1691 state_mem.go:35] "Initializing new in-memory state store" Sep 4 17:36:19.827643 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 4 17:36:19.842568 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 4 17:36:19.851964 kubelet[1691]: I0904 17:36:19.851879 1691 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 4 17:36:19.852518 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 4 17:36:19.852956 kubelet[1691]: I0904 17:36:19.852932 1691 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 4 17:36:19.853031 kubelet[1691]: I0904 17:36:19.853023 1691 status_manager.go:217] "Starting to sync pod status with apiserver" Sep 4 17:36:19.853057 kubelet[1691]: I0904 17:36:19.853040 1691 kubelet.go:2337] "Starting kubelet main sync loop" Sep 4 17:36:19.853161 kubelet[1691]: E0904 17:36:19.853083 1691 kubelet.go:2361] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Sep 4 17:36:19.855159 kubelet[1691]: I0904 17:36:19.854974 1691 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 4 17:36:19.855237 kubelet[1691]: I0904 17:36:19.855167 1691 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 4 17:36:19.855347 kubelet[1691]: I0904 17:36:19.855272 1691 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 4 17:36:19.856934 kubelet[1691]: E0904 17:36:19.856894 1691 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.110\" not found" Sep 4 17:36:19.893970 kubelet[1691]: I0904 17:36:19.893911 1691 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.110" Sep 4 17:36:19.900299 kubelet[1691]: I0904 17:36:19.900256 1691 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.110" Sep 4 17:36:19.916812 kubelet[1691]: E0904 17:36:19.916763 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.017442 kubelet[1691]: E0904 17:36:20.017305 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.024046 sudo[1558]: pam_unix(sudo:session): session closed for user root Sep 4 17:36:20.026724 sshd[1555]: pam_unix(sshd:session): session closed for user core Sep 4 17:36:20.030171 systemd[1]: sshd@4-10.0.0.110:22-10.0.0.1:33974.service: Deactivated successfully. Sep 4 17:36:20.032687 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 17:36:20.033758 systemd-logind[1408]: Session 5 logged out. Waiting for processes to exit. Sep 4 17:36:20.035261 systemd-logind[1408]: Removed session 5. Sep 4 17:36:20.118152 kubelet[1691]: E0904 17:36:20.118104 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.218608 kubelet[1691]: E0904 17:36:20.218555 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.319436 kubelet[1691]: E0904 17:36:20.319325 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.419890 kubelet[1691]: E0904 17:36:20.419849 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.520427 kubelet[1691]: E0904 17:36:20.520379 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.621166 kubelet[1691]: E0904 17:36:20.621048 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.712732 kubelet[1691]: I0904 17:36:20.712682 1691 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 4 17:36:20.713245 kubelet[1691]: W0904 17:36:20.712877 1691 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 4 17:36:20.722945 kubelet[1691]: E0904 17:36:20.722904 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.782361 kubelet[1691]: E0904 17:36:20.782314 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:20.823610 kubelet[1691]: E0904 17:36:20.823571 1691 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.110\" not found" Sep 4 17:36:20.925164 kubelet[1691]: I0904 17:36:20.924708 1691 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 4 17:36:20.925264 containerd[1432]: time="2024-09-04T17:36:20.925040440Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 4 17:36:20.925570 kubelet[1691]: I0904 17:36:20.925429 1691 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 4 17:36:21.782521 kubelet[1691]: E0904 17:36:21.782478 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:21.782962 kubelet[1691]: I0904 17:36:21.782579 1691 apiserver.go:52] "Watching apiserver" Sep 4 17:36:21.786510 kubelet[1691]: I0904 17:36:21.786359 1691 topology_manager.go:215] "Topology Admit Handler" podUID="c4aeab79-7dff-4da9-8c66-a5e7145dc1db" podNamespace="kube-flannel" podName="kube-flannel-ds-qhkw6" Sep 4 17:36:21.786510 kubelet[1691]: I0904 17:36:21.786485 1691 topology_manager.go:215] "Topology Admit Handler" podUID="96bbe2eb-c02e-433c-ae07-39641df0fd9e" podNamespace="kube-system" podName="kube-proxy-nnsm2" Sep 4 17:36:21.793324 systemd[1]: Created slice kubepods-besteffort-pod96bbe2eb_c02e_433c_ae07_39641df0fd9e.slice - libcontainer container kubepods-besteffort-pod96bbe2eb_c02e_433c_ae07_39641df0fd9e.slice. Sep 4 17:36:21.794796 kubelet[1691]: I0904 17:36:21.794779 1691 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Sep 4 17:36:21.806179 kubelet[1691]: I0904 17:36:21.806141 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/96bbe2eb-c02e-433c-ae07-39641df0fd9e-xtables-lock\") pod \"kube-proxy-nnsm2\" (UID: \"96bbe2eb-c02e-433c-ae07-39641df0fd9e\") " pod="kube-system/kube-proxy-nnsm2" Sep 4 17:36:21.806179 kubelet[1691]: I0904 17:36:21.806179 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fzfld\" (UniqueName: \"kubernetes.io/projected/96bbe2eb-c02e-433c-ae07-39641df0fd9e-kube-api-access-fzfld\") pod \"kube-proxy-nnsm2\" (UID: \"96bbe2eb-c02e-433c-ae07-39641df0fd9e\") " pod="kube-system/kube-proxy-nnsm2" Sep 4 17:36:21.806325 kubelet[1691]: I0904 17:36:21.806200 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/c4aeab79-7dff-4da9-8c66-a5e7145dc1db-run\") pod \"kube-flannel-ds-qhkw6\" (UID: \"c4aeab79-7dff-4da9-8c66-a5e7145dc1db\") " pod="kube-flannel/kube-flannel-ds-qhkw6" Sep 4 17:36:21.806325 kubelet[1691]: I0904 17:36:21.806216 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/c4aeab79-7dff-4da9-8c66-a5e7145dc1db-cni\") pod \"kube-flannel-ds-qhkw6\" (UID: \"c4aeab79-7dff-4da9-8c66-a5e7145dc1db\") " pod="kube-flannel/kube-flannel-ds-qhkw6" Sep 4 17:36:21.806325 kubelet[1691]: I0904 17:36:21.806233 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/c4aeab79-7dff-4da9-8c66-a5e7145dc1db-flannel-cfg\") pod \"kube-flannel-ds-qhkw6\" (UID: \"c4aeab79-7dff-4da9-8c66-a5e7145dc1db\") " pod="kube-flannel/kube-flannel-ds-qhkw6" Sep 4 17:36:21.806325 kubelet[1691]: I0904 17:36:21.806247 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/96bbe2eb-c02e-433c-ae07-39641df0fd9e-kube-proxy\") pod \"kube-proxy-nnsm2\" (UID: \"96bbe2eb-c02e-433c-ae07-39641df0fd9e\") " pod="kube-system/kube-proxy-nnsm2" Sep 4 17:36:21.806325 kubelet[1691]: I0904 17:36:21.806261 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/96bbe2eb-c02e-433c-ae07-39641df0fd9e-lib-modules\") pod \"kube-proxy-nnsm2\" (UID: \"96bbe2eb-c02e-433c-ae07-39641df0fd9e\") " pod="kube-system/kube-proxy-nnsm2" Sep 4 17:36:21.806429 kubelet[1691]: I0904 17:36:21.806275 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/c4aeab79-7dff-4da9-8c66-a5e7145dc1db-cni-plugin\") pod \"kube-flannel-ds-qhkw6\" (UID: \"c4aeab79-7dff-4da9-8c66-a5e7145dc1db\") " pod="kube-flannel/kube-flannel-ds-qhkw6" Sep 4 17:36:21.806429 kubelet[1691]: I0904 17:36:21.806293 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c4aeab79-7dff-4da9-8c66-a5e7145dc1db-xtables-lock\") pod \"kube-flannel-ds-qhkw6\" (UID: \"c4aeab79-7dff-4da9-8c66-a5e7145dc1db\") " pod="kube-flannel/kube-flannel-ds-qhkw6" Sep 4 17:36:21.806429 kubelet[1691]: I0904 17:36:21.806307 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nbkwc\" (UniqueName: \"kubernetes.io/projected/c4aeab79-7dff-4da9-8c66-a5e7145dc1db-kube-api-access-nbkwc\") pod \"kube-flannel-ds-qhkw6\" (UID: \"c4aeab79-7dff-4da9-8c66-a5e7145dc1db\") " pod="kube-flannel/kube-flannel-ds-qhkw6" Sep 4 17:36:21.819403 systemd[1]: Created slice kubepods-burstable-podc4aeab79_7dff_4da9_8c66_a5e7145dc1db.slice - libcontainer container kubepods-burstable-podc4aeab79_7dff_4da9_8c66_a5e7145dc1db.slice. Sep 4 17:36:22.118771 kubelet[1691]: E0904 17:36:22.118645 1691 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:36:22.120027 containerd[1432]: time="2024-09-04T17:36:22.119974785Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-nnsm2,Uid:96bbe2eb-c02e-433c-ae07-39641df0fd9e,Namespace:kube-system,Attempt:0,}" Sep 4 17:36:22.133035 kubelet[1691]: E0904 17:36:22.132991 1691 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:36:22.133609 containerd[1432]: time="2024-09-04T17:36:22.133568239Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-qhkw6,Uid:c4aeab79-7dff-4da9-8c66-a5e7145dc1db,Namespace:kube-flannel,Attempt:0,}" Sep 4 17:36:22.627626 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1546518479.mount: Deactivated successfully. Sep 4 17:36:22.635740 containerd[1432]: time="2024-09-04T17:36:22.635661919Z" 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:36:22.636193 containerd[1432]: time="2024-09-04T17:36:22.636154271Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Sep 4 17:36:22.636939 containerd[1432]: time="2024-09-04T17:36:22.636910618Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 17:36:22.638532 containerd[1432]: time="2024-09-04T17:36:22.637741066Z" 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:36:22.638532 containerd[1432]: time="2024-09-04T17:36:22.638213827Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 4 17:36:22.640538 containerd[1432]: time="2024-09-04T17:36:22.640485097Z" 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:36:22.642955 containerd[1432]: time="2024-09-04T17:36:22.642536212Z" 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 522.452644ms" Sep 4 17:36:22.643232 containerd[1432]: time="2024-09-04T17:36:22.643198906Z" 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 509.551309ms" Sep 4 17:36:22.757787 containerd[1432]: time="2024-09-04T17:36:22.757699355Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:36:22.758195 containerd[1432]: time="2024-09-04T17:36:22.757764299Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:36:22.758554 containerd[1432]: time="2024-09-04T17:36:22.758455304Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:22.758858 containerd[1432]: time="2024-09-04T17:36:22.758583519Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:36:22.758858 containerd[1432]: time="2024-09-04T17:36:22.758701341Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:36:22.758858 containerd[1432]: time="2024-09-04T17:36:22.758714003Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:22.758858 containerd[1432]: time="2024-09-04T17:36:22.758792604Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:22.758858 containerd[1432]: time="2024-09-04T17:36:22.758603388Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:22.784264 kubelet[1691]: E0904 17:36:22.783073 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:22.842674 systemd[1]: Started cri-containerd-683070d3d0bd2f8db9865fa2cb955c90514c927c5fd13e7bcf9c98a5241987ac.scope - libcontainer container 683070d3d0bd2f8db9865fa2cb955c90514c927c5fd13e7bcf9c98a5241987ac. Sep 4 17:36:22.844194 systemd[1]: Started cri-containerd-9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1.scope - libcontainer container 9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1. Sep 4 17:36:22.865463 containerd[1432]: time="2024-09-04T17:36:22.865424579Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-nnsm2,Uid:96bbe2eb-c02e-433c-ae07-39641df0fd9e,Namespace:kube-system,Attempt:0,} returns sandbox id \"683070d3d0bd2f8db9865fa2cb955c90514c927c5fd13e7bcf9c98a5241987ac\"" Sep 4 17:36:22.868250 kubelet[1691]: E0904 17:36:22.867727 1691 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:36:22.869673 containerd[1432]: time="2024-09-04T17:36:22.869638937Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.4\"" Sep 4 17:36:22.875269 containerd[1432]: time="2024-09-04T17:36:22.875225350Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-qhkw6,Uid:c4aeab79-7dff-4da9-8c66-a5e7145dc1db,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1\"" Sep 4 17:36:22.876038 kubelet[1691]: E0904 17:36:22.876010 1691 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:36:23.783626 kubelet[1691]: E0904 17:36:23.783557 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:23.819228 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount404865127.mount: Deactivated successfully. Sep 4 17:36:24.017243 containerd[1432]: time="2024-09-04T17:36:24.017185352Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:24.018416 containerd[1432]: time="2024-09-04T17:36:24.018203676Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.4: active requests=0, bytes read=25646049" Sep 4 17:36:24.019776 containerd[1432]: time="2024-09-04T17:36:24.019715516Z" level=info msg="ImageCreate event name:\"sha256:7fdda55d346bc23daec633f684e5ec2c91bd1469a5e006bdf45d15fbeb8dacdc\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:24.021789 containerd[1432]: time="2024-09-04T17:36:24.021750132Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:33ee1df1ba70e41bf9506d54bb5e64ef5f3ba9fc1b3021aaa4468606a7802acc\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:24.022651 containerd[1432]: time="2024-09-04T17:36:24.022615868Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.4\" with image id \"sha256:7fdda55d346bc23daec633f684e5ec2c91bd1469a5e006bdf45d15fbeb8dacdc\", repo tag \"registry.k8s.io/kube-proxy:v1.30.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:33ee1df1ba70e41bf9506d54bb5e64ef5f3ba9fc1b3021aaa4468606a7802acc\", size \"25645066\" in 1.152830988s" Sep 4 17:36:24.022697 containerd[1432]: time="2024-09-04T17:36:24.022652301Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.4\" returns image reference \"sha256:7fdda55d346bc23daec633f684e5ec2c91bd1469a5e006bdf45d15fbeb8dacdc\"" Sep 4 17:36:24.024094 containerd[1432]: time="2024-09-04T17:36:24.024063932Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 4 17:36:24.025154 containerd[1432]: time="2024-09-04T17:36:24.025114144Z" level=info msg="CreateContainer within sandbox \"683070d3d0bd2f8db9865fa2cb955c90514c927c5fd13e7bcf9c98a5241987ac\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 4 17:36:24.040784 containerd[1432]: time="2024-09-04T17:36:24.040146492Z" level=info msg="CreateContainer within sandbox \"683070d3d0bd2f8db9865fa2cb955c90514c927c5fd13e7bcf9c98a5241987ac\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"aa3c5eb32373900121d0f574c390e5d1ad9b7a34ce3251feb2b0d8d5e3dec059\"" Sep 4 17:36:24.041115 containerd[1432]: time="2024-09-04T17:36:24.041087565Z" level=info msg="StartContainer for \"aa3c5eb32373900121d0f574c390e5d1ad9b7a34ce3251feb2b0d8d5e3dec059\"" Sep 4 17:36:24.069681 systemd[1]: Started cri-containerd-aa3c5eb32373900121d0f574c390e5d1ad9b7a34ce3251feb2b0d8d5e3dec059.scope - libcontainer container aa3c5eb32373900121d0f574c390e5d1ad9b7a34ce3251feb2b0d8d5e3dec059. Sep 4 17:36:24.096458 containerd[1432]: time="2024-09-04T17:36:24.096416478Z" level=info msg="StartContainer for \"aa3c5eb32373900121d0f574c390e5d1ad9b7a34ce3251feb2b0d8d5e3dec059\" returns successfully" Sep 4 17:36:24.783819 kubelet[1691]: E0904 17:36:24.783764 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:24.870562 kubelet[1691]: E0904 17:36:24.870534 1691 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:36:24.883358 kubelet[1691]: I0904 17:36:24.883280 1691 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-nnsm2" podStartSLOduration=4.7287715420000005 podStartE2EDuration="5.883264775s" podCreationTimestamp="2024-09-04 17:36:19 +0000 UTC" firstStartedPulling="2024-09-04 17:36:22.868890713 +0000 UTC m=+4.358640727" lastFinishedPulling="2024-09-04 17:36:24.023383946 +0000 UTC m=+5.513133960" observedRunningTime="2024-09-04 17:36:24.883218257 +0000 UTC m=+6.372968271" watchObservedRunningTime="2024-09-04 17:36:24.883264775 +0000 UTC m=+6.373014789" Sep 4 17:36:25.088442 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2596392442.mount: Deactivated successfully. Sep 4 17:36:25.155075 containerd[1432]: time="2024-09-04T17:36:25.155028399Z" 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:36:25.155878 containerd[1432]: time="2024-09-04T17:36:25.155849292Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Sep 4 17:36:25.157178 containerd[1432]: time="2024-09-04T17:36:25.157136999Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:25.160125 containerd[1432]: time="2024-09-04T17:36:25.160089861Z" 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:36:25.160911 containerd[1432]: time="2024-09-04T17:36:25.160874225Z" 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.136779716s" Sep 4 17:36:25.160971 containerd[1432]: time="2024-09-04T17:36:25.160913585Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Sep 4 17:36:25.162867 containerd[1432]: time="2024-09-04T17:36:25.162730755Z" level=info msg="CreateContainer within sandbox \"9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 4 17:36:25.174720 containerd[1432]: time="2024-09-04T17:36:25.174639942Z" level=info msg="CreateContainer within sandbox \"9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01\"" Sep 4 17:36:25.175513 containerd[1432]: time="2024-09-04T17:36:25.175110944Z" level=info msg="StartContainer for \"d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01\"" Sep 4 17:36:25.197715 systemd[1]: Started cri-containerd-d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01.scope - libcontainer container d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01. Sep 4 17:36:25.221054 containerd[1432]: time="2024-09-04T17:36:25.220934352Z" level=info msg="StartContainer for \"d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01\" returns successfully" Sep 4 17:36:25.225480 systemd[1]: cri-containerd-d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01.scope: Deactivated successfully. Sep 4 17:36:25.463082 containerd[1432]: time="2024-09-04T17:36:25.462941186Z" level=info msg="shim disconnected" id=d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01 namespace=k8s.io Sep 4 17:36:25.463082 containerd[1432]: time="2024-09-04T17:36:25.462995500Z" level=warning msg="cleaning up after shim disconnected" id=d00cc6df3cff1d45111972d5a50cbfa28a7ffa044c6ed2f6b760a64822706d01 namespace=k8s.io Sep 4 17:36:25.463082 containerd[1432]: time="2024-09-04T17:36:25.463004154Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 17:36:25.784322 kubelet[1691]: E0904 17:36:25.784207 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:25.872925 kubelet[1691]: E0904 17:36:25.872880 1691 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:36:25.873049 kubelet[1691]: E0904 17:36:25.872886 1691 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:36:25.873815 containerd[1432]: time="2024-09-04T17:36:25.873767738Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 4 17:36:26.784538 kubelet[1691]: E0904 17:36:26.784472 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:27.006928 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount444215071.mount: Deactivated successfully. Sep 4 17:36:27.585992 containerd[1432]: time="2024-09-04T17:36:27.585936931Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:27.586560 containerd[1432]: time="2024-09-04T17:36:27.586525636Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874260" Sep 4 17:36:27.587292 containerd[1432]: time="2024-09-04T17:36:27.587267422Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:27.590973 containerd[1432]: time="2024-09-04T17:36:27.590877587Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:27.592591 containerd[1432]: time="2024-09-04T17:36:27.592549919Z" 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.718729693s" Sep 4 17:36:27.592673 containerd[1432]: time="2024-09-04T17:36:27.592592619Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Sep 4 17:36:27.596526 containerd[1432]: time="2024-09-04T17:36:27.596464532Z" level=info msg="CreateContainer within sandbox \"9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 4 17:36:27.607683 containerd[1432]: time="2024-09-04T17:36:27.607627488Z" level=info msg="CreateContainer within sandbox \"9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a\"" Sep 4 17:36:27.608505 containerd[1432]: time="2024-09-04T17:36:27.608442304Z" level=info msg="StartContainer for \"abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a\"" Sep 4 17:36:27.635689 systemd[1]: Started cri-containerd-abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a.scope - libcontainer container abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a. Sep 4 17:36:27.660728 containerd[1432]: time="2024-09-04T17:36:27.660669746Z" level=info msg="StartContainer for \"abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a\" returns successfully" Sep 4 17:36:27.665675 systemd[1]: cri-containerd-abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a.scope: Deactivated successfully. Sep 4 17:36:27.683415 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a-rootfs.mount: Deactivated successfully. Sep 4 17:36:27.755214 kubelet[1691]: I0904 17:36:27.755147 1691 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Sep 4 17:36:27.785477 kubelet[1691]: E0904 17:36:27.785373 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:27.793278 containerd[1432]: time="2024-09-04T17:36:27.793219102Z" level=info msg="shim disconnected" id=abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a namespace=k8s.io Sep 4 17:36:27.793278 containerd[1432]: time="2024-09-04T17:36:27.793276089Z" level=warning msg="cleaning up after shim disconnected" id=abd67ba93ac6c74ba420c6ed1698936ed019b79ec5000eac8464d8ae2d662c0a namespace=k8s.io Sep 4 17:36:27.793278 containerd[1432]: time="2024-09-04T17:36:27.793284310Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 17:36:27.878009 kubelet[1691]: E0904 17:36:27.877911 1691 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:36:27.879935 containerd[1432]: time="2024-09-04T17:36:27.879901395Z" level=info msg="CreateContainer within sandbox \"9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 4 17:36:27.893740 containerd[1432]: time="2024-09-04T17:36:27.893684788Z" level=info msg="CreateContainer within sandbox \"9ee361ae232c9e51d415c710df23754d341ffbed47b7cf7736157fda0798c7a1\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"861c88733a64185f3921ad5c3403c72cc58f6a8752467c9e38711a35fe8a77e1\"" Sep 4 17:36:27.894467 containerd[1432]: time="2024-09-04T17:36:27.894231550Z" level=info msg="StartContainer for \"861c88733a64185f3921ad5c3403c72cc58f6a8752467c9e38711a35fe8a77e1\"" Sep 4 17:36:27.921688 systemd[1]: Started cri-containerd-861c88733a64185f3921ad5c3403c72cc58f6a8752467c9e38711a35fe8a77e1.scope - libcontainer container 861c88733a64185f3921ad5c3403c72cc58f6a8752467c9e38711a35fe8a77e1. Sep 4 17:36:27.944884 containerd[1432]: time="2024-09-04T17:36:27.944841971Z" level=info msg="StartContainer for \"861c88733a64185f3921ad5c3403c72cc58f6a8752467c9e38711a35fe8a77e1\" returns successfully" Sep 4 17:36:28.786423 kubelet[1691]: E0904 17:36:28.786368 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:28.883556 kubelet[1691]: E0904 17:36:28.883471 1691 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:36:28.894928 kubelet[1691]: I0904 17:36:28.894696 1691 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-qhkw6" podStartSLOduration=5.17739273 podStartE2EDuration="9.894677471s" podCreationTimestamp="2024-09-04 17:36:19 +0000 UTC" firstStartedPulling="2024-09-04 17:36:22.876804142 +0000 UTC m=+4.366554156" lastFinishedPulling="2024-09-04 17:36:27.594088883 +0000 UTC m=+9.083838897" observedRunningTime="2024-09-04 17:36:28.894654478 +0000 UTC m=+10.384404492" watchObservedRunningTime="2024-09-04 17:36:28.894677471 +0000 UTC m=+10.384427485" Sep 4 17:36:29.043915 systemd-networkd[1371]: flannel.1: Link UP Sep 4 17:36:29.043921 systemd-networkd[1371]: flannel.1: Gained carrier Sep 4 17:36:29.787312 kubelet[1691]: E0904 17:36:29.787259 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:29.885605 kubelet[1691]: E0904 17:36:29.885574 1691 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:36:30.197619 systemd-networkd[1371]: flannel.1: Gained IPv6LL Sep 4 17:36:30.788284 kubelet[1691]: E0904 17:36:30.788226 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:30.792275 kubelet[1691]: I0904 17:36:30.792225 1691 topology_manager.go:215] "Topology Admit Handler" podUID="d9e2e722-1e0c-48fe-945c-c78623818146" podNamespace="default" podName="nginx-deployment-85f456d6dd-f4pfc" Sep 4 17:36:30.797767 systemd[1]: Created slice kubepods-besteffort-podd9e2e722_1e0c_48fe_945c_c78623818146.slice - libcontainer container kubepods-besteffort-podd9e2e722_1e0c_48fe_945c_c78623818146.slice. Sep 4 17:36:30.864338 kubelet[1691]: I0904 17:36:30.864274 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mltsc\" (UniqueName: \"kubernetes.io/projected/d9e2e722-1e0c-48fe-945c-c78623818146-kube-api-access-mltsc\") pod \"nginx-deployment-85f456d6dd-f4pfc\" (UID: \"d9e2e722-1e0c-48fe-945c-c78623818146\") " pod="default/nginx-deployment-85f456d6dd-f4pfc" Sep 4 17:36:31.102053 containerd[1432]: time="2024-09-04T17:36:31.101936369Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-f4pfc,Uid:d9e2e722-1e0c-48fe-945c-c78623818146,Namespace:default,Attempt:0,}" Sep 4 17:36:31.134491 systemd-networkd[1371]: cni0: Link UP Sep 4 17:36:31.140438 systemd-networkd[1371]: veth1701fc3d: Link UP Sep 4 17:36:31.142860 kernel: cni0: port 1(veth1701fc3d) entered blocking state Sep 4 17:36:31.142940 kernel: cni0: port 1(veth1701fc3d) entered disabled state Sep 4 17:36:31.142958 kernel: veth1701fc3d: entered allmulticast mode Sep 4 17:36:31.145764 kernel: veth1701fc3d: entered promiscuous mode Sep 4 17:36:31.145899 kernel: cni0: port 1(veth1701fc3d) entered blocking state Sep 4 17:36:31.145932 kernel: cni0: port 1(veth1701fc3d) entered forwarding state Sep 4 17:36:31.149589 kernel: cni0: port 1(veth1701fc3d) entered disabled state Sep 4 17:36:31.156772 kernel: cni0: port 1(veth1701fc3d) entered blocking state Sep 4 17:36:31.156853 kernel: cni0: port 1(veth1701fc3d) entered forwarding state Sep 4 17:36:31.156813 systemd-networkd[1371]: veth1701fc3d: Gained carrier Sep 4 17:36:31.157527 systemd-networkd[1371]: cni0: Gained carrier Sep 4 17:36:31.158642 containerd[1432]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014938), "name":"cbr0", "type":"bridge"} Sep 4 17:36:31.158642 containerd[1432]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:36:31.184926 containerd[1432]: {"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:36:31.184827662Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:36:31.184926 containerd[1432]: time="2024-09-04T17:36:31.184884943Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:36:31.184926 containerd[1432]: time="2024-09-04T17:36:31.184896527Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:31.185188 containerd[1432]: time="2024-09-04T17:36:31.184980892Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:31.203716 systemd[1]: Started cri-containerd-dfe2d33400fb29ebabb536a5f9caa8ab3a903c8f0814f95498e66b0cdf42c970.scope - libcontainer container dfe2d33400fb29ebabb536a5f9caa8ab3a903c8f0814f95498e66b0cdf42c970. Sep 4 17:36:31.213947 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 17:36:31.231539 containerd[1432]: time="2024-09-04T17:36:31.231482899Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-f4pfc,Uid:d9e2e722-1e0c-48fe-945c-c78623818146,Namespace:default,Attempt:0,} returns sandbox id \"dfe2d33400fb29ebabb536a5f9caa8ab3a903c8f0814f95498e66b0cdf42c970\"" Sep 4 17:36:31.233003 containerd[1432]: time="2024-09-04T17:36:31.232976376Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 17:36:31.788967 kubelet[1691]: E0904 17:36:31.788920 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:32.630619 systemd-networkd[1371]: veth1701fc3d: Gained IPv6LL Sep 4 17:36:32.790152 kubelet[1691]: E0904 17:36:32.790101 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:32.847036 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount58646544.mount: Deactivated successfully. Sep 4 17:36:33.141623 systemd-networkd[1371]: cni0: Gained IPv6LL Sep 4 17:36:33.584112 containerd[1432]: time="2024-09-04T17:36:33.583036533Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:33.588586 containerd[1432]: time="2024-09-04T17:36:33.588539489Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67679186" Sep 4 17:36:33.589689 containerd[1432]: time="2024-09-04T17:36:33.589656599Z" level=info msg="ImageCreate event name:\"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:33.592762 containerd[1432]: time="2024-09-04T17:36:33.592726344Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:337a19c298c7ca0845a051d89520d044d67a9402e4c33db4f541a93279763a1d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:33.593707 containerd[1432]: time="2024-09-04T17:36:33.593670475Z" 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.360661183s" Sep 4 17:36:33.593765 containerd[1432]: time="2024-09-04T17:36:33.593707197Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\"" Sep 4 17:36:33.595984 containerd[1432]: time="2024-09-04T17:36:33.595951646Z" level=info msg="CreateContainer within sandbox \"dfe2d33400fb29ebabb536a5f9caa8ab3a903c8f0814f95498e66b0cdf42c970\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 4 17:36:33.608560 containerd[1432]: time="2024-09-04T17:36:33.608509094Z" level=info msg="CreateContainer within sandbox \"dfe2d33400fb29ebabb536a5f9caa8ab3a903c8f0814f95498e66b0cdf42c970\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"b8cc5de031fd8ec26328a087009c5b210387ea6916dde139ccefd3a2799cc39e\"" Sep 4 17:36:33.609376 containerd[1432]: time="2024-09-04T17:36:33.609337027Z" level=info msg="StartContainer for \"b8cc5de031fd8ec26328a087009c5b210387ea6916dde139ccefd3a2799cc39e\"" Sep 4 17:36:33.642704 systemd[1]: Started cri-containerd-b8cc5de031fd8ec26328a087009c5b210387ea6916dde139ccefd3a2799cc39e.scope - libcontainer container b8cc5de031fd8ec26328a087009c5b210387ea6916dde139ccefd3a2799cc39e. Sep 4 17:36:33.668636 containerd[1432]: time="2024-09-04T17:36:33.667035797Z" level=info msg="StartContainer for \"b8cc5de031fd8ec26328a087009c5b210387ea6916dde139ccefd3a2799cc39e\" returns successfully" Sep 4 17:36:33.790386 kubelet[1691]: E0904 17:36:33.790322 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:33.907231 kubelet[1691]: I0904 17:36:33.906930 1691 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-f4pfc" podStartSLOduration=1.5446369359999998 podStartE2EDuration="3.906913805s" podCreationTimestamp="2024-09-04 17:36:30 +0000 UTC" firstStartedPulling="2024-09-04 17:36:31.232562622 +0000 UTC m=+12.722312636" lastFinishedPulling="2024-09-04 17:36:33.594839491 +0000 UTC m=+15.084589505" observedRunningTime="2024-09-04 17:36:33.906753173 +0000 UTC m=+15.396503187" watchObservedRunningTime="2024-09-04 17:36:33.906913805 +0000 UTC m=+15.396663819" Sep 4 17:36:34.791010 kubelet[1691]: E0904 17:36:34.790953 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:35.791626 kubelet[1691]: E0904 17:36:35.791578 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:36.792090 kubelet[1691]: E0904 17:36:36.792041 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:37.710695 kubelet[1691]: I0904 17:36:37.710615 1691 topology_manager.go:215] "Topology Admit Handler" podUID="bd30a940-5051-4336-a520-a3e0497a7954" podNamespace="default" podName="nfs-server-provisioner-0" Sep 4 17:36:37.716927 systemd[1]: Created slice kubepods-besteffort-podbd30a940_5051_4336_a520_a3e0497a7954.slice - libcontainer container kubepods-besteffort-podbd30a940_5051_4336_a520_a3e0497a7954.slice. Sep 4 17:36:37.792703 kubelet[1691]: E0904 17:36:37.792652 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:37.799941 kubelet[1691]: I0904 17:36:37.799893 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/bd30a940-5051-4336-a520-a3e0497a7954-data\") pod \"nfs-server-provisioner-0\" (UID: \"bd30a940-5051-4336-a520-a3e0497a7954\") " pod="default/nfs-server-provisioner-0" Sep 4 17:36:37.799941 kubelet[1691]: I0904 17:36:37.799939 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-w9hbk\" (UniqueName: \"kubernetes.io/projected/bd30a940-5051-4336-a520-a3e0497a7954-kube-api-access-w9hbk\") pod \"nfs-server-provisioner-0\" (UID: \"bd30a940-5051-4336-a520-a3e0497a7954\") " pod="default/nfs-server-provisioner-0" Sep 4 17:36:38.020328 containerd[1432]: time="2024-09-04T17:36:38.020283511Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:bd30a940-5051-4336-a520-a3e0497a7954,Namespace:default,Attempt:0,}" Sep 4 17:36:38.170645 systemd-networkd[1371]: vethc4462dbb: Link UP Sep 4 17:36:38.170943 kernel: cni0: port 2(vethc4462dbb) entered blocking state Sep 4 17:36:38.170972 kernel: cni0: port 2(vethc4462dbb) entered disabled state Sep 4 17:36:38.170990 kernel: vethc4462dbb: entered allmulticast mode Sep 4 17:36:38.171923 kernel: vethc4462dbb: entered promiscuous mode Sep 4 17:36:38.176092 kernel: cni0: port 2(vethc4462dbb) entered blocking state Sep 4 17:36:38.176163 kernel: cni0: port 2(vethc4462dbb) entered forwarding state Sep 4 17:36:38.176307 systemd-networkd[1371]: vethc4462dbb: Gained carrier Sep 4 17:36:38.178543 containerd[1432]: 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:36:38.178543 containerd[1432]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:36:38.197260 containerd[1432]: {"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:36:38.197158424Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:36:38.197260 containerd[1432]: time="2024-09-04T17:36:38.197229027Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:36:38.197260 containerd[1432]: time="2024-09-04T17:36:38.197246457Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:38.197430 containerd[1432]: time="2024-09-04T17:36:38.197330012Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:38.220687 systemd[1]: Started cri-containerd-0796103d92271d9b4180d4d237cfe98817e6ebff78de9473fb2428abe7990449.scope - libcontainer container 0796103d92271d9b4180d4d237cfe98817e6ebff78de9473fb2428abe7990449. Sep 4 17:36:38.231163 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 17:36:38.248297 containerd[1432]: time="2024-09-04T17:36:38.248259668Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:bd30a940-5051-4336-a520-a3e0497a7954,Namespace:default,Attempt:0,} returns sandbox id \"0796103d92271d9b4180d4d237cfe98817e6ebff78de9473fb2428abe7990449\"" Sep 4 17:36:38.250040 containerd[1432]: time="2024-09-04T17:36:38.250013926Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 4 17:36:38.793714 kubelet[1691]: E0904 17:36:38.793667 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:39.781590 kubelet[1691]: E0904 17:36:39.781552 1691 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:39.794294 kubelet[1691]: E0904 17:36:39.794182 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:39.964490 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3215202987.mount: Deactivated successfully. Sep 4 17:36:40.117621 systemd-networkd[1371]: vethc4462dbb: Gained IPv6LL Sep 4 17:36:40.795306 kubelet[1691]: E0904 17:36:40.795274 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:41.305915 containerd[1432]: time="2024-09-04T17:36:41.305856293Z" 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:36:41.307107 containerd[1432]: time="2024-09-04T17:36:41.307069097Z" 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:36:41.308539 containerd[1432]: time="2024-09-04T17:36:41.307882524Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:41.310591 containerd[1432]: time="2024-09-04T17:36:41.310555483Z" 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:36:41.311686 containerd[1432]: time="2024-09-04T17:36:41.311651569Z" 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.061605699s" Sep 4 17:36:41.311720 containerd[1432]: time="2024-09-04T17:36:41.311685837Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 4 17:36:41.314229 containerd[1432]: time="2024-09-04T17:36:41.314195254Z" level=info msg="CreateContainer within sandbox \"0796103d92271d9b4180d4d237cfe98817e6ebff78de9473fb2428abe7990449\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 4 17:36:41.325512 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount335811093.mount: Deactivated successfully. Sep 4 17:36:41.326794 containerd[1432]: time="2024-09-04T17:36:41.326739543Z" level=info msg="CreateContainer within sandbox \"0796103d92271d9b4180d4d237cfe98817e6ebff78de9473fb2428abe7990449\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"e3562a08a36998098c6300a01b7a06d1ee0ed8598f61f347615fe2ba30e7a1fe\"" Sep 4 17:36:41.327420 containerd[1432]: time="2024-09-04T17:36:41.327385191Z" level=info msg="StartContainer for \"e3562a08a36998098c6300a01b7a06d1ee0ed8598f61f347615fe2ba30e7a1fe\"" Sep 4 17:36:41.356665 systemd[1]: Started cri-containerd-e3562a08a36998098c6300a01b7a06d1ee0ed8598f61f347615fe2ba30e7a1fe.scope - libcontainer container e3562a08a36998098c6300a01b7a06d1ee0ed8598f61f347615fe2ba30e7a1fe. Sep 4 17:36:41.379940 containerd[1432]: time="2024-09-04T17:36:41.379891388Z" level=info msg="StartContainer for \"e3562a08a36998098c6300a01b7a06d1ee0ed8598f61f347615fe2ba30e7a1fe\" returns successfully" Sep 4 17:36:41.796353 kubelet[1691]: E0904 17:36:41.796308 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:41.930675 kubelet[1691]: I0904 17:36:41.930609 1691 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.867691814 podStartE2EDuration="4.930590648s" podCreationTimestamp="2024-09-04 17:36:37 +0000 UTC" firstStartedPulling="2024-09-04 17:36:38.249586395 +0000 UTC m=+19.739336409" lastFinishedPulling="2024-09-04 17:36:41.312485229 +0000 UTC m=+22.802235243" observedRunningTime="2024-09-04 17:36:41.930473451 +0000 UTC m=+23.420223465" watchObservedRunningTime="2024-09-04 17:36:41.930590648 +0000 UTC m=+23.420340662" Sep 4 17:36:42.797488 kubelet[1691]: E0904 17:36:42.797428 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:43.798476 kubelet[1691]: E0904 17:36:43.798430 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:44.799410 kubelet[1691]: E0904 17:36:44.799363 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:45.799990 kubelet[1691]: E0904 17:36:45.799926 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:46.800076 kubelet[1691]: E0904 17:36:46.800018 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:47.800549 kubelet[1691]: E0904 17:36:47.800490 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:48.801340 kubelet[1691]: E0904 17:36:48.801292 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:49.801818 kubelet[1691]: E0904 17:36:49.801770 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:50.802690 kubelet[1691]: E0904 17:36:50.802636 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:50.835473 kubelet[1691]: I0904 17:36:50.835438 1691 topology_manager.go:215] "Topology Admit Handler" podUID="3877a3c7-e3ee-4856-980a-6ad3350e7b9a" podNamespace="default" podName="test-pod-1" Sep 4 17:36:50.840474 systemd[1]: Created slice kubepods-besteffort-pod3877a3c7_e3ee_4856_980a_6ad3350e7b9a.slice - libcontainer container kubepods-besteffort-pod3877a3c7_e3ee_4856_980a_6ad3350e7b9a.slice. Sep 4 17:36:50.873019 kubelet[1691]: I0904 17:36:50.872967 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5qzgx\" (UniqueName: \"kubernetes.io/projected/3877a3c7-e3ee-4856-980a-6ad3350e7b9a-kube-api-access-5qzgx\") pod \"test-pod-1\" (UID: \"3877a3c7-e3ee-4856-980a-6ad3350e7b9a\") " pod="default/test-pod-1" Sep 4 17:36:50.873019 kubelet[1691]: I0904 17:36:50.873006 1691 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-0d4e381e-29d7-471b-b9dc-d842a7e4665f\" (UniqueName: \"kubernetes.io/nfs/3877a3c7-e3ee-4856-980a-6ad3350e7b9a-pvc-0d4e381e-29d7-471b-b9dc-d842a7e4665f\") pod \"test-pod-1\" (UID: \"3877a3c7-e3ee-4856-980a-6ad3350e7b9a\") " pod="default/test-pod-1" Sep 4 17:36:51.002546 kernel: FS-Cache: Loaded Sep 4 17:36:51.028665 kernel: RPC: Registered named UNIX socket transport module. Sep 4 17:36:51.028771 kernel: RPC: Registered udp transport module. Sep 4 17:36:51.028790 kernel: RPC: Registered tcp transport module. Sep 4 17:36:51.028803 kernel: RPC: Registered tcp-with-tls transport module. Sep 4 17:36:51.029765 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 4 17:36:51.209792 kernel: NFS: Registering the id_resolver key type Sep 4 17:36:51.209989 kernel: Key type id_resolver registered Sep 4 17:36:51.210007 kernel: Key type id_legacy registered Sep 4 17:36:51.233465 nfsidmap[2711]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 17:36:51.236597 nfsidmap[2714]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 17:36:51.443592 containerd[1432]: time="2024-09-04T17:36:51.443530558Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:3877a3c7-e3ee-4856-980a-6ad3350e7b9a,Namespace:default,Attempt:0,}" Sep 4 17:36:51.461867 systemd-networkd[1371]: veth404b9238: Link UP Sep 4 17:36:51.463568 kernel: cni0: port 3(veth404b9238) entered blocking state Sep 4 17:36:51.463617 kernel: cni0: port 3(veth404b9238) entered disabled state Sep 4 17:36:51.463635 kernel: veth404b9238: entered allmulticast mode Sep 4 17:36:51.464575 kernel: veth404b9238: entered promiscuous mode Sep 4 17:36:51.468613 kernel: cni0: port 3(veth404b9238) entered blocking state Sep 4 17:36:51.468659 kernel: cni0: port 3(veth404b9238) entered forwarding state Sep 4 17:36:51.468768 systemd-networkd[1371]: veth404b9238: Gained carrier Sep 4 17:36:51.470157 containerd[1432]: 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:36:51.470157 containerd[1432]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:36:51.484637 containerd[1432]: {"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:36:51.484427190Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:36:51.484637 containerd[1432]: time="2024-09-04T17:36:51.484480469Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:36:51.484637 containerd[1432]: time="2024-09-04T17:36:51.484491628Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:51.484637 containerd[1432]: time="2024-09-04T17:36:51.484591266Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:36:51.505744 systemd[1]: Started cri-containerd-8b5de23c217eaf8dab6f27118070b16e869b295eb98dfa17e52865386100df28.scope - libcontainer container 8b5de23c217eaf8dab6f27118070b16e869b295eb98dfa17e52865386100df28. Sep 4 17:36:51.515819 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 17:36:51.535525 containerd[1432]: time="2024-09-04T17:36:51.533707230Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:3877a3c7-e3ee-4856-980a-6ad3350e7b9a,Namespace:default,Attempt:0,} returns sandbox id \"8b5de23c217eaf8dab6f27118070b16e869b295eb98dfa17e52865386100df28\"" Sep 4 17:36:51.537846 containerd[1432]: time="2024-09-04T17:36:51.537806277Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 17:36:51.791613 containerd[1432]: time="2024-09-04T17:36:51.791158528Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:36:51.791903 containerd[1432]: time="2024-09-04T17:36:51.791709473Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 4 17:36:51.794753 containerd[1432]: time="2024-09-04T17:36:51.794712350Z" 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 256.865433ms" Sep 4 17:36:51.794753 containerd[1432]: time="2024-09-04T17:36:51.794749869Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\"" Sep 4 17:36:51.796583 containerd[1432]: time="2024-09-04T17:36:51.796554179Z" level=info msg="CreateContainer within sandbox \"8b5de23c217eaf8dab6f27118070b16e869b295eb98dfa17e52865386100df28\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 4 17:36:51.802772 kubelet[1691]: E0904 17:36:51.802735 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:51.807993 containerd[1432]: time="2024-09-04T17:36:51.807944585Z" level=info msg="CreateContainer within sandbox \"8b5de23c217eaf8dab6f27118070b16e869b295eb98dfa17e52865386100df28\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a18cec53f3aefc8346268fa2c3814e0648fa71f4924b2c70d385df1ca193cf7d\"" Sep 4 17:36:51.808426 containerd[1432]: time="2024-09-04T17:36:51.808388173Z" level=info msg="StartContainer for \"a18cec53f3aefc8346268fa2c3814e0648fa71f4924b2c70d385df1ca193cf7d\"" Sep 4 17:36:51.836716 systemd[1]: Started cri-containerd-a18cec53f3aefc8346268fa2c3814e0648fa71f4924b2c70d385df1ca193cf7d.scope - libcontainer container a18cec53f3aefc8346268fa2c3814e0648fa71f4924b2c70d385df1ca193cf7d. Sep 4 17:36:51.862236 containerd[1432]: time="2024-09-04T17:36:51.862187528Z" level=info msg="StartContainer for \"a18cec53f3aefc8346268fa2c3814e0648fa71f4924b2c70d385df1ca193cf7d\" returns successfully" Sep 4 17:36:52.803480 kubelet[1691]: E0904 17:36:52.803431 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:53.429672 systemd-networkd[1371]: veth404b9238: Gained IPv6LL Sep 4 17:36:53.804185 kubelet[1691]: E0904 17:36:53.804138 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:54.804636 kubelet[1691]: E0904 17:36:54.804569 1691 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:36:55.098657 update_engine[1416]: I0904 17:36:55.098176 1416 update_attempter.cc:509] Updating boot flags... Sep 4 17:36:55.128534 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 42 scanned by (udev-worker) (2878)