Nov 12 22:32:43.904853 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 12 22:32:43.904874 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Tue Nov 12 21:07:55 -00 2024 Nov 12 22:32:43.904884 kernel: KASLR enabled Nov 12 22:32:43.904889 kernel: efi: EFI v2.7 by EDK II Nov 12 22:32:43.904907 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbbf018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40d98 Nov 12 22:32:43.904914 kernel: random: crng init done Nov 12 22:32:43.904922 kernel: secureboot: Secure boot disabled Nov 12 22:32:43.904928 kernel: ACPI: Early table checksum verification disabled Nov 12 22:32:43.904934 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) Nov 12 22:32:43.904942 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 12 22:32:43.904948 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.904954 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.904960 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.904966 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.904974 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.904988 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.904996 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.905002 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.905009 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:32:43.905015 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 12 22:32:43.905022 kernel: NUMA: Failed to initialise from firmware Nov 12 22:32:43.905028 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 12 22:32:43.905035 kernel: NUMA: NODE_DATA [mem 0xdc956800-0xdc95bfff] Nov 12 22:32:43.905041 kernel: Zone ranges: Nov 12 22:32:43.905047 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 12 22:32:43.905055 kernel: DMA32 empty Nov 12 22:32:43.905062 kernel: Normal empty Nov 12 22:32:43.905068 kernel: Movable zone start for each node Nov 12 22:32:43.905074 kernel: Early memory node ranges Nov 12 22:32:43.905081 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Nov 12 22:32:43.905087 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Nov 12 22:32:43.905093 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Nov 12 22:32:43.905099 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 12 22:32:43.905106 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 12 22:32:43.905112 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 12 22:32:43.905118 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 12 22:32:43.905125 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 12 22:32:43.905132 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Nov 12 22:32:43.905138 kernel: psci: probing for conduit method from ACPI. Nov 12 22:32:43.905145 kernel: psci: PSCIv1.1 detected in firmware. Nov 12 22:32:43.905154 kernel: psci: Using standard PSCI v0.2 function IDs Nov 12 22:32:43.905161 kernel: psci: Trusted OS migration not required Nov 12 22:32:43.905167 kernel: psci: SMC Calling Convention v1.1 Nov 12 22:32:43.905175 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Nov 12 22:32:43.905182 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Nov 12 22:32:43.905189 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Nov 12 22:32:43.905196 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 12 22:32:43.905203 kernel: Detected PIPT I-cache on CPU0 Nov 12 22:32:43.905210 kernel: CPU features: detected: GIC system register CPU interface Nov 12 22:32:43.905217 kernel: CPU features: detected: Hardware dirty bit management Nov 12 22:32:43.905223 kernel: CPU features: detected: Spectre-v4 Nov 12 22:32:43.905230 kernel: CPU features: detected: Spectre-BHB Nov 12 22:32:43.905237 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 12 22:32:43.905245 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 12 22:32:43.905252 kernel: CPU features: detected: ARM erratum 1418040 Nov 12 22:32:43.905258 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 12 22:32:43.905265 kernel: alternatives: applying boot alternatives Nov 12 22:32:43.905273 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=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:32:43.905280 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Nov 12 22:32:43.905287 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 12 22:32:43.905294 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 12 22:32:43.905300 kernel: Fallback order for Node 0: 0 Nov 12 22:32:43.905307 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 12 22:32:43.905313 kernel: Policy zone: DMA Nov 12 22:32:43.905321 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 12 22:32:43.905328 kernel: software IO TLB: area num 4. Nov 12 22:32:43.905335 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Nov 12 22:32:43.905342 kernel: Memory: 2386316K/2572288K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39680K init, 897K bss, 185972K reserved, 0K cma-reserved) Nov 12 22:32:43.905349 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 12 22:32:43.905356 kernel: trace event string verifier disabled Nov 12 22:32:43.905362 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 12 22:32:43.905370 kernel: rcu: RCU event tracing is enabled. Nov 12 22:32:43.905377 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 12 22:32:43.905383 kernel: Trampoline variant of Tasks RCU enabled. Nov 12 22:32:43.905390 kernel: Tracing variant of Tasks RCU enabled. Nov 12 22:32:43.905397 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 12 22:32:43.905405 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 12 22:32:43.905412 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 12 22:32:43.905419 kernel: GICv3: 256 SPIs implemented Nov 12 22:32:43.905426 kernel: GICv3: 0 Extended SPIs implemented Nov 12 22:32:43.905432 kernel: Root IRQ handler: gic_handle_irq Nov 12 22:32:43.905439 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Nov 12 22:32:43.905446 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 12 22:32:43.905453 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 12 22:32:43.905459 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Nov 12 22:32:43.905466 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Nov 12 22:32:43.905473 kernel: GICv3: using LPI property table @0x00000000400f0000 Nov 12 22:32:43.905481 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Nov 12 22:32:43.905488 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 12 22:32:43.905495 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:32:43.905502 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 12 22:32:43.905509 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 12 22:32:43.905516 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 12 22:32:43.905522 kernel: arm-pv: using stolen time PV Nov 12 22:32:43.905529 kernel: Console: colour dummy device 80x25 Nov 12 22:32:43.905536 kernel: ACPI: Core revision 20230628 Nov 12 22:32:43.905543 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 12 22:32:43.905550 kernel: pid_max: default: 32768 minimum: 301 Nov 12 22:32:43.905558 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 12 22:32:43.905565 kernel: landlock: Up and running. Nov 12 22:32:43.905572 kernel: SELinux: Initializing. Nov 12 22:32:43.905579 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:32:43.905586 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:32:43.905593 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 12 22:32:43.905600 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 12 22:32:43.905607 kernel: rcu: Hierarchical SRCU implementation. Nov 12 22:32:43.905614 kernel: rcu: Max phase no-delay instances is 400. Nov 12 22:32:43.905622 kernel: Platform MSI: ITS@0x8080000 domain created Nov 12 22:32:43.905629 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 12 22:32:43.905636 kernel: Remapping and enabling EFI services. Nov 12 22:32:43.905643 kernel: smp: Bringing up secondary CPUs ... Nov 12 22:32:43.905650 kernel: Detected PIPT I-cache on CPU1 Nov 12 22:32:43.905657 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 12 22:32:43.905664 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Nov 12 22:32:43.905671 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:32:43.905678 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 12 22:32:43.905686 kernel: Detected PIPT I-cache on CPU2 Nov 12 22:32:43.905693 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 12 22:32:43.905705 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Nov 12 22:32:43.905713 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:32:43.905720 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 12 22:32:43.905728 kernel: Detected PIPT I-cache on CPU3 Nov 12 22:32:43.905735 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 12 22:32:43.905742 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Nov 12 22:32:43.905750 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:32:43.905758 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 12 22:32:43.905765 kernel: smp: Brought up 1 node, 4 CPUs Nov 12 22:32:43.905772 kernel: SMP: Total of 4 processors activated. Nov 12 22:32:43.905780 kernel: CPU features: detected: 32-bit EL0 Support Nov 12 22:32:43.905787 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 12 22:32:43.905794 kernel: CPU features: detected: Common not Private translations Nov 12 22:32:43.905802 kernel: CPU features: detected: CRC32 instructions Nov 12 22:32:43.905809 kernel: CPU features: detected: Enhanced Virtualization Traps Nov 12 22:32:43.905818 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 12 22:32:43.905825 kernel: CPU features: detected: LSE atomic instructions Nov 12 22:32:43.905832 kernel: CPU features: detected: Privileged Access Never Nov 12 22:32:43.905839 kernel: CPU features: detected: RAS Extension Support Nov 12 22:32:43.905847 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 12 22:32:43.905854 kernel: CPU: All CPU(s) started at EL1 Nov 12 22:32:43.905861 kernel: alternatives: applying system-wide alternatives Nov 12 22:32:43.905868 kernel: devtmpfs: initialized Nov 12 22:32:43.905876 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 12 22:32:43.905885 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 12 22:32:43.905892 kernel: pinctrl core: initialized pinctrl subsystem Nov 12 22:32:43.905905 kernel: SMBIOS 3.0.0 present. Nov 12 22:32:43.905913 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 12 22:32:43.905920 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 12 22:32:43.905928 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 12 22:32:43.905935 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 12 22:32:43.905942 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 12 22:32:43.905950 kernel: audit: initializing netlink subsys (disabled) Nov 12 22:32:43.905959 kernel: audit: type=2000 audit(0.018:1): state=initialized audit_enabled=0 res=1 Nov 12 22:32:43.905966 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 12 22:32:43.905974 kernel: cpuidle: using governor menu Nov 12 22:32:43.905985 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 12 22:32:43.905993 kernel: ASID allocator initialised with 32768 entries Nov 12 22:32:43.906000 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 12 22:32:43.906008 kernel: Serial: AMBA PL011 UART driver Nov 12 22:32:43.906015 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 12 22:32:43.906022 kernel: Modules: 0 pages in range for non-PLT usage Nov 12 22:32:43.906031 kernel: Modules: 508960 pages in range for PLT usage Nov 12 22:32:43.906038 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 12 22:32:43.906046 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 12 22:32:43.906053 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 12 22:32:43.906061 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 12 22:32:43.906068 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 12 22:32:43.906075 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 12 22:32:43.906082 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 12 22:32:43.906090 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 12 22:32:43.906098 kernel: ACPI: Added _OSI(Module Device) Nov 12 22:32:43.906105 kernel: ACPI: Added _OSI(Processor Device) Nov 12 22:32:43.906112 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 12 22:32:43.906120 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 12 22:32:43.906127 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 12 22:32:43.906134 kernel: ACPI: Interpreter enabled Nov 12 22:32:43.906142 kernel: ACPI: Using GIC for interrupt routing Nov 12 22:32:43.906149 kernel: ACPI: MCFG table detected, 1 entries Nov 12 22:32:43.906156 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 12 22:32:43.906164 kernel: printk: console [ttyAMA0] enabled Nov 12 22:32:43.906172 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 12 22:32:43.906304 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 12 22:32:43.906381 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 12 22:32:43.906446 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 12 22:32:43.906510 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 12 22:32:43.906574 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 12 22:32:43.906583 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 12 22:32:43.906593 kernel: PCI host bridge to bus 0000:00 Nov 12 22:32:43.906666 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 12 22:32:43.906730 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 12 22:32:43.906790 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 12 22:32:43.906850 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 12 22:32:43.906960 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 12 22:32:43.907052 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 12 22:32:43.907122 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 12 22:32:43.907188 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 12 22:32:43.907254 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 12 22:32:43.907333 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 12 22:32:43.907400 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 12 22:32:43.907468 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 12 22:32:43.907534 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 12 22:32:43.907593 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 12 22:32:43.907650 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 12 22:32:43.907660 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 12 22:32:43.907667 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 12 22:32:43.907675 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 12 22:32:43.907682 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 12 22:32:43.907690 kernel: iommu: Default domain type: Translated Nov 12 22:32:43.907699 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 12 22:32:43.907706 kernel: efivars: Registered efivars operations Nov 12 22:32:43.907713 kernel: vgaarb: loaded Nov 12 22:32:43.907721 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 12 22:32:43.907728 kernel: VFS: Disk quotas dquot_6.6.0 Nov 12 22:32:43.907735 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 12 22:32:43.907743 kernel: pnp: PnP ACPI init Nov 12 22:32:43.907817 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 12 22:32:43.907828 kernel: pnp: PnP ACPI: found 1 devices Nov 12 22:32:43.907837 kernel: NET: Registered PF_INET protocol family Nov 12 22:32:43.907844 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 12 22:32:43.907852 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 12 22:32:43.907859 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 12 22:32:43.907867 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 12 22:32:43.907874 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 12 22:32:43.907882 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 12 22:32:43.907889 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:32:43.907914 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:32:43.907922 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 12 22:32:43.907929 kernel: PCI: CLS 0 bytes, default 64 Nov 12 22:32:43.907937 kernel: kvm [1]: HYP mode not available Nov 12 22:32:43.907944 kernel: Initialise system trusted keyrings Nov 12 22:32:43.907951 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 12 22:32:43.907959 kernel: Key type asymmetric registered Nov 12 22:32:43.907966 kernel: Asymmetric key parser 'x509' registered Nov 12 22:32:43.907973 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 12 22:32:43.907987 kernel: io scheduler mq-deadline registered Nov 12 22:32:43.907994 kernel: io scheduler kyber registered Nov 12 22:32:43.908002 kernel: io scheduler bfq registered Nov 12 22:32:43.908009 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 12 22:32:43.908016 kernel: ACPI: button: Power Button [PWRB] Nov 12 22:32:43.908024 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 12 22:32:43.908095 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 12 22:32:43.908105 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 12 22:32:43.908113 kernel: thunder_xcv, ver 1.0 Nov 12 22:32:43.908120 kernel: thunder_bgx, ver 1.0 Nov 12 22:32:43.908129 kernel: nicpf, ver 1.0 Nov 12 22:32:43.908136 kernel: nicvf, ver 1.0 Nov 12 22:32:43.908209 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 12 22:32:43.908271 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-11-12T22:32:43 UTC (1731450763) Nov 12 22:32:43.908281 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 12 22:32:43.908288 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 12 22:32:43.908296 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 12 22:32:43.908303 kernel: watchdog: Hard watchdog permanently disabled Nov 12 22:32:43.908312 kernel: NET: Registered PF_INET6 protocol family Nov 12 22:32:43.908319 kernel: Segment Routing with IPv6 Nov 12 22:32:43.908326 kernel: In-situ OAM (IOAM) with IPv6 Nov 12 22:32:43.908334 kernel: NET: Registered PF_PACKET protocol family Nov 12 22:32:43.908341 kernel: Key type dns_resolver registered Nov 12 22:32:43.908348 kernel: registered taskstats version 1 Nov 12 22:32:43.908355 kernel: Loading compiled-in X.509 certificates Nov 12 22:32:43.908363 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: 27dd0d090d7a0971a24582c9198f7e80123ea69f' Nov 12 22:32:43.908370 kernel: Key type .fscrypt registered Nov 12 22:32:43.908379 kernel: Key type fscrypt-provisioning registered Nov 12 22:32:43.908386 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 12 22:32:43.908394 kernel: ima: Allocated hash algorithm: sha1 Nov 12 22:32:43.908401 kernel: ima: No architecture policies found Nov 12 22:32:43.908408 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 12 22:32:43.908415 kernel: clk: Disabling unused clocks Nov 12 22:32:43.908423 kernel: Freeing unused kernel memory: 39680K Nov 12 22:32:43.908430 kernel: Run /init as init process Nov 12 22:32:43.908438 kernel: with arguments: Nov 12 22:32:43.908445 kernel: /init Nov 12 22:32:43.908453 kernel: with environment: Nov 12 22:32:43.908460 kernel: HOME=/ Nov 12 22:32:43.908467 kernel: TERM=linux Nov 12 22:32:43.908474 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 12 22:32:43.908483 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) Nov 12 22:32:43.908492 systemd[1]: Detected virtualization kvm. Nov 12 22:32:43.908501 systemd[1]: Detected architecture arm64. Nov 12 22:32:43.908509 systemd[1]: Running in initrd. Nov 12 22:32:43.908517 systemd[1]: No hostname configured, using default hostname. Nov 12 22:32:43.908524 systemd[1]: Hostname set to . Nov 12 22:32:43.908532 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:32:43.908540 systemd[1]: Queued start job for default target initrd.target. Nov 12 22:32:43.908548 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:32:43.908556 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:32:43.908566 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 12 22:32:43.908574 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:32:43.908582 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 12 22:32:43.908590 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 12 22:32:43.908599 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 12 22:32:43.908607 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 12 22:32:43.908615 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:32:43.908624 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:32:43.908632 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:32:43.908640 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:32:43.908648 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:32:43.908656 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:32:43.908663 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:32:43.908671 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:32:43.908679 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 22:32:43.908687 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 22:32:43.908696 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:32:43.908704 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:32:43.908712 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:32:43.908720 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:32:43.908728 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 12 22:32:43.908736 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:32:43.908743 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 12 22:32:43.908751 systemd[1]: Starting systemd-fsck-usr.service... Nov 12 22:32:43.908760 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:32:43.908768 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:32:43.908776 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:32:43.908783 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 12 22:32:43.908791 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:32:43.908799 systemd[1]: Finished systemd-fsck-usr.service. Nov 12 22:32:43.908824 systemd-journald[238]: Collecting audit messages is disabled. Nov 12 22:32:43.908844 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:32:43.908854 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:32:43.908863 systemd-journald[238]: Journal started Nov 12 22:32:43.908881 systemd-journald[238]: Runtime Journal (/run/log/journal/9793fb168d5843c29817961a13dd884e) is 5.9M, max 47.3M, 41.4M free. Nov 12 22:32:43.899815 systemd-modules-load[239]: Inserted module 'overlay' Nov 12 22:32:43.911161 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:32:43.912734 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:32:43.916914 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 12 22:32:43.916040 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:32:43.919075 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:32:43.922031 kernel: Bridge firewalling registered Nov 12 22:32:43.921754 systemd-modules-load[239]: Inserted module 'br_netfilter' Nov 12 22:32:43.923056 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:32:43.924302 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:32:43.927564 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:32:43.930050 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:32:43.933967 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:32:43.938075 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:32:43.941079 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:32:43.945119 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:32:43.948020 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 12 22:32:43.959415 dracut-cmdline[278]: dracut-dracut-053 Nov 12 22:32:43.961853 dracut-cmdline[278]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:32:43.971638 systemd-resolved[273]: Positive Trust Anchors: Nov 12 22:32:43.971710 systemd-resolved[273]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:32:43.971741 systemd-resolved[273]: 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 Nov 12 22:32:43.976424 systemd-resolved[273]: Defaulting to hostname 'linux'. Nov 12 22:32:43.977336 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:32:43.980932 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:32:44.033926 kernel: SCSI subsystem initialized Nov 12 22:32:44.038909 kernel: Loading iSCSI transport class v2.0-870. Nov 12 22:32:44.047924 kernel: iscsi: registered transport (tcp) Nov 12 22:32:44.062306 kernel: iscsi: registered transport (qla4xxx) Nov 12 22:32:44.062320 kernel: QLogic iSCSI HBA Driver Nov 12 22:32:44.103557 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 12 22:32:44.112026 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 12 22:32:44.126924 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 12 22:32:44.126968 kernel: device-mapper: uevent: version 1.0.3 Nov 12 22:32:44.127935 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 12 22:32:44.172936 kernel: raid6: neonx8 gen() 15785 MB/s Nov 12 22:32:44.189925 kernel: raid6: neonx4 gen() 15669 MB/s Nov 12 22:32:44.206928 kernel: raid6: neonx2 gen() 13248 MB/s Nov 12 22:32:44.223928 kernel: raid6: neonx1 gen() 10494 MB/s Nov 12 22:32:44.240927 kernel: raid6: int64x8 gen() 6956 MB/s Nov 12 22:32:44.257928 kernel: raid6: int64x4 gen() 7346 MB/s Nov 12 22:32:44.274935 kernel: raid6: int64x2 gen() 6128 MB/s Nov 12 22:32:44.292012 kernel: raid6: int64x1 gen() 5053 MB/s Nov 12 22:32:44.292038 kernel: raid6: using algorithm neonx8 gen() 15785 MB/s Nov 12 22:32:44.309988 kernel: raid6: .... xor() 11923 MB/s, rmw enabled Nov 12 22:32:44.310015 kernel: raid6: using neon recovery algorithm Nov 12 22:32:44.315397 kernel: xor: measuring software checksum speed Nov 12 22:32:44.315412 kernel: 8regs : 19745 MB/sec Nov 12 22:32:44.316102 kernel: 32regs : 19622 MB/sec Nov 12 22:32:44.317336 kernel: arm64_neon : 26866 MB/sec Nov 12 22:32:44.317348 kernel: xor: using function: arm64_neon (26866 MB/sec) Nov 12 22:32:44.368376 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 12 22:32:44.379379 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:32:44.397110 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:32:44.408244 systemd-udevd[461]: Using default interface naming scheme 'v255'. Nov 12 22:32:44.411319 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:32:44.423095 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 12 22:32:44.435137 dracut-pre-trigger[469]: rd.md=0: removing MD RAID activation Nov 12 22:32:44.459592 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:32:44.470036 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:32:44.507483 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:32:44.516383 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 12 22:32:44.528100 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 12 22:32:44.529514 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:32:44.531128 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:32:44.533294 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:32:44.540042 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 12 22:32:44.550936 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:32:44.561365 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Nov 12 22:32:44.569699 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Nov 12 22:32:44.569800 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 12 22:32:44.569811 kernel: GPT:9289727 != 19775487 Nov 12 22:32:44.569827 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 12 22:32:44.569837 kernel: GPT:9289727 != 19775487 Nov 12 22:32:44.569845 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 12 22:32:44.569856 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 22:32:44.564125 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:32:44.564239 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:32:44.565775 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:32:44.568988 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:32:44.569166 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:32:44.571748 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:32:44.586371 kernel: BTRFS: device fsid 337794e4-53df-462b-aefc-e93e6a958f34 devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (507) Nov 12 22:32:44.581488 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:32:44.589914 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (515) Nov 12 22:32:44.591315 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:32:44.596264 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 12 22:32:44.607680 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 12 22:32:44.611689 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 12 22:32:44.612974 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 12 22:32:44.619352 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 12 22:32:44.634080 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 12 22:32:44.638880 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:32:44.641192 disk-uuid[551]: Primary Header is updated. Nov 12 22:32:44.641192 disk-uuid[551]: Secondary Entries is updated. Nov 12 22:32:44.641192 disk-uuid[551]: Secondary Header is updated. Nov 12 22:32:44.644286 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 22:32:44.660649 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:32:45.654931 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 22:32:45.657555 disk-uuid[552]: The operation has completed successfully. Nov 12 22:32:45.675915 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 12 22:32:45.676955 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 12 22:32:45.701097 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 12 22:32:45.703781 sh[573]: Success Nov 12 22:32:45.716944 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 12 22:32:45.741241 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 12 22:32:45.756254 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 12 22:32:45.758953 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 12 22:32:45.768179 kernel: BTRFS info (device dm-0): first mount of filesystem 337794e4-53df-462b-aefc-e93e6a958f34 Nov 12 22:32:45.768206 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:32:45.768216 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 12 22:32:45.769993 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 12 22:32:45.770007 kernel: BTRFS info (device dm-0): using free space tree Nov 12 22:32:45.773653 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 12 22:32:45.774931 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 12 22:32:45.789086 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 12 22:32:45.790606 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 12 22:32:45.799667 kernel: BTRFS info (device vda6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:32:45.799708 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:32:45.799719 kernel: BTRFS info (device vda6): using free space tree Nov 12 22:32:45.803053 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 22:32:45.809166 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 12 22:32:45.811934 kernel: BTRFS info (device vda6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:32:45.817130 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 12 22:32:45.823078 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 12 22:32:45.884019 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:32:45.902423 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:32:45.927097 ignition[670]: Ignition 2.20.0 Nov 12 22:32:45.927108 ignition[670]: Stage: fetch-offline Nov 12 22:32:45.927143 ignition[670]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:32:45.927152 ignition[670]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:32:45.927382 ignition[670]: parsed url from cmdline: "" Nov 12 22:32:45.927386 ignition[670]: no config URL provided Nov 12 22:32:45.927390 ignition[670]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 22:32:45.927397 ignition[670]: no config at "/usr/lib/ignition/user.ign" Nov 12 22:32:45.932443 systemd-networkd[763]: lo: Link UP Nov 12 22:32:45.927423 ignition[670]: op(1): [started] loading QEMU firmware config module Nov 12 22:32:45.932447 systemd-networkd[763]: lo: Gained carrier Nov 12 22:32:45.927427 ignition[670]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 12 22:32:45.933249 systemd-networkd[763]: Enumeration completed Nov 12 22:32:45.933332 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:32:45.933752 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:32:45.933755 systemd-networkd[763]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:32:45.942200 ignition[670]: op(1): [finished] loading QEMU firmware config module Nov 12 22:32:45.934595 systemd-networkd[763]: eth0: Link UP Nov 12 22:32:45.934598 systemd-networkd[763]: eth0: Gained carrier Nov 12 22:32:45.934604 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:32:45.935226 systemd[1]: Reached target network.target - Network. Nov 12 22:32:45.952827 ignition[670]: parsing config with SHA512: 54068239eac8fb1e8aa7ce5346ef1b7e8b2d134a9467df11dba5117c0878063fe61b3f19d27b3004c2523d2e4ee0d9b3eed155eb985d39b7740d3b45333545fa Nov 12 22:32:45.956948 unknown[670]: fetched base config from "system" Nov 12 22:32:45.956962 unknown[670]: fetched user config from "qemu" Nov 12 22:32:45.957289 ignition[670]: fetch-offline: fetch-offline passed Nov 12 22:32:45.958941 systemd-networkd[763]: eth0: DHCPv4 address 10.0.0.78/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 12 22:32:45.957382 ignition[670]: Ignition finished successfully Nov 12 22:32:45.960466 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:32:45.962037 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 12 22:32:45.973051 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 12 22:32:45.984158 ignition[771]: Ignition 2.20.0 Nov 12 22:32:45.984168 ignition[771]: Stage: kargs Nov 12 22:32:45.984327 ignition[771]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:32:45.984337 ignition[771]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:32:45.985032 ignition[771]: kargs: kargs passed Nov 12 22:32:45.985079 ignition[771]: Ignition finished successfully Nov 12 22:32:45.989951 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 12 22:32:46.001099 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 12 22:32:46.010635 ignition[779]: Ignition 2.20.0 Nov 12 22:32:46.010645 ignition[779]: Stage: disks Nov 12 22:32:46.010807 ignition[779]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:32:46.010817 ignition[779]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:32:46.011519 ignition[779]: disks: disks passed Nov 12 22:32:46.014014 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 12 22:32:46.011560 ignition[779]: Ignition finished successfully Nov 12 22:32:46.017648 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 12 22:32:46.018789 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 22:32:46.020858 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:32:46.022815 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:32:46.024610 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:32:46.037126 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 12 22:32:46.047430 systemd-fsck[790]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 12 22:32:46.052014 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 12 22:32:46.054880 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 12 22:32:46.103922 kernel: EXT4-fs (vda9): mounted filesystem be7e07bb-77fc-4aec-a4f6-d76dc4498784 r/w with ordered data mode. Quota mode: none. Nov 12 22:32:46.104449 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 12 22:32:46.105750 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 12 22:32:46.117977 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:32:46.119652 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 12 22:32:46.120734 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 12 22:32:46.120789 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 12 22:32:46.120810 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:32:46.126958 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 12 22:32:46.135140 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (798) Nov 12 22:32:46.135163 kernel: BTRFS info (device vda6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:32:46.135174 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:32:46.135183 kernel: BTRFS info (device vda6): using free space tree Nov 12 22:32:46.129061 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 12 22:32:46.137906 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 22:32:46.139594 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:32:46.178104 initrd-setup-root[823]: cut: /sysroot/etc/passwd: No such file or directory Nov 12 22:32:46.183630 initrd-setup-root[830]: cut: /sysroot/etc/group: No such file or directory Nov 12 22:32:46.186753 initrd-setup-root[837]: cut: /sysroot/etc/shadow: No such file or directory Nov 12 22:32:46.189595 initrd-setup-root[844]: cut: /sysroot/etc/gshadow: No such file or directory Nov 12 22:32:46.259882 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 12 22:32:46.270024 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 12 22:32:46.271592 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 12 22:32:46.277921 kernel: BTRFS info (device vda6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:32:46.290795 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 12 22:32:46.295808 ignition[913]: INFO : Ignition 2.20.0 Nov 12 22:32:46.295808 ignition[913]: INFO : Stage: mount Nov 12 22:32:46.297486 ignition[913]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:32:46.297486 ignition[913]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:32:46.297486 ignition[913]: INFO : mount: mount passed Nov 12 22:32:46.297486 ignition[913]: INFO : Ignition finished successfully Nov 12 22:32:46.298953 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 12 22:32:46.308000 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 12 22:32:46.766984 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 12 22:32:46.776191 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:32:46.782858 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (925) Nov 12 22:32:46.782887 kernel: BTRFS info (device vda6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:32:46.784531 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:32:46.784546 kernel: BTRFS info (device vda6): using free space tree Nov 12 22:32:46.786915 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 22:32:46.787871 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:32:46.803208 ignition[943]: INFO : Ignition 2.20.0 Nov 12 22:32:46.803208 ignition[943]: INFO : Stage: files Nov 12 22:32:46.804825 ignition[943]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:32:46.804825 ignition[943]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:32:46.804825 ignition[943]: DEBUG : files: compiled without relabeling support, skipping Nov 12 22:32:46.804825 ignition[943]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 12 22:32:46.804825 ignition[943]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 12 22:32:46.810796 ignition[943]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 12 22:32:46.810796 ignition[943]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 12 22:32:46.810796 ignition[943]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 12 22:32:46.810796 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 12 22:32:46.810796 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 12 22:32:46.810796 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:32:46.809455 unknown[943]: wrote ssh authorized keys file for user: core Nov 12 22:32:46.820804 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:32:46.820804 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Nov 12 22:32:46.820804 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Nov 12 22:32:46.820804 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Nov 12 22:32:46.820804 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1 Nov 12 22:32:47.068320 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 12 22:32:47.280845 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Nov 12 22:32:47.280845 ignition[943]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Nov 12 22:32:47.284714 ignition[943]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 12 22:32:47.284714 ignition[943]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 12 22:32:47.284714 ignition[943]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Nov 12 22:32:47.284714 ignition[943]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Nov 12 22:32:47.306408 ignition[943]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 12 22:32:47.310081 ignition[943]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 12 22:32:47.312584 ignition[943]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Nov 12 22:32:47.312584 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:32:47.312584 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:32:47.312584 ignition[943]: INFO : files: files passed Nov 12 22:32:47.312584 ignition[943]: INFO : Ignition finished successfully Nov 12 22:32:47.313145 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 12 22:32:47.327058 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 12 22:32:47.329397 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 12 22:32:47.330705 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 12 22:32:47.330785 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 12 22:32:47.337108 initrd-setup-root-after-ignition[972]: grep: /sysroot/oem/oem-release: No such file or directory Nov 12 22:32:47.340569 initrd-setup-root-after-ignition[974]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:32:47.340569 initrd-setup-root-after-ignition[974]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:32:47.344202 initrd-setup-root-after-ignition[978]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:32:47.345868 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:32:47.347548 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 12 22:32:47.361045 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 12 22:32:47.381428 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 12 22:32:47.381544 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 12 22:32:47.383839 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 12 22:32:47.385780 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 12 22:32:47.387705 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 12 22:32:47.396035 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 12 22:32:47.406961 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:32:47.416088 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 12 22:32:47.423571 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:32:47.424877 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:32:47.426130 systemd[1]: Stopped target timers.target - Timer Units. Nov 12 22:32:47.427849 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 12 22:32:47.428000 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:32:47.430587 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 12 22:32:47.431688 systemd[1]: Stopped target basic.target - Basic System. Nov 12 22:32:47.433328 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 12 22:32:47.435368 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:32:47.437241 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 12 22:32:47.439003 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 12 22:32:47.440881 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:32:47.443115 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 12 22:32:47.444095 systemd-networkd[763]: eth0: Gained IPv6LL Nov 12 22:32:47.445025 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 12 22:32:47.446941 systemd[1]: Stopped target swap.target - Swaps. Nov 12 22:32:47.450158 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 12 22:32:47.450284 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:32:47.452564 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:32:47.453770 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:32:47.455790 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 12 22:32:47.458962 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:32:47.460720 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 12 22:32:47.460835 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 12 22:32:47.463557 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 12 22:32:47.463679 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:32:47.466179 systemd[1]: Stopped target paths.target - Path Units. Nov 12 22:32:47.467808 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 12 22:32:47.470957 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:32:47.472315 systemd[1]: Stopped target slices.target - Slice Units. Nov 12 22:32:47.473939 systemd[1]: Stopped target sockets.target - Socket Units. Nov 12 22:32:47.475671 systemd[1]: iscsid.socket: Deactivated successfully. Nov 12 22:32:47.475762 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:32:47.477887 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 12 22:32:47.478000 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:32:47.479615 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 12 22:32:47.479727 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:32:47.481436 systemd[1]: ignition-files.service: Deactivated successfully. Nov 12 22:32:47.481545 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 12 22:32:47.493068 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 12 22:32:47.493868 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 12 22:32:47.494033 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:32:47.499749 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 12 22:32:47.501562 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 12 22:32:47.502777 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:32:47.505045 ignition[999]: INFO : Ignition 2.20.0 Nov 12 22:32:47.505045 ignition[999]: INFO : Stage: umount Nov 12 22:32:47.505045 ignition[999]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:32:47.505045 ignition[999]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:32:47.505045 ignition[999]: INFO : umount: umount passed Nov 12 22:32:47.505045 ignition[999]: INFO : Ignition finished successfully Nov 12 22:32:47.504149 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 12 22:32:47.504245 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:32:47.509072 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 12 22:32:47.509153 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 12 22:32:47.513598 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 12 22:32:47.514147 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 12 22:32:47.514232 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 12 22:32:47.517372 systemd[1]: Stopped target network.target - Network. Nov 12 22:32:47.519113 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 12 22:32:47.519197 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 12 22:32:47.520992 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 12 22:32:47.521043 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 12 22:32:47.522755 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 12 22:32:47.522804 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 12 22:32:47.525146 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 12 22:32:47.525193 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 12 22:32:47.527456 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 12 22:32:47.532258 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 12 22:32:47.539001 systemd-networkd[763]: eth0: DHCPv6 lease lost Nov 12 22:32:47.540593 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 12 22:32:47.540704 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 12 22:32:47.542444 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 12 22:32:47.542478 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:32:47.556421 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 12 22:32:47.557330 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 12 22:32:47.557396 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:32:47.559578 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:32:47.564575 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 12 22:32:47.564678 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 12 22:32:47.569352 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 12 22:32:47.569460 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 12 22:32:47.573595 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 12 22:32:47.573662 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 12 22:32:47.575563 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 12 22:32:47.575610 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:32:47.577451 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 12 22:32:47.577500 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 12 22:32:47.579161 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 12 22:32:47.579205 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:32:47.581652 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 12 22:32:47.581789 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:32:47.585293 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 12 22:32:47.585555 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 12 22:32:47.587617 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 12 22:32:47.587680 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 12 22:32:47.588963 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 12 22:32:47.589005 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:32:47.591114 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 12 22:32:47.591160 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:32:47.594135 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 12 22:32:47.594180 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 12 22:32:47.596882 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:32:47.596945 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:32:47.614055 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 12 22:32:47.615102 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 12 22:32:47.615168 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:32:47.617287 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:32:47.617335 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:32:47.623934 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 12 22:32:47.625110 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 12 22:32:47.626513 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 12 22:32:47.629146 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 12 22:32:47.641313 systemd[1]: Switching root. Nov 12 22:32:47.668024 systemd-journald[238]: Journal stopped Nov 12 22:32:48.363607 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Nov 12 22:32:48.363666 kernel: SELinux: policy capability network_peer_controls=1 Nov 12 22:32:48.363679 kernel: SELinux: policy capability open_perms=1 Nov 12 22:32:48.363691 kernel: SELinux: policy capability extended_socket_class=1 Nov 12 22:32:48.363701 kernel: SELinux: policy capability always_check_network=0 Nov 12 22:32:48.363711 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 12 22:32:48.363720 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 12 22:32:48.363730 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 12 22:32:48.363740 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 12 22:32:48.363750 kernel: audit: type=1403 audit(1731450767.786:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 12 22:32:48.363760 systemd[1]: Successfully loaded SELinux policy in 34.416ms. Nov 12 22:32:48.363776 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.592ms. Nov 12 22:32:48.363789 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) Nov 12 22:32:48.363802 systemd[1]: Detected virtualization kvm. Nov 12 22:32:48.363813 systemd[1]: Detected architecture arm64. Nov 12 22:32:48.363823 systemd[1]: Detected first boot. Nov 12 22:32:48.363833 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:32:48.363844 zram_generator::config[1046]: No configuration found. Nov 12 22:32:48.364100 systemd[1]: Populated /etc with preset unit settings. Nov 12 22:32:48.364119 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 12 22:32:48.364139 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 12 22:32:48.364154 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 12 22:32:48.364167 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 12 22:32:48.364178 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 12 22:32:48.364193 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 12 22:32:48.364204 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 12 22:32:48.364219 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 12 22:32:48.364230 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 12 22:32:48.364241 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 12 22:32:48.364251 systemd[1]: Created slice user.slice - User and Session Slice. Nov 12 22:32:48.364262 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:32:48.364273 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:32:48.364284 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 12 22:32:48.364294 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 12 22:32:48.364305 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 12 22:32:48.364321 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:32:48.364332 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 12 22:32:48.364352 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:32:48.364363 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 12 22:32:48.364374 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 12 22:32:48.364384 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 12 22:32:48.364395 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 12 22:32:48.364407 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:32:48.364422 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:32:48.364432 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:32:48.364443 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:32:48.364454 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 12 22:32:48.364464 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 12 22:32:48.364475 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:32:48.364486 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:32:48.364497 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:32:48.364507 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 12 22:32:48.364519 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 12 22:32:48.364530 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 12 22:32:48.364541 systemd[1]: Mounting media.mount - External Media Directory... Nov 12 22:32:48.364551 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 12 22:32:48.364562 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 12 22:32:48.364572 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 12 22:32:48.364583 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). Nov 12 22:32:48.364594 systemd[1]: Reached target machines.target - Containers. Nov 12 22:32:48.364606 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 12 22:32:48.364617 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:32:48.364627 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:32:48.364638 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 12 22:32:48.364649 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:32:48.364660 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:32:48.364670 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:32:48.364681 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 12 22:32:48.364692 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:32:48.364704 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 12 22:32:48.364715 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 12 22:32:48.364726 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 12 22:32:48.364736 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 12 22:32:48.364747 systemd[1]: Stopped systemd-fsck-usr.service. Nov 12 22:32:48.364758 kernel: fuse: init (API version 7.39) Nov 12 22:32:48.364767 kernel: loop: module loaded Nov 12 22:32:48.364777 kernel: ACPI: bus type drm_connector registered Nov 12 22:32:48.364788 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:32:48.364799 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:32:48.364809 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 12 22:32:48.364819 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 12 22:32:48.364830 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:32:48.364865 systemd-journald[1124]: Collecting audit messages is disabled. Nov 12 22:32:48.364888 systemd[1]: verity-setup.service: Deactivated successfully. Nov 12 22:32:48.364909 systemd[1]: Stopped verity-setup.service. Nov 12 22:32:48.364926 systemd-journald[1124]: Journal started Nov 12 22:32:48.364948 systemd-journald[1124]: Runtime Journal (/run/log/journal/9793fb168d5843c29817961a13dd884e) is 5.9M, max 47.3M, 41.4M free. Nov 12 22:32:48.143361 systemd[1]: Queued start job for default target multi-user.target. Nov 12 22:32:48.163954 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 12 22:32:48.164358 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 12 22:32:48.367780 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:32:48.368440 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 12 22:32:48.369672 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 12 22:32:48.370957 systemd[1]: Mounted media.mount - External Media Directory. Nov 12 22:32:48.372065 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 12 22:32:48.373290 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 12 22:32:48.374508 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 12 22:32:48.376917 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 12 22:32:48.378301 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:32:48.379882 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 12 22:32:48.380055 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 12 22:32:48.382299 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:32:48.382485 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:32:48.383883 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:32:48.384108 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:32:48.385407 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:32:48.385545 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:32:48.387159 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 12 22:32:48.387304 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 12 22:32:48.388640 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:32:48.388775 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:32:48.390198 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:32:48.391681 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 12 22:32:48.393210 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 12 22:32:48.405814 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 12 22:32:48.412123 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 12 22:32:48.414378 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 12 22:32:48.415547 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 12 22:32:48.415587 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:32:48.417634 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 12 22:32:48.419850 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 12 22:32:48.422092 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 12 22:32:48.423240 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:32:48.424745 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 12 22:32:48.426858 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 12 22:32:48.428178 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:32:48.429316 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 12 22:32:48.432022 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:32:48.433224 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:32:48.435857 systemd-journald[1124]: Time spent on flushing to /var/log/journal/9793fb168d5843c29817961a13dd884e is 17.465ms for 838 entries. Nov 12 22:32:48.435857 systemd-journald[1124]: System Journal (/var/log/journal/9793fb168d5843c29817961a13dd884e) is 8.0M, max 195.6M, 187.6M free. Nov 12 22:32:48.464534 systemd-journald[1124]: Received client request to flush runtime journal. Nov 12 22:32:48.464573 kernel: loop0: detected capacity change from 0 to 194096 Nov 12 22:32:48.436161 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 12 22:32:48.440299 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 12 22:32:48.444953 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:32:48.446644 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 12 22:32:48.450083 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 12 22:32:48.452966 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 12 22:32:48.454845 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 12 22:32:48.462436 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 12 22:32:48.470013 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 12 22:32:48.470120 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 12 22:32:48.475137 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 12 22:32:48.477148 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 12 22:32:48.481380 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:32:48.483369 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 12 22:32:48.498772 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:32:48.500381 kernel: loop1: detected capacity change from 0 to 116808 Nov 12 22:32:48.508565 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 12 22:32:48.509082 udevadm[1172]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 12 22:32:48.511987 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 12 22:32:48.527744 systemd-tmpfiles[1176]: ACLs are not supported, ignoring. Nov 12 22:32:48.527758 systemd-tmpfiles[1176]: ACLs are not supported, ignoring. Nov 12 22:32:48.534941 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:32:48.548940 kernel: loop2: detected capacity change from 0 to 113536 Nov 12 22:32:48.592936 kernel: loop3: detected capacity change from 0 to 194096 Nov 12 22:32:48.599945 kernel: loop4: detected capacity change from 0 to 116808 Nov 12 22:32:48.605920 kernel: loop5: detected capacity change from 0 to 113536 Nov 12 22:32:48.609921 (sd-merge)[1183]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Nov 12 22:32:48.610323 (sd-merge)[1183]: Merged extensions into '/usr'. Nov 12 22:32:48.616573 systemd[1]: Reloading requested from client PID 1157 ('systemd-sysext') (unit systemd-sysext.service)... Nov 12 22:32:48.616589 systemd[1]: Reloading... Nov 12 22:32:48.674937 zram_generator::config[1209]: No configuration found. Nov 12 22:32:48.748659 ldconfig[1152]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 12 22:32:48.771003 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. Nov 12 22:32:48.805767 systemd[1]: Reloading finished in 188 ms. Nov 12 22:32:48.841557 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 12 22:32:48.843094 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 12 22:32:48.863206 systemd[1]: Starting ensure-sysext.service... Nov 12 22:32:48.865465 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:32:48.877797 systemd[1]: Reloading requested from client PID 1245 ('systemctl') (unit ensure-sysext.service)... Nov 12 22:32:48.877814 systemd[1]: Reloading... Nov 12 22:32:48.889371 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 12 22:32:48.889611 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 12 22:32:48.890427 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 12 22:32:48.890695 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. Nov 12 22:32:48.890748 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. Nov 12 22:32:48.899661 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:32:48.899673 systemd-tmpfiles[1246]: Skipping /boot Nov 12 22:32:48.908283 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:32:48.908298 systemd-tmpfiles[1246]: Skipping /boot Nov 12 22:32:48.926934 zram_generator::config[1273]: No configuration found. Nov 12 22:32:49.012459 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. Nov 12 22:32:49.047683 systemd[1]: Reloading finished in 169 ms. Nov 12 22:32:49.061822 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 12 22:32:49.070266 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:32:49.078705 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 12 22:32:49.081025 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 12 22:32:49.083377 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 12 22:32:49.089178 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:32:49.095813 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:32:49.099378 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 12 22:32:49.103974 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:32:49.105137 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:32:49.114234 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:32:49.116496 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:32:49.119838 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:32:49.120808 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 12 22:32:49.122574 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:32:49.122711 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:32:49.124358 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:32:49.124500 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:32:49.131133 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:32:49.131361 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:32:49.137280 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 12 22:32:49.142354 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:32:49.142364 systemd-udevd[1318]: Using default interface naming scheme 'v255'. Nov 12 22:32:49.154138 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:32:49.156392 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:32:49.161672 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:32:49.168240 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:32:49.169425 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:32:49.171493 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 12 22:32:49.175704 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 12 22:32:49.178248 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:32:49.179205 augenrules[1364]: No rules Nov 12 22:32:49.181605 systemd[1]: audit-rules.service: Deactivated successfully. Nov 12 22:32:49.181784 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 12 22:32:49.183971 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 12 22:32:49.185709 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:32:49.185838 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:32:49.189517 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:32:49.189645 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:32:49.191293 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:32:49.191414 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:32:49.194618 systemd[1]: Finished ensure-sysext.service. Nov 12 22:32:49.198653 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:32:49.198781 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:32:49.213343 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 12 22:32:49.214317 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1347) Nov 12 22:32:49.216920 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1371) Nov 12 22:32:49.218475 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 12 22:32:49.221735 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 12 22:32:49.227779 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:32:49.230545 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:32:49.230613 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:32:49.236096 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 12 22:32:49.236955 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1371) Nov 12 22:32:49.240069 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). Nov 12 22:32:49.291329 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 12 22:32:49.297757 systemd-resolved[1312]: Positive Trust Anchors: Nov 12 22:32:49.298331 systemd-resolved[1312]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:32:49.298364 systemd-resolved[1312]: 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 Nov 12 22:32:49.306149 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 12 22:32:49.314783 systemd-networkd[1385]: lo: Link UP Nov 12 22:32:49.314788 systemd-networkd[1385]: lo: Gained carrier Nov 12 22:32:49.315660 systemd-networkd[1385]: Enumeration completed Nov 12 22:32:49.315781 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:32:49.318355 systemd-resolved[1312]: Defaulting to hostname 'linux'. Nov 12 22:32:49.318676 systemd-networkd[1385]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:32:49.318680 systemd-networkd[1385]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:32:49.319576 systemd-networkd[1385]: eth0: Link UP Nov 12 22:32:49.319579 systemd-networkd[1385]: eth0: Gained carrier Nov 12 22:32:49.319592 systemd-networkd[1385]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:32:49.325096 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 12 22:32:49.337609 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 12 22:32:49.338939 systemd[1]: Reached target time-set.target - System Time Set. Nov 12 22:32:49.347028 systemd-networkd[1385]: eth0: DHCPv4 address 10.0.0.78/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 12 22:32:49.348084 systemd-timesyncd[1387]: Network configuration changed, trying to establish connection. Nov 12 22:32:49.348987 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 12 22:32:49.350751 systemd-timesyncd[1387]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 12 22:32:49.350804 systemd-timesyncd[1387]: Initial clock synchronization to Tue 2024-11-12 22:32:49.339210 UTC. Nov 12 22:32:49.352153 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:32:49.353743 systemd[1]: Reached target network.target - Network. Nov 12 22:32:49.355090 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:32:49.379259 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:32:49.392363 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 12 22:32:49.402183 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 12 22:32:49.416207 lvm[1406]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:32:49.419395 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:32:49.456507 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 12 22:32:49.458096 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:32:49.459241 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:32:49.460422 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 12 22:32:49.461695 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 12 22:32:49.463146 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 12 22:32:49.464328 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 12 22:32:49.465667 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 12 22:32:49.466882 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 12 22:32:49.466928 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:32:49.467777 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:32:49.469573 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 12 22:32:49.472089 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 22:32:49.482866 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 12 22:32:49.485236 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 12 22:32:49.486923 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 22:32:49.488130 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:32:49.489121 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:32:49.490095 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:32:49.490125 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:32:49.491155 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 22:32:49.493398 lvm[1413]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:32:49.493373 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 12 22:32:49.496465 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 12 22:32:49.499156 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 12 22:32:49.502121 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). Nov 12 22:32:49.507520 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 12 22:32:49.511192 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 12 22:32:49.512648 jq[1416]: false Nov 12 22:32:49.516354 extend-filesystems[1417]: Found loop3 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found loop4 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found loop5 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda1 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda2 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda3 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found usr Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda4 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda6 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda7 Nov 12 22:32:49.520311 extend-filesystems[1417]: Found vda9 Nov 12 22:32:49.520311 extend-filesystems[1417]: Checking size of /dev/vda9 Nov 12 22:32:49.552780 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1352) Nov 12 22:32:49.552810 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Nov 12 22:32:49.519533 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 12 22:32:49.552972 extend-filesystems[1417]: Resized partition /dev/vda9 Nov 12 22:32:49.542417 dbus-daemon[1415]: [system] SELinux support is enabled Nov 12 22:32:49.525711 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 12 22:32:49.556117 extend-filesystems[1435]: resize2fs 1.47.1 (20-May-2024) Nov 12 22:32:49.527624 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 12 22:32:49.529040 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 12 22:32:49.530071 systemd[1]: Starting update-engine.service - Update Engine... Nov 12 22:32:49.558611 jq[1436]: true Nov 12 22:32:49.536073 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 12 22:32:49.557923 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 12 22:32:49.561531 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 12 22:32:49.568246 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 12 22:32:49.568430 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 12 22:32:49.568702 systemd[1]: motdgen.service: Deactivated successfully. Nov 12 22:32:49.568850 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 12 22:32:49.570404 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 12 22:32:49.570569 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 12 22:32:49.588520 update_engine[1434]: I20241112 22:32:49.588301 1434 main.cc:92] Flatcar Update Engine starting Nov 12 22:32:49.590922 update_engine[1434]: I20241112 22:32:49.590515 1434 update_check_scheduler.cc:74] Next update check in 4m38s Nov 12 22:32:49.592422 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Nov 12 22:32:49.592706 jq[1440]: true Nov 12 22:32:49.599143 (ntainerd)[1448]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 22:32:49.606545 systemd[1]: Started update-engine.service - Update Engine. Nov 12 22:32:49.608074 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). Nov 12 22:32:49.608099 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 12 22:32:49.610702 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). Nov 12 22:32:49.610723 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 12 22:32:49.618630 systemd-logind[1429]: Watching system buttons on /dev/input/event0 (Power Button) Nov 12 22:32:49.621528 systemd-logind[1429]: New seat seat0. Nov 12 22:32:49.627337 extend-filesystems[1435]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 12 22:32:49.627337 extend-filesystems[1435]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 12 22:32:49.627337 extend-filesystems[1435]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Nov 12 22:32:49.622215 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 12 22:32:49.637238 extend-filesystems[1417]: Resized filesystem in /dev/vda9 Nov 12 22:32:49.624304 systemd[1]: Started systemd-logind.service - User Login Management. Nov 12 22:32:49.628803 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 12 22:32:49.630969 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 12 22:32:49.641403 bash[1465]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:32:49.643195 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 12 22:32:49.646131 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 12 22:32:49.668464 locksmithd[1461]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 12 22:32:49.801540 containerd[1448]: time="2024-11-12T22:32:49.801412000Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 12 22:32:49.824332 containerd[1448]: time="2024-11-12T22:32:49.824205760Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:32:49.825693 containerd[1448]: time="2024-11-12T22:32:49.825652280Z" 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.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:32:49.825693 containerd[1448]: time="2024-11-12T22:32:49.825688000Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 22:32:49.825750 containerd[1448]: time="2024-11-12T22:32:49.825706360Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 22:32:49.825904 containerd[1448]: time="2024-11-12T22:32:49.825868160Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 22:32:49.825931 containerd[1448]: time="2024-11-12T22:32:49.825910520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826008 containerd[1448]: time="2024-11-12T22:32:49.825983680Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826008 containerd[1448]: time="2024-11-12T22:32:49.826001680Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826191 containerd[1448]: time="2024-11-12T22:32:49.826165840Z" 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 Nov 12 22:32:49.826191 containerd[1448]: time="2024-11-12T22:32:49.826186920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826240 containerd[1448]: time="2024-11-12T22:32:49.826203080Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826240 containerd[1448]: time="2024-11-12T22:32:49.826212400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826306 containerd[1448]: time="2024-11-12T22:32:49.826290960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826514 containerd[1448]: time="2024-11-12T22:32:49.826487240Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:32:49.826614 containerd[1448]: time="2024-11-12T22:32:49.826598240Z" 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 Nov 12 22:32:49.826636 containerd[1448]: time="2024-11-12T22:32:49.826616600Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 22:32:49.826716 containerd[1448]: time="2024-11-12T22:32:49.826702600Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 22:32:49.826759 containerd[1448]: time="2024-11-12T22:32:49.826748080Z" level=info msg="metadata content store policy set" policy=shared Nov 12 22:32:49.830538 containerd[1448]: time="2024-11-12T22:32:49.830502520Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 22:32:49.830588 containerd[1448]: time="2024-11-12T22:32:49.830555720Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 22:32:49.830588 containerd[1448]: time="2024-11-12T22:32:49.830572000Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 22:32:49.830623 containerd[1448]: time="2024-11-12T22:32:49.830589480Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 22:32:49.830623 containerd[1448]: time="2024-11-12T22:32:49.830604160Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 22:32:49.830763 containerd[1448]: time="2024-11-12T22:32:49.830743120Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 22:32:49.832662 containerd[1448]: time="2024-11-12T22:32:49.832628840Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.832997520Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833025240Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833041760Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833058040Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833071200Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833083760Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833096640Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833111160Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833125280Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833164200Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833176400Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833197840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833214080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836151 containerd[1448]: time="2024-11-12T22:32:49.833229800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833244400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833256280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833271120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833282720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833295640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833307680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833323640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833337960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833352840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833365440Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833380480Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833402160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833415360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836437 containerd[1448]: time="2024-11-12T22:32:49.833427200Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834053240Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834072800Z" 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 Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834084760Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834097760Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834108040Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834120960Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834131960Z" level=info msg="NRI interface is disabled by configuration." Nov 12 22:32:49.836672 containerd[1448]: time="2024-11-12T22:32:49.834143080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 22:32:49.836820 containerd[1448]: time="2024-11-12T22:32:49.834705400Z" 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}" Nov 12 22:32:49.836820 containerd[1448]: time="2024-11-12T22:32:49.834750840Z" level=info msg="Connect containerd service" Nov 12 22:32:49.836820 containerd[1448]: time="2024-11-12T22:32:49.834778680Z" level=info msg="using legacy CRI server" Nov 12 22:32:49.836820 containerd[1448]: time="2024-11-12T22:32:49.834785120Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 22:32:49.836820 containerd[1448]: time="2024-11-12T22:32:49.835549400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 22:32:49.837536 containerd[1448]: time="2024-11-12T22:32:49.837506160Z" 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" Nov 12 22:32:49.838014 containerd[1448]: time="2024-11-12T22:32:49.837983080Z" level=info msg="Start subscribing containerd event" Nov 12 22:32:49.838128 containerd[1448]: time="2024-11-12T22:32:49.838112400Z" level=info msg="Start recovering state" Nov 12 22:32:49.838239 containerd[1448]: time="2024-11-12T22:32:49.838225400Z" level=info msg="Start event monitor" Nov 12 22:32:49.838305 containerd[1448]: time="2024-11-12T22:32:49.838289960Z" level=info msg="Start snapshots syncer" Nov 12 22:32:49.838353 containerd[1448]: time="2024-11-12T22:32:49.838342880Z" level=info msg="Start cni network conf syncer for default" Nov 12 22:32:49.838397 containerd[1448]: time="2024-11-12T22:32:49.838387840Z" level=info msg="Start streaming server" Nov 12 22:32:49.839473 containerd[1448]: time="2024-11-12T22:32:49.839447800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 22:32:49.839589 containerd[1448]: time="2024-11-12T22:32:49.839574640Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 22:32:49.839806 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 22:32:49.841493 containerd[1448]: time="2024-11-12T22:32:49.841467480Z" level=info msg="containerd successfully booted in 0.041862s" Nov 12 22:32:50.089146 sshd_keygen[1438]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 12 22:32:50.108951 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 12 22:32:50.120189 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 12 22:32:50.125674 systemd[1]: issuegen.service: Deactivated successfully. Nov 12 22:32:50.125885 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 12 22:32:50.128566 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 12 22:32:50.139501 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 12 22:32:50.142229 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 12 22:32:50.144312 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 12 22:32:50.145738 systemd[1]: Reached target getty.target - Login Prompts. Nov 12 22:32:50.451066 systemd-networkd[1385]: eth0: Gained IPv6LL Nov 12 22:32:50.454066 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 12 22:32:50.455893 systemd[1]: Reached target network-online.target - Network is Online. Nov 12 22:32:50.466131 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 12 22:32:50.468482 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:32:50.470522 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 12 22:32:50.484839 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 12 22:32:50.485027 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 12 22:32:50.488424 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 12 22:32:50.493954 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 12 22:32:50.946762 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:32:50.948847 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 12 22:32:50.950278 systemd[1]: Startup finished in 543ms (kernel) + 4.086s (initrd) + 3.196s (userspace) = 7.826s. Nov 12 22:32:50.951095 (kubelet)[1518]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 12 22:32:51.419626 kubelet[1518]: E1112 22:32:51.419575 1518 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" Nov 12 22:32:51.422226 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 12 22:32:51.422374 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 12 22:32:56.401542 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 12 22:32:56.402607 systemd[1]: Started sshd@0-10.0.0.78:22-10.0.0.1:54436.service - OpenSSH per-connection server daemon (10.0.0.1:54436). Nov 12 22:32:56.465208 sshd[1533]: Accepted publickey for core from 10.0.0.1 port 54436 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:32:56.466717 sshd-session[1533]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:32:56.477320 systemd-logind[1429]: New session 1 of user core. Nov 12 22:32:56.478200 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 12 22:32:56.488194 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 12 22:32:56.497049 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 12 22:32:56.501192 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 12 22:32:56.504729 (systemd)[1537]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 12 22:32:56.571435 systemd[1537]: Queued start job for default target default.target. Nov 12 22:32:56.585867 systemd[1537]: Created slice app.slice - User Application Slice. Nov 12 22:32:56.585928 systemd[1537]: Reached target paths.target - Paths. Nov 12 22:32:56.585941 systemd[1537]: Reached target timers.target - Timers. Nov 12 22:32:56.587032 systemd[1537]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 12 22:32:56.595635 systemd[1537]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 12 22:32:56.595693 systemd[1537]: Reached target sockets.target - Sockets. Nov 12 22:32:56.595705 systemd[1537]: Reached target basic.target - Basic System. Nov 12 22:32:56.595739 systemd[1537]: Reached target default.target - Main User Target. Nov 12 22:32:56.595762 systemd[1537]: Startup finished in 86ms. Nov 12 22:32:56.596064 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 12 22:32:56.597316 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 12 22:32:56.654745 systemd[1]: Started sshd@1-10.0.0.78:22-10.0.0.1:54446.service - OpenSSH per-connection server daemon (10.0.0.1:54446). Nov 12 22:32:56.697512 sshd[1548]: Accepted publickey for core from 10.0.0.1 port 54446 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:32:56.698602 sshd-session[1548]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:32:56.702115 systemd-logind[1429]: New session 2 of user core. Nov 12 22:32:56.713115 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 12 22:32:56.763296 sshd[1550]: Connection closed by 10.0.0.1 port 54446 Nov 12 22:32:56.763571 sshd-session[1548]: pam_unix(sshd:session): session closed for user core Nov 12 22:32:56.773067 systemd[1]: sshd@1-10.0.0.78:22-10.0.0.1:54446.service: Deactivated successfully. Nov 12 22:32:56.774348 systemd[1]: session-2.scope: Deactivated successfully. Nov 12 22:32:56.775524 systemd-logind[1429]: Session 2 logged out. Waiting for processes to exit. Nov 12 22:32:56.776526 systemd[1]: Started sshd@2-10.0.0.78:22-10.0.0.1:54460.service - OpenSSH per-connection server daemon (10.0.0.1:54460). Nov 12 22:32:56.777216 systemd-logind[1429]: Removed session 2. Nov 12 22:32:56.819602 sshd[1555]: Accepted publickey for core from 10.0.0.1 port 54460 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:32:56.820568 sshd-session[1555]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:32:56.824916 systemd-logind[1429]: New session 3 of user core. Nov 12 22:32:56.834095 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 12 22:32:56.880933 sshd[1557]: Connection closed by 10.0.0.1 port 54460 Nov 12 22:32:56.881313 sshd-session[1555]: pam_unix(sshd:session): session closed for user core Nov 12 22:32:56.891059 systemd[1]: sshd@2-10.0.0.78:22-10.0.0.1:54460.service: Deactivated successfully. Nov 12 22:32:56.892364 systemd[1]: session-3.scope: Deactivated successfully. Nov 12 22:32:56.894949 systemd-logind[1429]: Session 3 logged out. Waiting for processes to exit. Nov 12 22:32:56.896020 systemd[1]: Started sshd@3-10.0.0.78:22-10.0.0.1:54476.service - OpenSSH per-connection server daemon (10.0.0.1:54476). Nov 12 22:32:56.896581 systemd-logind[1429]: Removed session 3. Nov 12 22:32:56.939086 sshd[1562]: Accepted publickey for core from 10.0.0.1 port 54476 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:32:56.940376 sshd-session[1562]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:32:56.943403 systemd-logind[1429]: New session 4 of user core. Nov 12 22:32:56.957102 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 12 22:32:57.007659 sshd[1564]: Connection closed by 10.0.0.1 port 54476 Nov 12 22:32:57.007560 sshd-session[1562]: pam_unix(sshd:session): session closed for user core Nov 12 22:32:57.019159 systemd[1]: sshd@3-10.0.0.78:22-10.0.0.1:54476.service: Deactivated successfully. Nov 12 22:32:57.020433 systemd[1]: session-4.scope: Deactivated successfully. Nov 12 22:32:57.021611 systemd-logind[1429]: Session 4 logged out. Waiting for processes to exit. Nov 12 22:32:57.034231 systemd[1]: Started sshd@4-10.0.0.78:22-10.0.0.1:54478.service - OpenSSH per-connection server daemon (10.0.0.1:54478). Nov 12 22:32:57.035099 systemd-logind[1429]: Removed session 4. Nov 12 22:32:57.074319 sshd[1569]: Accepted publickey for core from 10.0.0.1 port 54478 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:32:57.075391 sshd-session[1569]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:32:57.079049 systemd-logind[1429]: New session 5 of user core. Nov 12 22:32:57.091083 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 12 22:32:57.147474 sudo[1572]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 12 22:32:57.147730 sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:32:57.165165 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 12 22:32:57.180300 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 12 22:32:57.180485 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 12 22:32:57.649400 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:32:57.659101 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:32:57.674692 systemd[1]: Reloading requested from client PID 1622 ('systemctl') (unit session-5.scope)... Nov 12 22:32:57.674707 systemd[1]: Reloading... Nov 12 22:32:57.744010 zram_generator::config[1660]: No configuration found. Nov 12 22:32:57.903774 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. Nov 12 22:32:57.953317 systemd[1]: Reloading finished in 278 ms. Nov 12 22:32:57.991240 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:32:57.993672 systemd[1]: kubelet.service: Deactivated successfully. Nov 12 22:32:57.993861 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:32:57.995238 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:32:58.088972 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:32:58.093215 (kubelet)[1707]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 12 22:32:58.130925 kubelet[1707]: 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. Nov 12 22:32:58.130925 kubelet[1707]: 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. Nov 12 22:32:58.130925 kubelet[1707]: 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. Nov 12 22:32:58.131250 kubelet[1707]: I1112 22:32:58.131027 1707 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" Nov 12 22:32:58.913310 kubelet[1707]: I1112 22:32:58.913266 1707 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Nov 12 22:32:58.913310 kubelet[1707]: I1112 22:32:58.913297 1707 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 12 22:32:58.913502 kubelet[1707]: I1112 22:32:58.913486 1707 server.go:927] "Client rotation is on, will bootstrap in background" Nov 12 22:32:58.949231 kubelet[1707]: I1112 22:32:58.949197 1707 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 12 22:32:58.957616 kubelet[1707]: I1112 22:32:58.957587 1707 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 12 22:32:58.958662 kubelet[1707]: I1112 22:32:58.958623 1707 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 12 22:32:58.958816 kubelet[1707]: I1112 22:32:58.958660 1707 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.78","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"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} Nov 12 22:32:58.958921 kubelet[1707]: I1112 22:32:58.958870 1707 topology_manager.go:138] "Creating topology manager with none policy" Nov 12 22:32:58.958921 kubelet[1707]: I1112 22:32:58.958879 1707 container_manager_linux.go:301] "Creating device plugin manager" Nov 12 22:32:58.959180 kubelet[1707]: I1112 22:32:58.959152 1707 state_mem.go:36] "Initialized new in-memory state store" Nov 12 22:32:58.960168 kubelet[1707]: I1112 22:32:58.960149 1707 kubelet.go:400] "Attempting to sync node with API server" Nov 12 22:32:58.960168 kubelet[1707]: I1112 22:32:58.960169 1707 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 12 22:32:58.960471 kubelet[1707]: I1112 22:32:58.960463 1707 kubelet.go:312] "Adding apiserver pod source" Nov 12 22:32:58.960783 kubelet[1707]: I1112 22:32:58.960600 1707 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 12 22:32:58.960783 kubelet[1707]: E1112 22:32:58.960617 1707 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:32:58.960783 kubelet[1707]: E1112 22:32:58.960741 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:32:58.961869 kubelet[1707]: I1112 22:32:58.961831 1707 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Nov 12 22:32:58.962369 kubelet[1707]: I1112 22:32:58.962352 1707 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 12 22:32:58.962528 kubelet[1707]: W1112 22:32:58.962515 1707 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 12 22:32:58.963460 kubelet[1707]: I1112 22:32:58.963442 1707 server.go:1264] "Started kubelet" Nov 12 22:32:58.964208 kubelet[1707]: I1112 22:32:58.963740 1707 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Nov 12 22:32:58.964208 kubelet[1707]: I1112 22:32:58.963971 1707 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 12 22:32:58.964371 kubelet[1707]: I1112 22:32:58.964337 1707 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 12 22:32:58.964968 kubelet[1707]: I1112 22:32:58.964569 1707 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 12 22:32:58.968453 kubelet[1707]: I1112 22:32:58.968427 1707 server.go:455] "Adding debug handlers to kubelet server" Nov 12 22:32:58.972555 kubelet[1707]: E1112 22:32:58.972333 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:58.972555 kubelet[1707]: I1112 22:32:58.972438 1707 volume_manager.go:291] "Starting Kubelet Volume Manager" Nov 12 22:32:58.972555 kubelet[1707]: I1112 22:32:58.972537 1707 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Nov 12 22:32:58.972674 kubelet[1707]: I1112 22:32:58.972664 1707 reconciler.go:26] "Reconciler: start to sync state" Nov 12 22:32:58.977018 kubelet[1707]: W1112 22:32:58.976198 1707 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.78" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 12 22:32:58.977018 kubelet[1707]: E1112 22:32:58.976233 1707 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.78" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 12 22:32:58.977018 kubelet[1707]: W1112 22:32:58.976279 1707 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 Nov 12 22:32:58.977018 kubelet[1707]: W1112 22:32:58.976388 1707 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 Nov 12 22:32:58.977018 kubelet[1707]: E1112 22:32:58.976464 1707 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 Nov 12 22:32:58.977018 kubelet[1707]: E1112 22:32:58.976504 1707 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 Nov 12 22:32:58.978415 kubelet[1707]: I1112 22:32:58.978384 1707 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 Nov 12 22:32:58.983038 kubelet[1707]: I1112 22:32:58.982980 1707 factory.go:221] Registration of the containerd container factory successfully Nov 12 22:32:58.983038 kubelet[1707]: I1112 22:32:58.983000 1707 factory.go:221] Registration of the systemd container factory successfully Nov 12 22:32:58.983204 kubelet[1707]: E1112 22:32:58.983057 1707 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 12 22:32:58.988616 kubelet[1707]: E1112 22:32:58.988579 1707 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.78\" not found" node="10.0.0.78" Nov 12 22:32:58.993955 kubelet[1707]: I1112 22:32:58.993890 1707 cpu_manager.go:214] "Starting CPU manager" policy="none" Nov 12 22:32:58.993955 kubelet[1707]: I1112 22:32:58.993945 1707 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Nov 12 22:32:58.993955 kubelet[1707]: I1112 22:32:58.993963 1707 state_mem.go:36] "Initialized new in-memory state store" Nov 12 22:32:59.059863 kubelet[1707]: I1112 22:32:59.059806 1707 policy_none.go:49] "None policy: Start" Nov 12 22:32:59.060764 kubelet[1707]: I1112 22:32:59.060744 1707 memory_manager.go:170] "Starting memorymanager" policy="None" Nov 12 22:32:59.060810 kubelet[1707]: I1112 22:32:59.060779 1707 state_mem.go:35] "Initializing new in-memory state store" Nov 12 22:32:59.066703 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 12 22:32:59.073831 kubelet[1707]: I1112 22:32:59.073793 1707 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.78" Nov 12 22:32:59.076995 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 12 22:32:59.077922 kubelet[1707]: I1112 22:32:59.077881 1707 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.78" Nov 12 22:32:59.080772 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 12 22:32:59.081803 kubelet[1707]: I1112 22:32:59.081672 1707 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 12 22:32:59.082923 kubelet[1707]: I1112 22:32:59.082697 1707 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 12 22:32:59.082923 kubelet[1707]: I1112 22:32:59.082794 1707 status_manager.go:217] "Starting to sync pod status with apiserver" Nov 12 22:32:59.082923 kubelet[1707]: I1112 22:32:59.082811 1707 kubelet.go:2337] "Starting kubelet main sync loop" Nov 12 22:32:59.083808 kubelet[1707]: E1112 22:32:59.082870 1707 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]" Nov 12 22:32:59.086219 kubelet[1707]: E1112 22:32:59.086194 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.087768 kubelet[1707]: I1112 22:32:59.087737 1707 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 12 22:32:59.088018 kubelet[1707]: I1112 22:32:59.087977 1707 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 12 22:32:59.088141 kubelet[1707]: I1112 22:32:59.088094 1707 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 12 22:32:59.090223 kubelet[1707]: E1112 22:32:59.090163 1707 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.78\" not found" Nov 12 22:32:59.110153 sudo[1572]: pam_unix(sudo:session): session closed for user root Nov 12 22:32:59.111698 sshd[1571]: Connection closed by 10.0.0.1 port 54478 Nov 12 22:32:59.112272 sshd-session[1569]: pam_unix(sshd:session): session closed for user core Nov 12 22:32:59.115271 systemd[1]: sshd@4-10.0.0.78:22-10.0.0.1:54478.service: Deactivated successfully. Nov 12 22:32:59.116837 systemd[1]: session-5.scope: Deactivated successfully. Nov 12 22:32:59.118210 systemd-logind[1429]: Session 5 logged out. Waiting for processes to exit. Nov 12 22:32:59.119498 systemd-logind[1429]: Removed session 5. Nov 12 22:32:59.187343 kubelet[1707]: E1112 22:32:59.187250 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.287760 kubelet[1707]: E1112 22:32:59.287716 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.388287 kubelet[1707]: E1112 22:32:59.388243 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.489212 kubelet[1707]: E1112 22:32:59.489127 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.589689 kubelet[1707]: E1112 22:32:59.589663 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.690298 kubelet[1707]: E1112 22:32:59.690263 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.791028 kubelet[1707]: E1112 22:32:59.790908 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.891473 kubelet[1707]: E1112 22:32:59.891439 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:32:59.915667 kubelet[1707]: I1112 22:32:59.915630 1707 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 12 22:32:59.915871 kubelet[1707]: W1112 22:32:59.915840 1707 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 Nov 12 22:32:59.961005 kubelet[1707]: E1112 22:32:59.960974 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:32:59.991558 kubelet[1707]: E1112 22:32:59.991520 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:33:00.092348 kubelet[1707]: E1112 22:33:00.092272 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:33:00.192794 kubelet[1707]: E1112 22:33:00.192766 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:33:00.293587 kubelet[1707]: E1112 22:33:00.293560 1707 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.78\" not found" Nov 12 22:33:00.394966 kubelet[1707]: I1112 22:33:00.394944 1707 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 12 22:33:00.395326 containerd[1448]: time="2024-11-12T22:33:00.395227117Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 12 22:33:00.395567 kubelet[1707]: I1112 22:33:00.395385 1707 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 12 22:33:00.961254 kubelet[1707]: E1112 22:33:00.961199 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:00.961254 kubelet[1707]: I1112 22:33:00.961218 1707 apiserver.go:52] "Watching apiserver" Nov 12 22:33:00.964777 kubelet[1707]: I1112 22:33:00.964720 1707 topology_manager.go:215] "Topology Admit Handler" podUID="8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c" podNamespace="kube-flannel" podName="kube-flannel-ds-w5xhp" Nov 12 22:33:00.964869 kubelet[1707]: I1112 22:33:00.964838 1707 topology_manager.go:215] "Topology Admit Handler" podUID="17b709c4-029d-4f9a-aae9-8d05f66c9b0c" podNamespace="kube-system" podName="kube-proxy-h42qf" Nov 12 22:33:00.971668 systemd[1]: Created slice kubepods-besteffort-pod17b709c4_029d_4f9a_aae9_8d05f66c9b0c.slice - libcontainer container kubepods-besteffort-pod17b709c4_029d_4f9a_aae9_8d05f66c9b0c.slice. Nov 12 22:33:00.974048 kubelet[1707]: I1112 22:33:00.974015 1707 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Nov 12 22:33:00.984518 kubelet[1707]: I1112 22:33:00.984486 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c-run\") pod \"kube-flannel-ds-w5xhp\" (UID: \"8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c\") " pod="kube-flannel/kube-flannel-ds-w5xhp" Nov 12 22:33:00.984598 kubelet[1707]: I1112 22:33:00.984523 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c-cni-plugin\") pod \"kube-flannel-ds-w5xhp\" (UID: \"8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c\") " pod="kube-flannel/kube-flannel-ds-w5xhp" Nov 12 22:33:00.984598 kubelet[1707]: I1112 22:33:00.984559 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c-flannel-cfg\") pod \"kube-flannel-ds-w5xhp\" (UID: \"8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c\") " pod="kube-flannel/kube-flannel-ds-w5xhp" Nov 12 22:33:00.984598 kubelet[1707]: I1112 22:33:00.984574 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c-xtables-lock\") pod \"kube-flannel-ds-w5xhp\" (UID: \"8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c\") " pod="kube-flannel/kube-flannel-ds-w5xhp" Nov 12 22:33:00.984665 kubelet[1707]: I1112 22:33:00.984622 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/17b709c4-029d-4f9a-aae9-8d05f66c9b0c-kube-proxy\") pod \"kube-proxy-h42qf\" (UID: \"17b709c4-029d-4f9a-aae9-8d05f66c9b0c\") " pod="kube-system/kube-proxy-h42qf" Nov 12 22:33:00.984665 kubelet[1707]: I1112 22:33:00.984649 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/17b709c4-029d-4f9a-aae9-8d05f66c9b0c-xtables-lock\") pod \"kube-proxy-h42qf\" (UID: \"17b709c4-029d-4f9a-aae9-8d05f66c9b0c\") " pod="kube-system/kube-proxy-h42qf" Nov 12 22:33:00.984718 kubelet[1707]: I1112 22:33:00.984664 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/17b709c4-029d-4f9a-aae9-8d05f66c9b0c-lib-modules\") pod \"kube-proxy-h42qf\" (UID: \"17b709c4-029d-4f9a-aae9-8d05f66c9b0c\") " pod="kube-system/kube-proxy-h42qf" Nov 12 22:33:00.984744 kubelet[1707]: I1112 22:33:00.984721 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hdzxl\" (UniqueName: \"kubernetes.io/projected/17b709c4-029d-4f9a-aae9-8d05f66c9b0c-kube-api-access-hdzxl\") pod \"kube-proxy-h42qf\" (UID: \"17b709c4-029d-4f9a-aae9-8d05f66c9b0c\") " pod="kube-system/kube-proxy-h42qf" Nov 12 22:33:00.984785 kubelet[1707]: I1112 22:33:00.984738 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c-cni\") pod \"kube-flannel-ds-w5xhp\" (UID: \"8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c\") " pod="kube-flannel/kube-flannel-ds-w5xhp" Nov 12 22:33:00.984817 kubelet[1707]: I1112 22:33:00.984805 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r25w9\" (UniqueName: \"kubernetes.io/projected/8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c-kube-api-access-r25w9\") pod \"kube-flannel-ds-w5xhp\" (UID: \"8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c\") " pod="kube-flannel/kube-flannel-ds-w5xhp" Nov 12 22:33:00.985514 systemd[1]: Created slice kubepods-burstable-pod8d235a0a_ffdc_4bb7_95f2_7490a70b2f6c.slice - libcontainer container kubepods-burstable-pod8d235a0a_ffdc_4bb7_95f2_7490a70b2f6c.slice. Nov 12 22:33:01.283967 kubelet[1707]: E1112 22:33:01.283845 1707 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" Nov 12 22:33:01.284597 containerd[1448]: time="2024-11-12T22:33:01.284546861Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-h42qf,Uid:17b709c4-029d-4f9a-aae9-8d05f66c9b0c,Namespace:kube-system,Attempt:0,}" Nov 12 22:33:01.301255 kubelet[1707]: E1112 22:33:01.301173 1707 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" Nov 12 22:33:01.301611 containerd[1448]: time="2024-11-12T22:33:01.301541361Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-w5xhp,Uid:8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c,Namespace:kube-flannel,Attempt:0,}" Nov 12 22:33:01.820502 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2288061738.mount: Deactivated successfully. Nov 12 22:33:01.826441 containerd[1448]: time="2024-11-12T22:33:01.826095227Z" 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\"}" Nov 12 22:33:01.828564 containerd[1448]: time="2024-11-12T22:33:01.828516216Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Nov 12 22:33:01.830417 containerd[1448]: time="2024-11-12T22:33:01.830380800Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 12 22:33:01.831471 containerd[1448]: time="2024-11-12T22:33:01.831447464Z" 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\"}" Nov 12 22:33:01.831661 containerd[1448]: time="2024-11-12T22:33:01.831637877Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 12 22:33:01.833511 containerd[1448]: time="2024-11-12T22:33:01.833453798Z" 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\"}" Nov 12 22:33:01.835231 containerd[1448]: time="2024-11-12T22:33:01.835174393Z" 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 550.54812ms" Nov 12 22:33:01.836106 containerd[1448]: time="2024-11-12T22:33:01.835972072Z" 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 534.135815ms" Nov 12 22:33:01.948733 containerd[1448]: time="2024-11-12T22:33:01.948606640Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:33:01.948733 containerd[1448]: time="2024-11-12T22:33:01.948679494Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:33:01.948733 containerd[1448]: time="2024-11-12T22:33:01.948696528Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:01.949098 containerd[1448]: time="2024-11-12T22:33:01.949020094Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:01.949402 containerd[1448]: time="2024-11-12T22:33:01.949061120Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:33:01.949402 containerd[1448]: time="2024-11-12T22:33:01.949112502Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:33:01.949402 containerd[1448]: time="2024-11-12T22:33:01.949127097Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:01.949402 containerd[1448]: time="2024-11-12T22:33:01.949230060Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:01.961776 kubelet[1707]: E1112 22:33:01.961709 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:02.057138 systemd[1]: Started cri-containerd-0964d676b76a9bd85102b45904519f287f0abfa274f798e3c081f6854a3b391e.scope - libcontainer container 0964d676b76a9bd85102b45904519f287f0abfa274f798e3c081f6854a3b391e. Nov 12 22:33:02.059732 systemd[1]: Started cri-containerd-f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d.scope - libcontainer container f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d. Nov 12 22:33:02.078159 containerd[1448]: time="2024-11-12T22:33:02.078053336Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-h42qf,Uid:17b709c4-029d-4f9a-aae9-8d05f66c9b0c,Namespace:kube-system,Attempt:0,} returns sandbox id \"0964d676b76a9bd85102b45904519f287f0abfa274f798e3c081f6854a3b391e\"" Nov 12 22:33:02.079528 kubelet[1707]: E1112 22:33:02.079501 1707 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" Nov 12 22:33:02.081174 containerd[1448]: time="2024-11-12T22:33:02.081131001Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.6\"" Nov 12 22:33:02.088144 containerd[1448]: time="2024-11-12T22:33:02.088113818Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-w5xhp,Uid:8d235a0a-ffdc-4bb7-95f2-7490a70b2f6c,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d\"" Nov 12 22:33:02.088836 kubelet[1707]: E1112 22:33:02.088578 1707 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" Nov 12 22:33:02.962026 kubelet[1707]: E1112 22:33:02.961975 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:03.106770 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount838331372.mount: Deactivated successfully. Nov 12 22:33:03.311169 containerd[1448]: time="2024-11-12T22:33:03.311039249Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.6\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:03.312086 containerd[1448]: time="2024-11-12T22:33:03.312027143Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.6: active requests=0, bytes read=25660280" Nov 12 22:33:03.312982 containerd[1448]: time="2024-11-12T22:33:03.312943020Z" level=info msg="ImageCreate event name:\"sha256:95ea5eecb1c87350e3f1d3aa5e1e9aef277acc9b38dff12db3f7e97141ccb494\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:03.314929 containerd[1448]: time="2024-11-12T22:33:03.314855189Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:aaf790f611159ab21713affc2c5676f742c9b31db26dd2e61e46c4257dd11b76\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:03.315930 containerd[1448]: time="2024-11-12T22:33:03.315740435Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.6\" with image id \"sha256:95ea5eecb1c87350e3f1d3aa5e1e9aef277acc9b38dff12db3f7e97141ccb494\", repo tag \"registry.k8s.io/kube-proxy:v1.30.6\", repo digest \"registry.k8s.io/kube-proxy@sha256:aaf790f611159ab21713affc2c5676f742c9b31db26dd2e61e46c4257dd11b76\", size \"25659297\" in 1.234572086s" Nov 12 22:33:03.315930 containerd[1448]: time="2024-11-12T22:33:03.315773825Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.6\" returns image reference \"sha256:95ea5eecb1c87350e3f1d3aa5e1e9aef277acc9b38dff12db3f7e97141ccb494\"" Nov 12 22:33:03.317236 containerd[1448]: time="2024-11-12T22:33:03.317167234Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 12 22:33:03.318620 containerd[1448]: time="2024-11-12T22:33:03.318593993Z" level=info msg="CreateContainer within sandbox \"0964d676b76a9bd85102b45904519f287f0abfa274f798e3c081f6854a3b391e\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 12 22:33:03.333472 containerd[1448]: time="2024-11-12T22:33:03.333412210Z" level=info msg="CreateContainer within sandbox \"0964d676b76a9bd85102b45904519f287f0abfa274f798e3c081f6854a3b391e\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"9346d6a1d50a0e7c27d963114beea6e0b666266d7e5ddac4079516565c82453c\"" Nov 12 22:33:03.336889 containerd[1448]: time="2024-11-12T22:33:03.336825035Z" level=info msg="StartContainer for \"9346d6a1d50a0e7c27d963114beea6e0b666266d7e5ddac4079516565c82453c\"" Nov 12 22:33:03.372112 systemd[1]: Started cri-containerd-9346d6a1d50a0e7c27d963114beea6e0b666266d7e5ddac4079516565c82453c.scope - libcontainer container 9346d6a1d50a0e7c27d963114beea6e0b666266d7e5ddac4079516565c82453c. Nov 12 22:33:03.401812 containerd[1448]: time="2024-11-12T22:33:03.401757395Z" level=info msg="StartContainer for \"9346d6a1d50a0e7c27d963114beea6e0b666266d7e5ddac4079516565c82453c\" returns successfully" Nov 12 22:33:03.962932 kubelet[1707]: E1112 22:33:03.962888 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:04.095458 kubelet[1707]: E1112 22:33:04.095365 1707 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" Nov 12 22:33:04.106717 kubelet[1707]: I1112 22:33:04.106652 1707 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-h42qf" podStartSLOduration=3.870262822 podStartE2EDuration="5.106638055s" podCreationTimestamp="2024-11-12 22:32:59 +0000 UTC" firstStartedPulling="2024-11-12 22:33:02.08043835 +0000 UTC m=+3.984013901" lastFinishedPulling="2024-11-12 22:33:03.316813583 +0000 UTC m=+5.220389134" observedRunningTime="2024-11-12 22:33:04.105078787 +0000 UTC m=+6.008654338" watchObservedRunningTime="2024-11-12 22:33:04.106638055 +0000 UTC m=+6.010213606" Nov 12 22:33:04.605180 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2446881402.mount: Deactivated successfully. Nov 12 22:33:04.629481 containerd[1448]: time="2024-11-12T22:33:04.629438089Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:04.630348 containerd[1448]: time="2024-11-12T22:33:04.630116053Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673531" Nov 12 22:33:04.630989 containerd[1448]: time="2024-11-12T22:33:04.630951491Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:04.633336 containerd[1448]: time="2024-11-12T22:33:04.633307807Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:04.634259 containerd[1448]: time="2024-11-12T22:33:04.634225501Z" 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.317026677s" Nov 12 22:33:04.634259 containerd[1448]: time="2024-11-12T22:33:04.634258572Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 12 22:33:04.636174 containerd[1448]: time="2024-11-12T22:33:04.636145065Z" level=info msg="CreateContainer within sandbox \"f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 12 22:33:04.645729 containerd[1448]: time="2024-11-12T22:33:04.645691377Z" level=info msg="CreateContainer within sandbox \"f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec\"" Nov 12 22:33:04.646179 containerd[1448]: time="2024-11-12T22:33:04.646151524Z" level=info msg="StartContainer for \"e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec\"" Nov 12 22:33:04.672124 systemd[1]: Started cri-containerd-e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec.scope - libcontainer container e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec. Nov 12 22:33:04.692247 containerd[1448]: time="2024-11-12T22:33:04.692172742Z" level=info msg="StartContainer for \"e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec\" returns successfully" Nov 12 22:33:04.696366 systemd[1]: cri-containerd-e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec.scope: Deactivated successfully. Nov 12 22:33:04.839673 containerd[1448]: time="2024-11-12T22:33:04.839592763Z" level=info msg="shim disconnected" id=e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec namespace=k8s.io Nov 12 22:33:04.839673 containerd[1448]: time="2024-11-12T22:33:04.839650186Z" level=warning msg="cleaning up after shim disconnected" id=e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec namespace=k8s.io Nov 12 22:33:04.839673 containerd[1448]: time="2024-11-12T22:33:04.839662263Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 22:33:04.963761 kubelet[1707]: E1112 22:33:04.963716 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:05.097492 kubelet[1707]: E1112 22:33:05.097445 1707 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" Nov 12 22:33:05.097492 kubelet[1707]: E1112 22:33:05.097484 1707 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" Nov 12 22:33:05.098328 containerd[1448]: time="2024-11-12T22:33:05.098286802Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 12 22:33:05.555230 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e016ae68c7227555ab4a74708c98e51d0b201b1d8e568f8f210c609cd7ca91ec-rootfs.mount: Deactivated successfully. Nov 12 22:33:05.964787 kubelet[1707]: E1112 22:33:05.964707 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:06.284883 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1207324119.mount: Deactivated successfully. Nov 12 22:33:06.717999 containerd[1448]: time="2024-11-12T22:33:06.717953380Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:06.718400 containerd[1448]: time="2024-11-12T22:33:06.718362836Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Nov 12 22:33:06.719278 containerd[1448]: time="2024-11-12T22:33:06.719219218Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:06.722207 containerd[1448]: time="2024-11-12T22:33:06.722149991Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:06.723389 containerd[1448]: time="2024-11-12T22:33:06.723299818Z" 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.624960309s" Nov 12 22:33:06.723389 containerd[1448]: time="2024-11-12T22:33:06.723335369Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 12 22:33:06.725665 containerd[1448]: time="2024-11-12T22:33:06.725563681Z" level=info msg="CreateContainer within sandbox \"f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 12 22:33:06.736791 containerd[1448]: time="2024-11-12T22:33:06.736756429Z" level=info msg="CreateContainer within sandbox \"f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879\"" Nov 12 22:33:06.737367 containerd[1448]: time="2024-11-12T22:33:06.737297252Z" level=info msg="StartContainer for \"42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879\"" Nov 12 22:33:06.764116 systemd[1]: Started cri-containerd-42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879.scope - libcontainer container 42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879. Nov 12 22:33:06.785358 containerd[1448]: time="2024-11-12T22:33:06.785291103Z" level=info msg="StartContainer for \"42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879\" returns successfully" Nov 12 22:33:06.790354 systemd[1]: cri-containerd-42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879.scope: Deactivated successfully. Nov 12 22:33:06.803111 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879-rootfs.mount: Deactivated successfully. Nov 12 22:33:06.805651 kubelet[1707]: I1112 22:33:06.805573 1707 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Nov 12 22:33:06.897095 containerd[1448]: time="2024-11-12T22:33:06.897034191Z" level=info msg="shim disconnected" id=42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879 namespace=k8s.io Nov 12 22:33:06.897095 containerd[1448]: time="2024-11-12T22:33:06.897089577Z" level=warning msg="cleaning up after shim disconnected" id=42b1c9eeacca282024e40fbfbae4b6b9adea662c3cc1f3029d94a8c0c6528879 namespace=k8s.io Nov 12 22:33:06.897095 containerd[1448]: time="2024-11-12T22:33:06.897098495Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 22:33:06.965119 kubelet[1707]: E1112 22:33:06.965069 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:07.102251 kubelet[1707]: E1112 22:33:07.101811 1707 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" Nov 12 22:33:07.104395 containerd[1448]: time="2024-11-12T22:33:07.104356779Z" level=info msg="CreateContainer within sandbox \"f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 12 22:33:07.115677 containerd[1448]: time="2024-11-12T22:33:07.115588456Z" level=info msg="CreateContainer within sandbox \"f2c0b0a14e41d01b45d3be4b33611a7a6d02b786fd6ada8c57a63fa156c6842d\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"cda9ed769fd81e5cf02d7550821e6ed9d938459e805fb8b775ec10279f67092f\"" Nov 12 22:33:07.116335 containerd[1448]: time="2024-11-12T22:33:07.116059424Z" level=info msg="StartContainer for \"cda9ed769fd81e5cf02d7550821e6ed9d938459e805fb8b775ec10279f67092f\"" Nov 12 22:33:07.137032 systemd[1]: Started cri-containerd-cda9ed769fd81e5cf02d7550821e6ed9d938459e805fb8b775ec10279f67092f.scope - libcontainer container cda9ed769fd81e5cf02d7550821e6ed9d938459e805fb8b775ec10279f67092f. Nov 12 22:33:07.158765 containerd[1448]: time="2024-11-12T22:33:07.158694600Z" level=info msg="StartContainer for \"cda9ed769fd81e5cf02d7550821e6ed9d938459e805fb8b775ec10279f67092f\" returns successfully" Nov 12 22:33:07.965640 kubelet[1707]: E1112 22:33:07.965591 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:08.105716 kubelet[1707]: E1112 22:33:08.105666 1707 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" Nov 12 22:33:08.246659 systemd-networkd[1385]: flannel.1: Link UP Nov 12 22:33:08.246668 systemd-networkd[1385]: flannel.1: Gained carrier Nov 12 22:33:08.966609 kubelet[1707]: E1112 22:33:08.966567 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:09.107024 kubelet[1707]: E1112 22:33:09.106994 1707 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" Nov 12 22:33:09.843121 systemd-networkd[1385]: flannel.1: Gained IPv6LL Nov 12 22:33:09.894207 kubelet[1707]: I1112 22:33:09.894144 1707 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-w5xhp" podStartSLOduration=6.258866028 podStartE2EDuration="10.894124757s" podCreationTimestamp="2024-11-12 22:32:59 +0000 UTC" firstStartedPulling="2024-11-12 22:33:02.089009322 +0000 UTC m=+3.992584873" lastFinishedPulling="2024-11-12 22:33:06.724268051 +0000 UTC m=+8.627843602" observedRunningTime="2024-11-12 22:33:08.1142962 +0000 UTC m=+10.017871751" watchObservedRunningTime="2024-11-12 22:33:09.894124757 +0000 UTC m=+11.797700308" Nov 12 22:33:09.894402 kubelet[1707]: I1112 22:33:09.894366 1707 topology_manager.go:215] "Topology Admit Handler" podUID="9c0951f6-12b8-4f43-a0d5-66cb46cf75aa" podNamespace="default" podName="nginx-deployment-85f456d6dd-t6xhw" Nov 12 22:33:09.899823 systemd[1]: Created slice kubepods-besteffort-pod9c0951f6_12b8_4f43_a0d5_66cb46cf75aa.slice - libcontainer container kubepods-besteffort-pod9c0951f6_12b8_4f43_a0d5_66cb46cf75aa.slice. Nov 12 22:33:09.939084 kubelet[1707]: I1112 22:33:09.939046 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5wqnw\" (UniqueName: \"kubernetes.io/projected/9c0951f6-12b8-4f43-a0d5-66cb46cf75aa-kube-api-access-5wqnw\") pod \"nginx-deployment-85f456d6dd-t6xhw\" (UID: \"9c0951f6-12b8-4f43-a0d5-66cb46cf75aa\") " pod="default/nginx-deployment-85f456d6dd-t6xhw" Nov 12 22:33:09.967587 kubelet[1707]: E1112 22:33:09.967529 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:10.203421 containerd[1448]: time="2024-11-12T22:33:10.203373981Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-t6xhw,Uid:9c0951f6-12b8-4f43-a0d5-66cb46cf75aa,Namespace:default,Attempt:0,}" Nov 12 22:33:10.230680 systemd-networkd[1385]: cni0: Link UP Nov 12 22:33:10.230686 systemd-networkd[1385]: cni0: Gained carrier Nov 12 22:33:10.230929 systemd-networkd[1385]: cni0: Lost carrier Nov 12 22:33:10.237089 systemd-networkd[1385]: veth4a56767b: Link UP Nov 12 22:33:10.239038 kernel: cni0: port 1(veth4a56767b) entered blocking state Nov 12 22:33:10.239108 kernel: cni0: port 1(veth4a56767b) entered disabled state Nov 12 22:33:10.240471 kernel: veth4a56767b: entered allmulticast mode Nov 12 22:33:10.240508 kernel: veth4a56767b: entered promiscuous mode Nov 12 22:33:10.241331 kernel: cni0: port 1(veth4a56767b) entered blocking state Nov 12 22:33:10.241369 kernel: cni0: port 1(veth4a56767b) entered forwarding state Nov 12 22:33:10.244925 kernel: cni0: port 1(veth4a56767b) entered disabled state Nov 12 22:33:10.254519 kernel: cni0: port 1(veth4a56767b) entered blocking state Nov 12 22:33:10.254564 kernel: cni0: port 1(veth4a56767b) entered forwarding state Nov 12 22:33:10.254646 systemd-networkd[1385]: veth4a56767b: Gained carrier Nov 12 22:33:10.254925 systemd-networkd[1385]: cni0: Gained carrier Nov 12 22:33:10.256083 containerd[1448]: 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"} Nov 12 22:33:10.256083 containerd[1448]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:33:10.274843 containerd[1448]: {"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-11-12T22:33:10.274444393Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:33:10.274843 containerd[1448]: time="2024-11-12T22:33:10.274826078Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:33:10.274843 containerd[1448]: time="2024-11-12T22:33:10.274839475Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:10.275048 containerd[1448]: time="2024-11-12T22:33:10.274928818Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:10.294053 systemd[1]: Started cri-containerd-cf7766d17148bcbf87fc2cd6fb61b322e170474e2aca0f38ff2f245ca8602895.scope - libcontainer container cf7766d17148bcbf87fc2cd6fb61b322e170474e2aca0f38ff2f245ca8602895. Nov 12 22:33:10.303579 systemd-resolved[1312]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 12 22:33:10.319292 containerd[1448]: time="2024-11-12T22:33:10.319261492Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-t6xhw,Uid:9c0951f6-12b8-4f43-a0d5-66cb46cf75aa,Namespace:default,Attempt:0,} returns sandbox id \"cf7766d17148bcbf87fc2cd6fb61b322e170474e2aca0f38ff2f245ca8602895\"" Nov 12 22:33:10.320711 containerd[1448]: time="2024-11-12T22:33:10.320631543Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 22:33:10.967662 kubelet[1707]: E1112 22:33:10.967623 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:11.968822 kubelet[1707]: E1112 22:33:11.968712 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:11.981692 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4066745353.mount: Deactivated successfully. Nov 12 22:33:12.019023 systemd-networkd[1385]: cni0: Gained IPv6LL Nov 12 22:33:12.211017 systemd-networkd[1385]: veth4a56767b: Gained IPv6LL Nov 12 22:33:12.543165 containerd[1448]: time="2024-11-12T22:33:12.543112117Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:12.543734 containerd[1448]: time="2024-11-12T22:33:12.543688458Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67683180" Nov 12 22:33:12.544590 containerd[1448]: time="2024-11-12T22:33:12.544558507Z" level=info msg="ImageCreate event name:\"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:12.547685 containerd[1448]: time="2024-11-12T22:33:12.547632616Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:12.550265 containerd[1448]: time="2024-11-12T22:33:12.550207450Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"67683058\" in 2.229543153s" Nov 12 22:33:12.550265 containerd[1448]: time="2024-11-12T22:33:12.550251363Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 22:33:12.552075 containerd[1448]: time="2024-11-12T22:33:12.551954308Z" level=info msg="CreateContainer within sandbox \"cf7766d17148bcbf87fc2cd6fb61b322e170474e2aca0f38ff2f245ca8602895\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 12 22:33:12.565518 containerd[1448]: time="2024-11-12T22:33:12.565437416Z" level=info msg="CreateContainer within sandbox \"cf7766d17148bcbf87fc2cd6fb61b322e170474e2aca0f38ff2f245ca8602895\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"a3a8aa438cbee9e388859cf340b05a80f26c8712efb244b144779bcaf728141c\"" Nov 12 22:33:12.566671 containerd[1448]: time="2024-11-12T22:33:12.565941049Z" level=info msg="StartContainer for \"a3a8aa438cbee9e388859cf340b05a80f26c8712efb244b144779bcaf728141c\"" Nov 12 22:33:12.595094 systemd[1]: Started cri-containerd-a3a8aa438cbee9e388859cf340b05a80f26c8712efb244b144779bcaf728141c.scope - libcontainer container a3a8aa438cbee9e388859cf340b05a80f26c8712efb244b144779bcaf728141c. Nov 12 22:33:12.622504 containerd[1448]: time="2024-11-12T22:33:12.622464111Z" level=info msg="StartContainer for \"a3a8aa438cbee9e388859cf340b05a80f26c8712efb244b144779bcaf728141c\" returns successfully" Nov 12 22:33:12.969786 kubelet[1707]: E1112 22:33:12.969738 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:13.123622 kubelet[1707]: I1112 22:33:13.123567 1707 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-t6xhw" podStartSLOduration=1.893130937 podStartE2EDuration="4.123552453s" podCreationTimestamp="2024-11-12 22:33:09 +0000 UTC" firstStartedPulling="2024-11-12 22:33:10.320417225 +0000 UTC m=+12.223992776" lastFinishedPulling="2024-11-12 22:33:12.550838741 +0000 UTC m=+14.454414292" observedRunningTime="2024-11-12 22:33:13.123484304 +0000 UTC m=+15.027059855" watchObservedRunningTime="2024-11-12 22:33:13.123552453 +0000 UTC m=+15.027128004" Nov 12 22:33:13.970050 kubelet[1707]: E1112 22:33:13.970002 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:14.970483 kubelet[1707]: E1112 22:33:14.970434 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:15.970831 kubelet[1707]: E1112 22:33:15.970758 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:16.865072 kubelet[1707]: I1112 22:33:16.864928 1707 topology_manager.go:215] "Topology Admit Handler" podUID="aa0ebfa1-80b3-4b0b-94d8-21c4309298e4" podNamespace="default" podName="nfs-server-provisioner-0" Nov 12 22:33:16.870376 systemd[1]: Created slice kubepods-besteffort-podaa0ebfa1_80b3_4b0b_94d8_21c4309298e4.slice - libcontainer container kubepods-besteffort-podaa0ebfa1_80b3_4b0b_94d8_21c4309298e4.slice. Nov 12 22:33:16.971178 kubelet[1707]: E1112 22:33:16.971132 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:16.978832 kubelet[1707]: I1112 22:33:16.978803 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/aa0ebfa1-80b3-4b0b-94d8-21c4309298e4-data\") pod \"nfs-server-provisioner-0\" (UID: \"aa0ebfa1-80b3-4b0b-94d8-21c4309298e4\") " pod="default/nfs-server-provisioner-0" Nov 12 22:33:16.978921 kubelet[1707]: I1112 22:33:16.978842 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2rknn\" (UniqueName: \"kubernetes.io/projected/aa0ebfa1-80b3-4b0b-94d8-21c4309298e4-kube-api-access-2rknn\") pod \"nfs-server-provisioner-0\" (UID: \"aa0ebfa1-80b3-4b0b-94d8-21c4309298e4\") " pod="default/nfs-server-provisioner-0" Nov 12 22:33:17.173360 containerd[1448]: time="2024-11-12T22:33:17.173313246Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:aa0ebfa1-80b3-4b0b-94d8-21c4309298e4,Namespace:default,Attempt:0,}" Nov 12 22:33:17.190505 systemd-networkd[1385]: vethf36145ce: Link UP Nov 12 22:33:17.192343 kernel: cni0: port 2(vethf36145ce) entered blocking state Nov 12 22:33:17.192417 kernel: cni0: port 2(vethf36145ce) entered disabled state Nov 12 22:33:17.192449 kernel: vethf36145ce: entered allmulticast mode Nov 12 22:33:17.192468 kernel: vethf36145ce: entered promiscuous mode Nov 12 22:33:17.199175 kernel: cni0: port 2(vethf36145ce) entered blocking state Nov 12 22:33:17.199264 kernel: cni0: port 2(vethf36145ce) entered forwarding state Nov 12 22:33:17.199443 systemd-networkd[1385]: vethf36145ce: Gained carrier Nov 12 22:33:17.202230 containerd[1448]: 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"} Nov 12 22:33:17.202230 containerd[1448]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:33:17.217201 containerd[1448]: {"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-11-12T22:33:17.217063086Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:33:17.217722 containerd[1448]: time="2024-11-12T22:33:17.217658611Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:33:17.217722 containerd[1448]: time="2024-11-12T22:33:17.217699646Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:17.217942 containerd[1448]: time="2024-11-12T22:33:17.217879584Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:17.237106 systemd[1]: Started cri-containerd-9fed4b58a3a4ca2473a6868f92c73832349868553da3c02323601e30ce5caf69.scope - libcontainer container 9fed4b58a3a4ca2473a6868f92c73832349868553da3c02323601e30ce5caf69. Nov 12 22:33:17.246937 systemd-resolved[1312]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 12 22:33:17.263794 containerd[1448]: time="2024-11-12T22:33:17.263751718Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:aa0ebfa1-80b3-4b0b-94d8-21c4309298e4,Namespace:default,Attempt:0,} returns sandbox id \"9fed4b58a3a4ca2473a6868f92c73832349868553da3c02323601e30ce5caf69\"" Nov 12 22:33:17.265445 containerd[1448]: time="2024-11-12T22:33:17.265378274Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 12 22:33:17.972094 kubelet[1707]: E1112 22:33:17.972003 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:18.419030 systemd-networkd[1385]: vethf36145ce: Gained IPv6LL Nov 12 22:33:18.960976 kubelet[1707]: E1112 22:33:18.960919 1707 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:18.972138 kubelet[1707]: E1112 22:33:18.972108 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:19.639449 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2693166725.mount: Deactivated successfully. Nov 12 22:33:19.972503 kubelet[1707]: E1112 22:33:19.972455 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:20.972747 kubelet[1707]: E1112 22:33:20.972711 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:20.983263 containerd[1448]: time="2024-11-12T22:33:20.983051560Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:20.984270 containerd[1448]: time="2024-11-12T22:33:20.984218399Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Nov 12 22:33:20.985057 containerd[1448]: time="2024-11-12T22:33:20.985025836Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:20.987622 containerd[1448]: time="2024-11-12T22:33:20.987561094Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:20.988775 containerd[1448]: time="2024-11-12T22:33:20.988699657Z" 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.723283748s" Nov 12 22:33:20.988775 containerd[1448]: time="2024-11-12T22:33:20.988732573Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 12 22:33:20.991242 containerd[1448]: time="2024-11-12T22:33:20.991131366Z" level=info msg="CreateContainer within sandbox \"9fed4b58a3a4ca2473a6868f92c73832349868553da3c02323601e30ce5caf69\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 12 22:33:21.001201 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2038180343.mount: Deactivated successfully. Nov 12 22:33:21.002826 containerd[1448]: time="2024-11-12T22:33:21.002771776Z" level=info msg="CreateContainer within sandbox \"9fed4b58a3a4ca2473a6868f92c73832349868553da3c02323601e30ce5caf69\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ad86a87e904f581091ee453e08f5d9e28a8fc56eeb032a0e75db248aaa80f429\"" Nov 12 22:33:21.003207 containerd[1448]: time="2024-11-12T22:33:21.003173777Z" level=info msg="StartContainer for \"ad86a87e904f581091ee453e08f5d9e28a8fc56eeb032a0e75db248aaa80f429\"" Nov 12 22:33:21.029065 systemd[1]: Started cri-containerd-ad86a87e904f581091ee453e08f5d9e28a8fc56eeb032a0e75db248aaa80f429.scope - libcontainer container ad86a87e904f581091ee453e08f5d9e28a8fc56eeb032a0e75db248aaa80f429. Nov 12 22:33:21.048053 containerd[1448]: time="2024-11-12T22:33:21.048008279Z" level=info msg="StartContainer for \"ad86a87e904f581091ee453e08f5d9e28a8fc56eeb032a0e75db248aaa80f429\" returns successfully" Nov 12 22:33:21.141823 kubelet[1707]: I1112 22:33:21.141758 1707 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.417121285 podStartE2EDuration="5.141744289s" podCreationTimestamp="2024-11-12 22:33:16 +0000 UTC" firstStartedPulling="2024-11-12 22:33:17.265133544 +0000 UTC m=+19.168709095" lastFinishedPulling="2024-11-12 22:33:20.989756548 +0000 UTC m=+22.893332099" observedRunningTime="2024-11-12 22:33:21.141060036 +0000 UTC m=+23.044635547" watchObservedRunningTime="2024-11-12 22:33:21.141744289 +0000 UTC m=+23.045319840" Nov 12 22:33:21.973918 kubelet[1707]: E1112 22:33:21.973859 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:22.974086 kubelet[1707]: E1112 22:33:22.974011 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:23.974553 kubelet[1707]: E1112 22:33:23.974510 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:24.975149 kubelet[1707]: E1112 22:33:24.975099 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:25.975493 kubelet[1707]: E1112 22:33:25.975439 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:26.976445 kubelet[1707]: E1112 22:33:26.976407 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:27.977264 kubelet[1707]: E1112 22:33:27.977216 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:28.977372 kubelet[1707]: E1112 22:33:28.977312 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:29.978177 kubelet[1707]: E1112 22:33:29.978123 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:30.834251 kubelet[1707]: I1112 22:33:30.834199 1707 topology_manager.go:215] "Topology Admit Handler" podUID="2322a725-67b3-4b67-8987-6d6e19e2886d" podNamespace="default" podName="test-pod-1" Nov 12 22:33:30.840986 systemd[1]: Created slice kubepods-besteffort-pod2322a725_67b3_4b67_8987_6d6e19e2886d.slice - libcontainer container kubepods-besteffort-pod2322a725_67b3_4b67_8987_6d6e19e2886d.slice. Nov 12 22:33:30.854269 kubelet[1707]: I1112 22:33:30.854209 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c2e32b26-9c26-4876-9cb4-6d0654e7af16\" (UniqueName: \"kubernetes.io/nfs/2322a725-67b3-4b67-8987-6d6e19e2886d-pvc-c2e32b26-9c26-4876-9cb4-6d0654e7af16\") pod \"test-pod-1\" (UID: \"2322a725-67b3-4b67-8987-6d6e19e2886d\") " pod="default/test-pod-1" Nov 12 22:33:30.854381 kubelet[1707]: I1112 22:33:30.854279 1707 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vc6x6\" (UniqueName: \"kubernetes.io/projected/2322a725-67b3-4b67-8987-6d6e19e2886d-kube-api-access-vc6x6\") pod \"test-pod-1\" (UID: \"2322a725-67b3-4b67-8987-6d6e19e2886d\") " pod="default/test-pod-1" Nov 12 22:33:30.978592 kubelet[1707]: E1112 22:33:30.978564 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:30.983972 kernel: FS-Cache: Loaded Nov 12 22:33:31.007388 kernel: RPC: Registered named UNIX socket transport module. Nov 12 22:33:31.007493 kernel: RPC: Registered udp transport module. Nov 12 22:33:31.008096 kernel: RPC: Registered tcp transport module. Nov 12 22:33:31.009564 kernel: RPC: Registered tcp-with-tls transport module. Nov 12 22:33:31.009624 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 12 22:33:31.189107 kernel: NFS: Registering the id_resolver key type Nov 12 22:33:31.189229 kernel: Key type id_resolver registered Nov 12 22:33:31.189269 kernel: Key type id_legacy registered Nov 12 22:33:31.216733 nfsidmap[2740]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 12 22:33:31.219835 nfsidmap[2743]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 12 22:33:31.443619 containerd[1448]: time="2024-11-12T22:33:31.443528925Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2322a725-67b3-4b67-8987-6d6e19e2886d,Namespace:default,Attempt:0,}" Nov 12 22:33:31.460579 systemd-networkd[1385]: vethd85e49c5: Link UP Nov 12 22:33:31.462490 kernel: cni0: port 3(vethd85e49c5) entered blocking state Nov 12 22:33:31.462538 kernel: cni0: port 3(vethd85e49c5) entered disabled state Nov 12 22:33:31.462574 kernel: vethd85e49c5: entered allmulticast mode Nov 12 22:33:31.463920 kernel: vethd85e49c5: entered promiscuous mode Nov 12 22:33:31.469562 kernel: cni0: port 3(vethd85e49c5) entered blocking state Nov 12 22:33:31.469668 kernel: cni0: port 3(vethd85e49c5) entered forwarding state Nov 12 22:33:31.469653 systemd-networkd[1385]: vethd85e49c5: Gained carrier Nov 12 22:33:31.470968 containerd[1448]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000012938), "name":"cbr0", "type":"bridge"} Nov 12 22:33:31.470968 containerd[1448]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:33:31.485540 containerd[1448]: {"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-11-12T22:33:31.485447718Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:33:31.485540 containerd[1448]: time="2024-11-12T22:33:31.485510714Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:33:31.485540 containerd[1448]: time="2024-11-12T22:33:31.485522954Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:31.485810 containerd[1448]: time="2024-11-12T22:33:31.485764981Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:33:31.501052 systemd[1]: Started cri-containerd-4b6d77b8171c7ba2deccc7d963d03108f034e0df1143048f21e9c522983546e0.scope - libcontainer container 4b6d77b8171c7ba2deccc7d963d03108f034e0df1143048f21e9c522983546e0. Nov 12 22:33:31.510324 systemd-resolved[1312]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 12 22:33:31.525236 containerd[1448]: time="2024-11-12T22:33:31.525127504Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2322a725-67b3-4b67-8987-6d6e19e2886d,Namespace:default,Attempt:0,} returns sandbox id \"4b6d77b8171c7ba2deccc7d963d03108f034e0df1143048f21e9c522983546e0\"" Nov 12 22:33:31.526554 containerd[1448]: time="2024-11-12T22:33:31.526518913Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 22:33:31.816887 containerd[1448]: time="2024-11-12T22:33:31.816782224Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:33:31.817424 containerd[1448]: time="2024-11-12T22:33:31.817346156Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 12 22:33:31.820469 containerd[1448]: time="2024-11-12T22:33:31.820440599Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"67683058\" in 293.890287ms" Nov 12 22:33:31.820469 containerd[1448]: time="2024-11-12T22:33:31.820470277Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 22:33:31.822504 containerd[1448]: time="2024-11-12T22:33:31.822457776Z" level=info msg="CreateContainer within sandbox \"4b6d77b8171c7ba2deccc7d963d03108f034e0df1143048f21e9c522983546e0\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 12 22:33:31.832985 containerd[1448]: time="2024-11-12T22:33:31.832927325Z" level=info msg="CreateContainer within sandbox \"4b6d77b8171c7ba2deccc7d963d03108f034e0df1143048f21e9c522983546e0\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"47e8afd03a972e87e4003a40304e8d620a79cdc37caa839dc67894ce09b16c20\"" Nov 12 22:33:31.833407 containerd[1448]: time="2024-11-12T22:33:31.833382462Z" level=info msg="StartContainer for \"47e8afd03a972e87e4003a40304e8d620a79cdc37caa839dc67894ce09b16c20\"" Nov 12 22:33:31.859031 systemd[1]: Started cri-containerd-47e8afd03a972e87e4003a40304e8d620a79cdc37caa839dc67894ce09b16c20.scope - libcontainer container 47e8afd03a972e87e4003a40304e8d620a79cdc37caa839dc67894ce09b16c20. Nov 12 22:33:31.877506 containerd[1448]: time="2024-11-12T22:33:31.877443786Z" level=info msg="StartContainer for \"47e8afd03a972e87e4003a40304e8d620a79cdc37caa839dc67894ce09b16c20\" returns successfully" Nov 12 22:33:31.979823 kubelet[1707]: E1112 22:33:31.979775 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:32.165557 kubelet[1707]: I1112 22:33:32.165350 1707 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.87046155 podStartE2EDuration="15.165335707s" podCreationTimestamp="2024-11-12 22:33:17 +0000 UTC" firstStartedPulling="2024-11-12 22:33:31.526324883 +0000 UTC m=+33.429900434" lastFinishedPulling="2024-11-12 22:33:31.82119904 +0000 UTC m=+33.724774591" observedRunningTime="2024-11-12 22:33:32.164971404 +0000 UTC m=+34.068546955" watchObservedRunningTime="2024-11-12 22:33:32.165335707 +0000 UTC m=+34.068911258" Nov 12 22:33:32.947117 systemd-networkd[1385]: vethd85e49c5: Gained IPv6LL Nov 12 22:33:32.980797 kubelet[1707]: E1112 22:33:32.980762 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:33:33.981671 kubelet[1707]: E1112 22:33:33.981444 1707 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"