Apr 12 18:22:47.727197 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Apr 12 18:22:47.727215 kernel: Linux version 5.15.154-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Fri Apr 12 17:21:24 -00 2024 Apr 12 18:22:47.727223 kernel: efi: EFI v2.70 by EDK II Apr 12 18:22:47.727228 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Apr 12 18:22:47.727233 kernel: random: crng init done Apr 12 18:22:47.727239 kernel: ACPI: Early table checksum verification disabled Apr 12 18:22:47.727245 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Apr 12 18:22:47.727251 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Apr 12 18:22:47.727257 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727262 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727268 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727273 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727278 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727283 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727291 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727301 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727307 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Apr 12 18:22:47.727312 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Apr 12 18:22:47.727318 kernel: NUMA: Failed to initialise from firmware Apr 12 18:22:47.727324 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Apr 12 18:22:47.727329 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Apr 12 18:22:47.727335 kernel: Zone ranges: Apr 12 18:22:47.727340 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Apr 12 18:22:47.727347 kernel: DMA32 empty Apr 12 18:22:47.727353 kernel: Normal empty Apr 12 18:22:47.727358 kernel: Movable zone start for each node Apr 12 18:22:47.727364 kernel: Early memory node ranges Apr 12 18:22:47.727370 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Apr 12 18:22:47.727375 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Apr 12 18:22:47.727381 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Apr 12 18:22:47.727386 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Apr 12 18:22:47.727392 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Apr 12 18:22:47.727397 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Apr 12 18:22:47.727403 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Apr 12 18:22:47.727409 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Apr 12 18:22:47.727416 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Apr 12 18:22:47.727421 kernel: psci: probing for conduit method from ACPI. Apr 12 18:22:47.727427 kernel: psci: PSCIv1.1 detected in firmware. Apr 12 18:22:47.727432 kernel: psci: Using standard PSCI v0.2 function IDs Apr 12 18:22:47.727438 kernel: psci: Trusted OS migration not required Apr 12 18:22:47.727446 kernel: psci: SMC Calling Convention v1.1 Apr 12 18:22:47.727452 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Apr 12 18:22:47.727459 kernel: ACPI: SRAT not present Apr 12 18:22:47.727466 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Apr 12 18:22:47.727472 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Apr 12 18:22:47.727478 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Apr 12 18:22:47.727484 kernel: Detected PIPT I-cache on CPU0 Apr 12 18:22:47.727491 kernel: CPU features: detected: GIC system register CPU interface Apr 12 18:22:47.727497 kernel: CPU features: detected: Hardware dirty bit management Apr 12 18:22:47.727513 kernel: CPU features: detected: Spectre-v4 Apr 12 18:22:47.727520 kernel: CPU features: detected: Spectre-BHB Apr 12 18:22:47.727528 kernel: CPU features: kernel page table isolation forced ON by KASLR Apr 12 18:22:47.727534 kernel: CPU features: detected: Kernel page table isolation (KPTI) Apr 12 18:22:47.727540 kernel: CPU features: detected: ARM erratum 1418040 Apr 12 18:22:47.727546 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Apr 12 18:22:47.727552 kernel: Policy zone: DMA Apr 12 18:22:47.727559 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=c0b96868344262519ffdb2dae3782c942008a0fecdbc0bc85d2e170bd2e8b8a8 Apr 12 18:22:47.727566 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 12 18:22:47.727572 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 12 18:22:47.727578 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 12 18:22:47.727611 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 12 18:22:47.727620 kernel: Memory: 2457468K/2572288K available (9792K kernel code, 2092K rwdata, 7568K rodata, 36352K init, 777K bss, 114820K reserved, 0K cma-reserved) Apr 12 18:22:47.727627 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Apr 12 18:22:47.727633 kernel: trace event string verifier disabled Apr 12 18:22:47.727639 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 12 18:22:47.727646 kernel: rcu: RCU event tracing is enabled. Apr 12 18:22:47.727652 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Apr 12 18:22:47.727658 kernel: Trampoline variant of Tasks RCU enabled. Apr 12 18:22:47.727664 kernel: Tracing variant of Tasks RCU enabled. Apr 12 18:22:47.727673 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 12 18:22:47.727680 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Apr 12 18:22:47.727686 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 12 18:22:47.727692 kernel: GICv3: 256 SPIs implemented Apr 12 18:22:47.727699 kernel: GICv3: 0 Extended SPIs implemented Apr 12 18:22:47.727705 kernel: GICv3: Distributor has no Range Selector support Apr 12 18:22:47.727710 kernel: Root IRQ handler: gic_handle_irq Apr 12 18:22:47.727716 kernel: GICv3: 16 PPIs implemented Apr 12 18:22:47.727722 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Apr 12 18:22:47.727728 kernel: ACPI: SRAT not present Apr 12 18:22:47.727734 kernel: ITS [mem 0x08080000-0x0809ffff] Apr 12 18:22:47.727740 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Apr 12 18:22:47.727747 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Apr 12 18:22:47.727753 kernel: GICv3: using LPI property table @0x00000000400d0000 Apr 12 18:22:47.727759 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Apr 12 18:22:47.727764 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 12 18:22:47.727777 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Apr 12 18:22:47.727784 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Apr 12 18:22:47.727790 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Apr 12 18:22:47.727796 kernel: arm-pv: using stolen time PV Apr 12 18:22:47.727803 kernel: Console: colour dummy device 80x25 Apr 12 18:22:47.727809 kernel: ACPI: Core revision 20210730 Apr 12 18:22:47.727815 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Apr 12 18:22:47.727821 kernel: pid_max: default: 32768 minimum: 301 Apr 12 18:22:47.727827 kernel: LSM: Security Framework initializing Apr 12 18:22:47.727833 kernel: SELinux: Initializing. Apr 12 18:22:47.727841 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 12 18:22:47.727847 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 12 18:22:47.727854 kernel: rcu: Hierarchical SRCU implementation. Apr 12 18:22:47.727860 kernel: Platform MSI: ITS@0x8080000 domain created Apr 12 18:22:47.727866 kernel: PCI/MSI: ITS@0x8080000 domain created Apr 12 18:22:47.727872 kernel: Remapping and enabling EFI services. Apr 12 18:22:47.727878 kernel: smp: Bringing up secondary CPUs ... Apr 12 18:22:47.727884 kernel: Detected PIPT I-cache on CPU1 Apr 12 18:22:47.727890 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Apr 12 18:22:47.727898 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Apr 12 18:22:47.727904 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 12 18:22:47.727910 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Apr 12 18:22:47.727917 kernel: Detected PIPT I-cache on CPU2 Apr 12 18:22:47.727923 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Apr 12 18:22:47.727929 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Apr 12 18:22:47.727935 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 12 18:22:47.727941 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Apr 12 18:22:47.727947 kernel: Detected PIPT I-cache on CPU3 Apr 12 18:22:47.727953 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Apr 12 18:22:47.727961 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Apr 12 18:22:47.727967 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 12 18:22:47.727973 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Apr 12 18:22:47.727979 kernel: smp: Brought up 1 node, 4 CPUs Apr 12 18:22:47.727989 kernel: SMP: Total of 4 processors activated. Apr 12 18:22:47.727997 kernel: CPU features: detected: 32-bit EL0 Support Apr 12 18:22:47.728004 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Apr 12 18:22:47.728010 kernel: CPU features: detected: Common not Private translations Apr 12 18:22:47.728016 kernel: CPU features: detected: CRC32 instructions Apr 12 18:22:47.728023 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Apr 12 18:22:47.728029 kernel: CPU features: detected: LSE atomic instructions Apr 12 18:22:47.728036 kernel: CPU features: detected: Privileged Access Never Apr 12 18:22:47.728043 kernel: CPU features: detected: RAS Extension Support Apr 12 18:22:47.728050 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Apr 12 18:22:47.728056 kernel: CPU: All CPU(s) started at EL1 Apr 12 18:22:47.728063 kernel: alternatives: patching kernel code Apr 12 18:22:47.728070 kernel: devtmpfs: initialized Apr 12 18:22:47.728077 kernel: KASLR enabled Apr 12 18:22:47.728083 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 12 18:22:47.728090 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Apr 12 18:22:47.728096 kernel: pinctrl core: initialized pinctrl subsystem Apr 12 18:22:47.728102 kernel: SMBIOS 3.0.0 present. Apr 12 18:22:47.728109 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Apr 12 18:22:47.728115 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 12 18:22:47.728122 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 12 18:22:47.728128 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 12 18:22:47.728136 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 12 18:22:47.728143 kernel: audit: initializing netlink subsys (disabled) Apr 12 18:22:47.728149 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Apr 12 18:22:47.728156 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 12 18:22:47.728162 kernel: cpuidle: using governor menu Apr 12 18:22:47.728169 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 12 18:22:47.728175 kernel: ASID allocator initialised with 32768 entries Apr 12 18:22:47.728182 kernel: ACPI: bus type PCI registered Apr 12 18:22:47.728188 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 12 18:22:47.728196 kernel: Serial: AMBA PL011 UART driver Apr 12 18:22:47.728202 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Apr 12 18:22:47.728209 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Apr 12 18:22:47.728215 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Apr 12 18:22:47.728222 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Apr 12 18:22:47.728228 kernel: cryptd: max_cpu_qlen set to 1000 Apr 12 18:22:47.728235 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 12 18:22:47.728241 kernel: ACPI: Added _OSI(Module Device) Apr 12 18:22:47.728248 kernel: ACPI: Added _OSI(Processor Device) Apr 12 18:22:47.728255 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 12 18:22:47.728262 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 12 18:22:47.728268 kernel: ACPI: Added _OSI(Linux-Dell-Video) Apr 12 18:22:47.728275 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Apr 12 18:22:47.728281 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Apr 12 18:22:47.728288 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 12 18:22:47.728294 kernel: ACPI: Interpreter enabled Apr 12 18:22:47.728300 kernel: ACPI: Using GIC for interrupt routing Apr 12 18:22:47.728307 kernel: ACPI: MCFG table detected, 1 entries Apr 12 18:22:47.728314 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Apr 12 18:22:47.728321 kernel: printk: console [ttyAMA0] enabled Apr 12 18:22:47.728327 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Apr 12 18:22:47.728437 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 12 18:22:47.728499 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 12 18:22:47.728579 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 12 18:22:47.728659 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Apr 12 18:22:47.728762 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Apr 12 18:22:47.728780 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Apr 12 18:22:47.728787 kernel: PCI host bridge to bus 0000:00 Apr 12 18:22:47.728857 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Apr 12 18:22:47.728915 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Apr 12 18:22:47.728970 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Apr 12 18:22:47.729023 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 12 18:22:47.729098 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Apr 12 18:22:47.729172 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Apr 12 18:22:47.729237 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Apr 12 18:22:47.729300 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Apr 12 18:22:47.729361 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Apr 12 18:22:47.729423 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Apr 12 18:22:47.729485 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Apr 12 18:22:47.729562 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Apr 12 18:22:47.729617 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Apr 12 18:22:47.729671 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Apr 12 18:22:47.729725 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Apr 12 18:22:47.729734 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Apr 12 18:22:47.729741 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Apr 12 18:22:47.729748 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Apr 12 18:22:47.729756 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Apr 12 18:22:47.729763 kernel: iommu: Default domain type: Translated Apr 12 18:22:47.729776 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 12 18:22:47.729783 kernel: vgaarb: loaded Apr 12 18:22:47.729790 kernel: pps_core: LinuxPPS API ver. 1 registered Apr 12 18:22:47.729796 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Apr 12 18:22:47.729803 kernel: PTP clock support registered Apr 12 18:22:47.729810 kernel: Registered efivars operations Apr 12 18:22:47.729817 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 12 18:22:47.729825 kernel: VFS: Disk quotas dquot_6.6.0 Apr 12 18:22:47.729832 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 12 18:22:47.729839 kernel: pnp: PnP ACPI init Apr 12 18:22:47.729905 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Apr 12 18:22:47.729915 kernel: pnp: PnP ACPI: found 1 devices Apr 12 18:22:47.729922 kernel: NET: Registered PF_INET protocol family Apr 12 18:22:47.729928 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 12 18:22:47.729935 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 12 18:22:47.729942 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 12 18:22:47.729950 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 12 18:22:47.729957 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Apr 12 18:22:47.729964 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 12 18:22:47.729970 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 12 18:22:47.729977 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 12 18:22:47.729984 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 12 18:22:47.729990 kernel: PCI: CLS 0 bytes, default 64 Apr 12 18:22:47.729997 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Apr 12 18:22:47.730005 kernel: kvm [1]: HYP mode not available Apr 12 18:22:47.730011 kernel: Initialise system trusted keyrings Apr 12 18:22:47.730018 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 12 18:22:47.730024 kernel: Key type asymmetric registered Apr 12 18:22:47.730031 kernel: Asymmetric key parser 'x509' registered Apr 12 18:22:47.730037 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Apr 12 18:22:47.730044 kernel: io scheduler mq-deadline registered Apr 12 18:22:47.730051 kernel: io scheduler kyber registered Apr 12 18:22:47.730057 kernel: io scheduler bfq registered Apr 12 18:22:47.730064 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Apr 12 18:22:47.730072 kernel: ACPI: button: Power Button [PWRB] Apr 12 18:22:47.730079 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Apr 12 18:22:47.730140 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Apr 12 18:22:47.730149 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 12 18:22:47.730156 kernel: thunder_xcv, ver 1.0 Apr 12 18:22:47.730162 kernel: thunder_bgx, ver 1.0 Apr 12 18:22:47.730169 kernel: nicpf, ver 1.0 Apr 12 18:22:47.730176 kernel: nicvf, ver 1.0 Apr 12 18:22:47.730243 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 12 18:22:47.730307 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-04-12T18:22:47 UTC (1712946167) Apr 12 18:22:47.730316 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 12 18:22:47.730323 kernel: NET: Registered PF_INET6 protocol family Apr 12 18:22:47.730329 kernel: Segment Routing with IPv6 Apr 12 18:22:47.730336 kernel: In-situ OAM (IOAM) with IPv6 Apr 12 18:22:47.732704 kernel: NET: Registered PF_PACKET protocol family Apr 12 18:22:47.732727 kernel: Key type dns_resolver registered Apr 12 18:22:47.732735 kernel: registered taskstats version 1 Apr 12 18:22:47.732747 kernel: Loading compiled-in X.509 certificates Apr 12 18:22:47.732754 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.154-flatcar: 8c258d82bbd8df4a9da2c0ea4108142f04be6b34' Apr 12 18:22:47.732761 kernel: Key type .fscrypt registered Apr 12 18:22:47.732767 kernel: Key type fscrypt-provisioning registered Apr 12 18:22:47.732781 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 12 18:22:47.732788 kernel: ima: Allocated hash algorithm: sha1 Apr 12 18:22:47.732794 kernel: ima: No architecture policies found Apr 12 18:22:47.732801 kernel: Freeing unused kernel memory: 36352K Apr 12 18:22:47.732812 kernel: Run /init as init process Apr 12 18:22:47.732818 kernel: with arguments: Apr 12 18:22:47.732825 kernel: /init Apr 12 18:22:47.732831 kernel: with environment: Apr 12 18:22:47.732837 kernel: HOME=/ Apr 12 18:22:47.732844 kernel: TERM=linux Apr 12 18:22:47.732850 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 12 18:22:47.732861 systemd[1]: systemd 252 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) Apr 12 18:22:47.732870 systemd[1]: Detected virtualization kvm. Apr 12 18:22:47.732878 systemd[1]: Detected architecture arm64. Apr 12 18:22:47.732887 systemd[1]: Running in initrd. Apr 12 18:22:47.732893 systemd[1]: No hostname configured, using default hostname. Apr 12 18:22:47.732903 systemd[1]: Hostname set to . Apr 12 18:22:47.732910 systemd[1]: Initializing machine ID from VM UUID. Apr 12 18:22:47.732917 systemd[1]: Queued start job for default target initrd.target. Apr 12 18:22:47.732924 systemd[1]: Started systemd-ask-password-console.path. Apr 12 18:22:47.732930 systemd[1]: Reached target cryptsetup.target. Apr 12 18:22:47.732941 systemd[1]: Reached target paths.target. Apr 12 18:22:47.732948 systemd[1]: Reached target slices.target. Apr 12 18:22:47.732954 systemd[1]: Reached target swap.target. Apr 12 18:22:47.732961 systemd[1]: Reached target timers.target. Apr 12 18:22:47.732969 systemd[1]: Listening on iscsid.socket. Apr 12 18:22:47.732978 systemd[1]: Listening on iscsiuio.socket. Apr 12 18:22:47.732985 systemd[1]: Listening on systemd-journald-audit.socket. Apr 12 18:22:47.732994 systemd[1]: Listening on systemd-journald-dev-log.socket. Apr 12 18:22:47.733001 systemd[1]: Listening on systemd-journald.socket. Apr 12 18:22:47.733008 systemd[1]: Listening on systemd-networkd.socket. Apr 12 18:22:47.733015 systemd[1]: Listening on systemd-udevd-control.socket. Apr 12 18:22:47.733022 systemd[1]: Listening on systemd-udevd-kernel.socket. Apr 12 18:22:47.733028 systemd[1]: Reached target sockets.target. Apr 12 18:22:47.733035 systemd[1]: Starting kmod-static-nodes.service... Apr 12 18:22:47.733045 systemd[1]: Finished network-cleanup.service. Apr 12 18:22:47.733052 systemd[1]: Starting systemd-fsck-usr.service... Apr 12 18:22:47.733060 systemd[1]: Starting systemd-journald.service... Apr 12 18:22:47.733067 systemd[1]: Starting systemd-modules-load.service... Apr 12 18:22:47.733074 systemd[1]: Starting systemd-resolved.service... Apr 12 18:22:47.733081 systemd[1]: Starting systemd-vconsole-setup.service... Apr 12 18:22:47.733088 systemd[1]: Finished kmod-static-nodes.service. Apr 12 18:22:47.733095 systemd[1]: Finished systemd-fsck-usr.service. Apr 12 18:22:47.733101 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Apr 12 18:22:47.733108 systemd[1]: Finished systemd-vconsole-setup.service. Apr 12 18:22:47.733115 systemd[1]: Starting dracut-cmdline-ask.service... Apr 12 18:22:47.733126 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Apr 12 18:22:47.733134 kernel: audit: type=1130 audit(1712946167.730:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.733143 systemd-journald[251]: Journal started Apr 12 18:22:47.733197 systemd-journald[251]: Runtime Journal (/run/log/journal/35011497ff6145de8237c7c5f1945980) is 6.0M, max 48.7M, 42.6M free. Apr 12 18:22:47.730000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.721897 systemd-modules-load[252]: Inserted module 'overlay' Apr 12 18:22:47.734710 systemd[1]: Started systemd-journald.service. Apr 12 18:22:47.734000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.738540 kernel: audit: type=1130 audit(1712946167.734:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.745880 systemd-resolved[253]: Positive Trust Anchors: Apr 12 18:22:47.745894 systemd-resolved[253]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 12 18:22:47.752362 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 12 18:22:47.752384 kernel: audit: type=1130 audit(1712946167.748:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.748000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.745922 systemd-resolved[253]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Apr 12 18:22:47.761892 kernel: Bridge firewalling registered Apr 12 18:22:47.761908 kernel: audit: type=1130 audit(1712946167.753:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.753000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.761964 dracut-cmdline[269]: dracut-dracut-053 Apr 12 18:22:47.746148 systemd[1]: Finished dracut-cmdline-ask.service. Apr 12 18:22:47.763679 dracut-cmdline[269]: 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=c0b96868344262519ffdb2dae3782c942008a0fecdbc0bc85d2e170bd2e8b8a8 Apr 12 18:22:47.749608 systemd[1]: Starting dracut-cmdline.service... Apr 12 18:22:47.768819 kernel: SCSI subsystem initialized Apr 12 18:22:47.749938 systemd-resolved[253]: Defaulting to hostname 'linux'. Apr 12 18:22:47.753448 systemd-modules-load[252]: Inserted module 'br_netfilter' Apr 12 18:22:47.753578 systemd[1]: Started systemd-resolved.service. Apr 12 18:22:47.754348 systemd[1]: Reached target nss-lookup.target. Apr 12 18:22:47.775565 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 12 18:22:47.775585 kernel: device-mapper: uevent: version 1.0.3 Apr 12 18:22:47.776574 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Apr 12 18:22:47.778813 systemd-modules-load[252]: Inserted module 'dm_multipath' Apr 12 18:22:47.779535 systemd[1]: Finished systemd-modules-load.service. Apr 12 18:22:47.779000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.780902 systemd[1]: Starting systemd-sysctl.service... Apr 12 18:22:47.784533 kernel: audit: type=1130 audit(1712946167.779:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.789118 systemd[1]: Finished systemd-sysctl.service. Apr 12 18:22:47.789000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.793533 kernel: audit: type=1130 audit(1712946167.789:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.821526 kernel: Loading iSCSI transport class v2.0-870. Apr 12 18:22:47.832529 kernel: iscsi: registered transport (tcp) Apr 12 18:22:47.849535 kernel: iscsi: registered transport (qla4xxx) Apr 12 18:22:47.849548 kernel: QLogic iSCSI HBA Driver Apr 12 18:22:47.883048 systemd[1]: Finished dracut-cmdline.service. Apr 12 18:22:47.886584 kernel: audit: type=1130 audit(1712946167.883:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.883000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:47.884547 systemd[1]: Starting dracut-pre-udev.service... Apr 12 18:22:47.927530 kernel: raid6: neonx8 gen() 13722 MB/s Apr 12 18:22:47.944528 kernel: raid6: neonx8 xor() 10789 MB/s Apr 12 18:22:47.961523 kernel: raid6: neonx4 gen() 13480 MB/s Apr 12 18:22:47.978535 kernel: raid6: neonx4 xor() 11221 MB/s Apr 12 18:22:47.995522 kernel: raid6: neonx2 gen() 12997 MB/s Apr 12 18:22:48.012524 kernel: raid6: neonx2 xor() 10365 MB/s Apr 12 18:22:48.029524 kernel: raid6: neonx1 gen() 10514 MB/s Apr 12 18:22:48.046536 kernel: raid6: neonx1 xor() 8674 MB/s Apr 12 18:22:48.063534 kernel: raid6: int64x8 gen() 6238 MB/s Apr 12 18:22:48.080522 kernel: raid6: int64x8 xor() 3527 MB/s Apr 12 18:22:48.097527 kernel: raid6: int64x4 gen() 7193 MB/s Apr 12 18:22:48.114534 kernel: raid6: int64x4 xor() 3839 MB/s Apr 12 18:22:48.131534 kernel: raid6: int64x2 gen() 6139 MB/s Apr 12 18:22:48.148526 kernel: raid6: int64x2 xor() 3311 MB/s Apr 12 18:22:48.165525 kernel: raid6: int64x1 gen() 5028 MB/s Apr 12 18:22:48.182560 kernel: raid6: int64x1 xor() 2638 MB/s Apr 12 18:22:48.182581 kernel: raid6: using algorithm neonx8 gen() 13722 MB/s Apr 12 18:22:48.182598 kernel: raid6: .... xor() 10789 MB/s, rmw enabled Apr 12 18:22:48.183590 kernel: raid6: using neon recovery algorithm Apr 12 18:22:48.193527 kernel: xor: measuring software checksum speed Apr 12 18:22:48.194522 kernel: 8regs : 17220 MB/sec Apr 12 18:22:48.195524 kernel: 32regs : 20439 MB/sec Apr 12 18:22:48.196720 kernel: arm64_neon : 27939 MB/sec Apr 12 18:22:48.196731 kernel: xor: using function: arm64_neon (27939 MB/sec) Apr 12 18:22:48.250526 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Apr 12 18:22:48.259966 systemd[1]: Finished dracut-pre-udev.service. Apr 12 18:22:48.264275 kernel: audit: type=1130 audit(1712946168.260:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:48.264294 kernel: audit: type=1334 audit(1712946168.263:10): prog-id=7 op=LOAD Apr 12 18:22:48.260000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:48.263000 audit: BPF prog-id=7 op=LOAD Apr 12 18:22:48.263000 audit: BPF prog-id=8 op=LOAD Apr 12 18:22:48.264614 systemd[1]: Starting systemd-udevd.service... Apr 12 18:22:48.276496 systemd-udevd[452]: Using default interface naming scheme 'v252'. Apr 12 18:22:48.279813 systemd[1]: Started systemd-udevd.service. Apr 12 18:22:48.280000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:48.281201 systemd[1]: Starting dracut-pre-trigger.service... Apr 12 18:22:48.291303 dracut-pre-trigger[459]: rd.md=0: removing MD RAID activation Apr 12 18:22:48.316476 systemd[1]: Finished dracut-pre-trigger.service. Apr 12 18:22:48.316000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:48.317914 systemd[1]: Starting systemd-udev-trigger.service... Apr 12 18:22:48.354730 systemd[1]: Finished systemd-udev-trigger.service. Apr 12 18:22:48.355000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:48.380524 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Apr 12 18:22:48.384684 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 12 18:22:48.384718 kernel: GPT:9289727 != 19775487 Apr 12 18:22:48.384727 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 12 18:22:48.385689 kernel: GPT:9289727 != 19775487 Apr 12 18:22:48.385728 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 12 18:22:48.386520 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 12 18:22:48.402531 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (508) Apr 12 18:22:48.403438 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Apr 12 18:22:48.404516 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Apr 12 18:22:48.411500 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Apr 12 18:22:48.414854 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Apr 12 18:22:48.418117 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Apr 12 18:22:48.419713 systemd[1]: Starting disk-uuid.service... Apr 12 18:22:48.425284 disk-uuid[524]: Primary Header is updated. Apr 12 18:22:48.425284 disk-uuid[524]: Secondary Entries is updated. Apr 12 18:22:48.425284 disk-uuid[524]: Secondary Header is updated. Apr 12 18:22:48.428211 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 12 18:22:48.436520 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 12 18:22:49.440527 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 12 18:22:49.440646 disk-uuid[525]: The operation has completed successfully. Apr 12 18:22:49.472622 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 12 18:22:49.473000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.473000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.472724 systemd[1]: Finished disk-uuid.service. Apr 12 18:22:49.478710 systemd[1]: Starting verity-setup.service... Apr 12 18:22:49.499743 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 12 18:22:49.546654 systemd[1]: Found device dev-mapper-usr.device. Apr 12 18:22:49.548000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.549469 systemd[1]: Mounting sysusr-usr.mount... Apr 12 18:22:49.550263 systemd[1]: Finished verity-setup.service. Apr 12 18:22:49.601534 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Apr 12 18:22:49.601860 systemd[1]: Mounted sysusr-usr.mount. Apr 12 18:22:49.602626 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Apr 12 18:22:49.603275 systemd[1]: Starting ignition-setup.service... Apr 12 18:22:49.605497 systemd[1]: Starting parse-ip-for-networkd.service... Apr 12 18:22:49.616876 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 12 18:22:49.616919 kernel: BTRFS info (device vda6): using free space tree Apr 12 18:22:49.616929 kernel: BTRFS info (device vda6): has skinny extents Apr 12 18:22:49.626078 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 12 18:22:49.637016 systemd[1]: Finished ignition-setup.service. Apr 12 18:22:49.638518 systemd[1]: Starting ignition-fetch-offline.service... Apr 12 18:22:49.637000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.686576 systemd[1]: Finished parse-ip-for-networkd.service. Apr 12 18:22:49.686000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.687000 audit: BPF prog-id=9 op=LOAD Apr 12 18:22:49.688629 systemd[1]: Starting systemd-networkd.service... Apr 12 18:22:49.720099 systemd-networkd[700]: lo: Link UP Apr 12 18:22:49.720113 systemd-networkd[700]: lo: Gained carrier Apr 12 18:22:49.722541 systemd-networkd[700]: Enumeration completed Apr 12 18:22:49.722928 systemd-networkd[700]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 12 18:22:49.724134 systemd-networkd[700]: eth0: Link UP Apr 12 18:22:49.725000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.724137 systemd-networkd[700]: eth0: Gained carrier Apr 12 18:22:49.724717 systemd[1]: Started systemd-networkd.service. Apr 12 18:22:49.725816 systemd[1]: Reached target network.target. Apr 12 18:22:49.727496 systemd[1]: Starting iscsiuio.service... Apr 12 18:22:49.738238 systemd[1]: Started iscsiuio.service. Apr 12 18:22:49.738000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.739689 systemd[1]: Starting iscsid.service... Apr 12 18:22:49.742595 systemd-networkd[700]: eth0: DHCPv4 address 10.0.0.56/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 12 18:22:49.744945 iscsid[707]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Apr 12 18:22:49.744945 iscsid[707]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Apr 12 18:22:49.744945 iscsid[707]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Apr 12 18:22:49.744945 iscsid[707]: If using hardware iscsi like qla4xxx this message can be ignored. Apr 12 18:22:49.744945 iscsid[707]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Apr 12 18:22:49.744945 iscsid[707]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Apr 12 18:22:49.750000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.746138 systemd[1]: Started iscsid.service. Apr 12 18:22:49.751304 systemd[1]: Starting dracut-initqueue.service... Apr 12 18:22:49.763459 systemd[1]: Finished dracut-initqueue.service. Apr 12 18:22:49.763000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.764536 systemd[1]: Reached target remote-fs-pre.target. Apr 12 18:22:49.766077 systemd[1]: Reached target remote-cryptsetup.target. Apr 12 18:22:49.767728 systemd[1]: Reached target remote-fs.target. Apr 12 18:22:49.770111 systemd[1]: Starting dracut-pre-mount.service... Apr 12 18:22:49.777868 systemd[1]: Finished dracut-pre-mount.service. Apr 12 18:22:49.778000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.778141 ignition[632]: Ignition 2.14.0 Apr 12 18:22:49.778147 ignition[632]: Stage: fetch-offline Apr 12 18:22:49.778184 ignition[632]: no configs at "/usr/lib/ignition/base.d" Apr 12 18:22:49.778193 ignition[632]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 12 18:22:49.778326 ignition[632]: parsed url from cmdline: "" Apr 12 18:22:49.778329 ignition[632]: no config URL provided Apr 12 18:22:49.778334 ignition[632]: reading system config file "/usr/lib/ignition/user.ign" Apr 12 18:22:49.778341 ignition[632]: no config at "/usr/lib/ignition/user.ign" Apr 12 18:22:49.778358 ignition[632]: op(1): [started] loading QEMU firmware config module Apr 12 18:22:49.778363 ignition[632]: op(1): executing: "modprobe" "qemu_fw_cfg" Apr 12 18:22:49.783459 ignition[632]: op(1): [finished] loading QEMU firmware config module Apr 12 18:22:49.805853 ignition[632]: parsing config with SHA512: 4d99c7ea15488f2cc81536de20fe2d57610eb1ba362020b8eb9b684f8b913ecb46b12136a9d1e28062c1da92a6d9da9b30e966ed951df20d8d302841b9c39416 Apr 12 18:22:49.831521 unknown[632]: fetched base config from "system" Apr 12 18:22:49.831532 unknown[632]: fetched user config from "qemu" Apr 12 18:22:49.832053 ignition[632]: fetch-offline: fetch-offline passed Apr 12 18:22:49.832126 ignition[632]: Ignition finished successfully Apr 12 18:22:49.834000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.833557 systemd[1]: Finished ignition-fetch-offline.service. Apr 12 18:22:49.834853 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Apr 12 18:22:49.835575 systemd[1]: Starting ignition-kargs.service... Apr 12 18:22:49.843877 ignition[723]: Ignition 2.14.0 Apr 12 18:22:49.843887 ignition[723]: Stage: kargs Apr 12 18:22:49.843983 ignition[723]: no configs at "/usr/lib/ignition/base.d" Apr 12 18:22:49.843993 ignition[723]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 12 18:22:49.846270 systemd[1]: Finished ignition-kargs.service. Apr 12 18:22:49.847000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.845034 ignition[723]: kargs: kargs passed Apr 12 18:22:49.845080 ignition[723]: Ignition finished successfully Apr 12 18:22:49.848658 systemd[1]: Starting ignition-disks.service... Apr 12 18:22:49.855039 ignition[729]: Ignition 2.14.0 Apr 12 18:22:49.855048 ignition[729]: Stage: disks Apr 12 18:22:49.855139 ignition[729]: no configs at "/usr/lib/ignition/base.d" Apr 12 18:22:49.855148 ignition[729]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 12 18:22:49.856058 ignition[729]: disks: disks passed Apr 12 18:22:49.858000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.857911 systemd[1]: Finished ignition-disks.service. Apr 12 18:22:49.856097 ignition[729]: Ignition finished successfully Apr 12 18:22:49.859608 systemd[1]: Reached target initrd-root-device.target. Apr 12 18:22:49.860870 systemd[1]: Reached target local-fs-pre.target. Apr 12 18:22:49.862134 systemd[1]: Reached target local-fs.target. Apr 12 18:22:49.863338 systemd[1]: Reached target sysinit.target. Apr 12 18:22:49.864667 systemd[1]: Reached target basic.target. Apr 12 18:22:49.866656 systemd[1]: Starting systemd-fsck-root.service... Apr 12 18:22:49.878048 systemd-fsck[737]: ROOT: clean, 612/553520 files, 56018/553472 blocks Apr 12 18:22:49.881046 systemd[1]: Finished systemd-fsck-root.service. Apr 12 18:22:49.881000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.882452 systemd[1]: Mounting sysroot.mount... Apr 12 18:22:49.890166 systemd[1]: Mounted sysroot.mount. Apr 12 18:22:49.891318 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Apr 12 18:22:49.890871 systemd[1]: Reached target initrd-root-fs.target. Apr 12 18:22:49.892899 systemd[1]: Mounting sysroot-usr.mount... Apr 12 18:22:49.893722 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Apr 12 18:22:49.893757 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 12 18:22:49.893789 systemd[1]: Reached target ignition-diskful.target. Apr 12 18:22:49.895528 systemd[1]: Mounted sysroot-usr.mount. Apr 12 18:22:49.897230 systemd[1]: Starting initrd-setup-root.service... Apr 12 18:22:49.901278 initrd-setup-root[747]: cut: /sysroot/etc/passwd: No such file or directory Apr 12 18:22:49.905296 initrd-setup-root[755]: cut: /sysroot/etc/group: No such file or directory Apr 12 18:22:49.909362 initrd-setup-root[763]: cut: /sysroot/etc/shadow: No such file or directory Apr 12 18:22:49.913028 initrd-setup-root[771]: cut: /sysroot/etc/gshadow: No such file or directory Apr 12 18:22:49.939869 systemd[1]: Finished initrd-setup-root.service. Apr 12 18:22:49.940000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.941313 systemd[1]: Starting ignition-mount.service... Apr 12 18:22:49.942572 systemd[1]: Starting sysroot-boot.service... Apr 12 18:22:49.947066 bash[788]: umount: /sysroot/usr/share/oem: not mounted. Apr 12 18:22:49.955479 ignition[789]: INFO : Ignition 2.14.0 Apr 12 18:22:49.955479 ignition[789]: INFO : Stage: mount Apr 12 18:22:49.957942 ignition[789]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 12 18:22:49.957942 ignition[789]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 12 18:22:49.957942 ignition[789]: INFO : mount: mount passed Apr 12 18:22:49.957942 ignition[789]: INFO : Ignition finished successfully Apr 12 18:22:49.960616 systemd[1]: Finished ignition-mount.service. Apr 12 18:22:49.961000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:49.972200 systemd[1]: Finished sysroot-boot.service. Apr 12 18:22:49.972000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:50.560468 systemd[1]: Mounting sysroot-usr-share-oem.mount... Apr 12 18:22:50.567310 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (799) Apr 12 18:22:50.567339 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 12 18:22:50.567349 kernel: BTRFS info (device vda6): using free space tree Apr 12 18:22:50.568519 kernel: BTRFS info (device vda6): has skinny extents Apr 12 18:22:50.571472 systemd[1]: Mounted sysroot-usr-share-oem.mount. Apr 12 18:22:50.572941 systemd[1]: Starting ignition-files.service... Apr 12 18:22:50.586408 ignition[819]: INFO : Ignition 2.14.0 Apr 12 18:22:50.586408 ignition[819]: INFO : Stage: files Apr 12 18:22:50.587879 ignition[819]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 12 18:22:50.587879 ignition[819]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 12 18:22:50.587879 ignition[819]: DEBUG : files: compiled without relabeling support, skipping Apr 12 18:22:50.591265 ignition[819]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 12 18:22:50.591265 ignition[819]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 12 18:22:50.591265 ignition[819]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 12 18:22:50.591265 ignition[819]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 12 18:22:50.591265 ignition[819]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 12 18:22:50.591010 unknown[819]: wrote ssh authorized keys file for user: core Apr 12 18:22:50.598408 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Apr 12 18:22:50.598408 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Apr 12 18:22:50.598408 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/opt/cni-plugins-linux-arm64-v1.3.0.tgz" Apr 12 18:22:50.598408 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET https://github.com/containernetworking/plugins/releases/download/v1.3.0/cni-plugins-linux-arm64-v1.3.0.tgz: attempt #1 Apr 12 18:22:50.920022 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET result: OK Apr 12 18:22:51.067693 systemd-networkd[700]: eth0: Gained IPv6LL Apr 12 18:22:51.189763 ignition[819]: DEBUG : files: createFilesystemsFiles: createFiles: op(4): file matches expected sum of: b2b7fb74f1b3cb8928f49e5bf9d4bc686e057e837fac3caf1b366d54757921dba80d70cc010399b274d136e8dee9a25b1ad87cdfdc4ffcf42cf88f3e8f99587a Apr 12 18:22:51.192639 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/opt/cni-plugins-linux-arm64-v1.3.0.tgz" Apr 12 18:22:51.192639 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/opt/crictl-v1.27.0-linux-arm64.tar.gz" Apr 12 18:22:51.192639 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(5): GET https://github.com/kubernetes-sigs/cri-tools/releases/download/v1.27.0/crictl-v1.27.0-linux-arm64.tar.gz: attempt #1 Apr 12 18:22:51.397990 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(5): GET result: OK Apr 12 18:22:51.520619 ignition[819]: DEBUG : files: createFilesystemsFiles: createFiles: op(5): file matches expected sum of: db062e43351a63347871e7094115be2ae3853afcd346d47f7b51141da8c3202c2df58d2e17359322f632abcb37474fd7fdb3b7aadbc5cfd5cf6d3bad040b6251 Apr 12 18:22:51.520619 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/opt/crictl-v1.27.0-linux-arm64.tar.gz" Apr 12 18:22:51.525169 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/bin/kubeadm" Apr 12 18:22:51.525169 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://dl.k8s.io/release/v1.27.2/bin/linux/arm64/kubeadm: attempt #1 Apr 12 18:22:51.598624 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Apr 12 18:22:52.010484 ignition[819]: DEBUG : files: createFilesystemsFiles: createFiles: op(6): file matches expected sum of: 45b3100984c979ba0f1c0df8f4211474c2d75ebe916e677dff5fc8e3b3697cf7a953da94e356f39684cc860dff6878b772b7514c55651c2f866d9efeef23f970 Apr 12 18:22:52.013190 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/bin/kubeadm" Apr 12 18:22:52.013190 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/bin/kubelet" Apr 12 18:22:52.013190 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://dl.k8s.io/release/v1.27.2/bin/linux/arm64/kubelet: attempt #1 Apr 12 18:22:52.033283 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Apr 12 18:22:52.637569 ignition[819]: DEBUG : files: createFilesystemsFiles: createFiles: op(7): file matches expected sum of: 71857ff499ae135fa478e1827a0ed8865e578a8d2b1e25876e914fd0beba03733801c0654bcd4c0567bafeb16887dafb2dbbe8d1116e6ea28dcd8366c142d348 Apr 12 18:22:52.640849 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/bin/kubelet" Apr 12 18:22:52.640849 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(8): [started] writing file "/sysroot/home/core/install.sh" Apr 12 18:22:52.640849 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(8): [finished] writing file "/sysroot/home/core/install.sh" Apr 12 18:22:52.640849 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(9): [started] writing file "/sysroot/etc/docker/daemon.json" Apr 12 18:22:52.640849 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(9): [finished] writing file "/sysroot/etc/docker/daemon.json" Apr 12 18:22:52.640849 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(a): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 12 18:22:52.640849 ignition[819]: INFO : files: createFilesystemsFiles: createFiles: op(a): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(b): [started] processing unit "containerd.service" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(b): op(c): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(b): op(c): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(b): [finished] processing unit "containerd.service" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(d): [started] processing unit "prepare-cni-plugins.service" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(d): op(e): [started] writing unit "prepare-cni-plugins.service" at "/sysroot/etc/systemd/system/prepare-cni-plugins.service" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(d): op(e): [finished] writing unit "prepare-cni-plugins.service" at "/sysroot/etc/systemd/system/prepare-cni-plugins.service" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(d): [finished] processing unit "prepare-cni-plugins.service" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(f): [started] processing unit "prepare-critools.service" Apr 12 18:22:52.640849 ignition[819]: INFO : files: op(f): op(10): [started] writing unit "prepare-critools.service" at "/sysroot/etc/systemd/system/prepare-critools.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(f): op(10): [finished] writing unit "prepare-critools.service" at "/sysroot/etc/systemd/system/prepare-critools.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(f): [finished] processing unit "prepare-critools.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(11): [started] processing unit "coreos-metadata.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(11): op(12): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(11): op(12): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(11): [finished] processing unit "coreos-metadata.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(13): [started] setting preset to enabled for "prepare-cni-plugins.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(13): [finished] setting preset to enabled for "prepare-cni-plugins.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(14): [started] setting preset to enabled for "prepare-critools.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(14): [finished] setting preset to enabled for "prepare-critools.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(15): [started] setting preset to disabled for "coreos-metadata.service" Apr 12 18:22:52.670371 ignition[819]: INFO : files: op(15): op(16): [started] removing enablement symlink(s) for "coreos-metadata.service" Apr 12 18:22:52.690061 ignition[819]: INFO : files: op(15): op(16): [finished] removing enablement symlink(s) for "coreos-metadata.service" Apr 12 18:22:52.692397 ignition[819]: INFO : files: op(15): [finished] setting preset to disabled for "coreos-metadata.service" Apr 12 18:22:52.692397 ignition[819]: INFO : files: createResultFile: createFiles: op(17): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 12 18:22:52.692397 ignition[819]: INFO : files: createResultFile: createFiles: op(17): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 12 18:22:52.692397 ignition[819]: INFO : files: files passed Apr 12 18:22:52.692397 ignition[819]: INFO : Ignition finished successfully Apr 12 18:22:52.706587 kernel: kauditd_printk_skb: 22 callbacks suppressed Apr 12 18:22:52.706608 kernel: audit: type=1130 audit(1712946172.694:33): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.706625 kernel: audit: type=1130 audit(1712946172.703:34): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.694000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.703000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.692514 systemd[1]: Finished ignition-files.service. Apr 12 18:22:52.695429 systemd[1]: Starting initrd-setup-root-after-ignition.service... Apr 12 18:22:52.699778 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Apr 12 18:22:52.716211 kernel: audit: type=1130 audit(1712946172.709:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.716231 kernel: audit: type=1131 audit(1712946172.709:36): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.709000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.709000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.716298 initrd-setup-root-after-ignition[843]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Apr 12 18:22:52.700442 systemd[1]: Starting ignition-quench.service... Apr 12 18:22:52.718604 initrd-setup-root-after-ignition[846]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 12 18:22:52.702064 systemd[1]: Finished initrd-setup-root-after-ignition.service. Apr 12 18:22:52.703464 systemd[1]: Reached target ignition-complete.target. Apr 12 18:22:52.708035 systemd[1]: Starting initrd-parse-etc.service... Apr 12 18:22:52.708918 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 12 18:22:52.708998 systemd[1]: Finished ignition-quench.service. Apr 12 18:22:52.729681 kernel: audit: type=1130 audit(1712946172.723:37): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.729703 kernel: audit: type=1131 audit(1712946172.723:38): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.723000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.723000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.722762 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 12 18:22:52.722868 systemd[1]: Finished initrd-parse-etc.service. Apr 12 18:22:52.724736 systemd[1]: Reached target initrd-fs.target. Apr 12 18:22:52.730330 systemd[1]: Reached target initrd.target. Apr 12 18:22:52.731829 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Apr 12 18:22:52.732632 systemd[1]: Starting dracut-pre-pivot.service... Apr 12 18:22:52.743297 systemd[1]: Finished dracut-pre-pivot.service. Apr 12 18:22:52.744000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.744894 systemd[1]: Starting initrd-cleanup.service... Apr 12 18:22:52.748302 kernel: audit: type=1130 audit(1712946172.744:39): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.753298 systemd[1]: Stopped target nss-lookup.target. Apr 12 18:22:52.756000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.754213 systemd[1]: Stopped target remote-cryptsetup.target. Apr 12 18:22:52.761039 kernel: audit: type=1131 audit(1712946172.756:40): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.755121 systemd[1]: Stopped target timers.target. Apr 12 18:22:52.755971 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 12 18:22:52.756083 systemd[1]: Stopped dracut-pre-pivot.service. Apr 12 18:22:52.757027 systemd[1]: Stopped target initrd.target. Apr 12 18:22:52.760439 systemd[1]: Stopped target basic.target. Apr 12 18:22:52.761770 systemd[1]: Stopped target ignition-complete.target. Apr 12 18:22:52.763031 systemd[1]: Stopped target ignition-diskful.target. Apr 12 18:22:52.764447 systemd[1]: Stopped target initrd-root-device.target. Apr 12 18:22:52.765849 systemd[1]: Stopped target remote-fs.target. Apr 12 18:22:52.767110 systemd[1]: Stopped target remote-fs-pre.target. Apr 12 18:22:52.768348 systemd[1]: Stopped target sysinit.target. Apr 12 18:22:52.774000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.769633 systemd[1]: Stopped target local-fs.target. Apr 12 18:22:52.779253 kernel: audit: type=1131 audit(1712946172.774:41): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.771012 systemd[1]: Stopped target local-fs-pre.target. Apr 12 18:22:52.779000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.772349 systemd[1]: Stopped target swap.target. Apr 12 18:22:52.784089 kernel: audit: type=1131 audit(1712946172.779:42): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.782000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.773539 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 12 18:22:52.773669 systemd[1]: Stopped dracut-pre-mount.service. Apr 12 18:22:52.775014 systemd[1]: Stopped target cryptsetup.target. Apr 12 18:22:52.778377 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 12 18:22:52.778481 systemd[1]: Stopped dracut-initqueue.service. Apr 12 18:22:52.780122 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 12 18:22:52.780221 systemd[1]: Stopped ignition-fetch-offline.service. Apr 12 18:22:52.783681 systemd[1]: Stopped target paths.target. Apr 12 18:22:52.784811 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 12 18:22:52.789528 systemd[1]: Stopped systemd-ask-password-console.path. Apr 12 18:22:52.790960 systemd[1]: Stopped target slices.target. Apr 12 18:22:52.794000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.792098 systemd[1]: Stopped target sockets.target. Apr 12 18:22:52.795000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.793575 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 12 18:22:52.793697 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Apr 12 18:22:52.799151 iscsid[707]: iscsid shutting down. Apr 12 18:22:52.795090 systemd[1]: ignition-files.service: Deactivated successfully. Apr 12 18:22:52.795185 systemd[1]: Stopped ignition-files.service. Apr 12 18:22:52.797185 systemd[1]: Stopping ignition-mount.service... Apr 12 18:22:52.798546 systemd[1]: Stopping iscsid.service... Apr 12 18:22:52.800000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.799609 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 12 18:22:52.804000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.799722 systemd[1]: Stopped kmod-static-nodes.service. Apr 12 18:22:52.806000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.807834 ignition[860]: INFO : Ignition 2.14.0 Apr 12 18:22:52.807834 ignition[860]: INFO : Stage: umount Apr 12 18:22:52.807834 ignition[860]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 12 18:22:52.807834 ignition[860]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 12 18:22:52.807834 ignition[860]: INFO : umount: umount passed Apr 12 18:22:52.807834 ignition[860]: INFO : Ignition finished successfully Apr 12 18:22:52.809000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.810000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.801681 systemd[1]: Stopping sysroot-boot.service... Apr 12 18:22:52.814000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.803643 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 12 18:22:52.818000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.803801 systemd[1]: Stopped systemd-udev-trigger.service. Apr 12 18:22:52.819000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.805114 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 12 18:22:52.805204 systemd[1]: Stopped dracut-pre-trigger.service. Apr 12 18:22:52.822000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.822000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.808664 systemd[1]: iscsid.service: Deactivated successfully. Apr 12 18:22:52.824000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.808767 systemd[1]: Stopped iscsid.service. Apr 12 18:22:52.810381 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 12 18:22:52.810455 systemd[1]: Stopped ignition-mount.service. Apr 12 18:22:52.812628 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 12 18:22:52.813127 systemd[1]: iscsid.socket: Deactivated successfully. Apr 12 18:22:52.813195 systemd[1]: Closed iscsid.socket. Apr 12 18:22:52.814107 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 12 18:22:52.814152 systemd[1]: Stopped ignition-disks.service. Apr 12 18:22:52.815595 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 12 18:22:52.815636 systemd[1]: Stopped ignition-kargs.service. Apr 12 18:22:52.818665 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 12 18:22:52.818706 systemd[1]: Stopped ignition-setup.service. Apr 12 18:22:52.820334 systemd[1]: Stopping iscsiuio.service... Apr 12 18:22:52.822211 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 12 18:22:52.822293 systemd[1]: Finished initrd-cleanup.service. Apr 12 18:22:52.823664 systemd[1]: iscsiuio.service: Deactivated successfully. Apr 12 18:22:52.823742 systemd[1]: Stopped iscsiuio.service. Apr 12 18:22:52.825470 systemd[1]: Stopped target network.target. Apr 12 18:22:52.826247 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 12 18:22:52.841000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.826281 systemd[1]: Closed iscsiuio.socket. Apr 12 18:22:52.842000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.827409 systemd[1]: Stopping systemd-networkd.service... Apr 12 18:22:52.828906 systemd[1]: Stopping systemd-resolved.service... Apr 12 18:22:52.838539 systemd-networkd[700]: eth0: DHCPv6 lease lost Apr 12 18:22:52.846000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.840455 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 12 18:22:52.848000 audit: BPF prog-id=9 op=UNLOAD Apr 12 18:22:52.848000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.840730 systemd[1]: Stopped systemd-networkd.service. Apr 12 18:22:52.849000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.852000 audit: BPF prog-id=6 op=UNLOAD Apr 12 18:22:52.842336 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 12 18:22:52.842422 systemd[1]: Stopped systemd-resolved.service. Apr 12 18:22:52.843732 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 12 18:22:52.843771 systemd[1]: Closed systemd-networkd.socket. Apr 12 18:22:52.845266 systemd[1]: Stopping network-cleanup.service... Apr 12 18:22:52.845938 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 12 18:22:52.860000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.845992 systemd[1]: Stopped parse-ip-for-networkd.service. Apr 12 18:22:52.847352 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 12 18:22:52.862000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.847393 systemd[1]: Stopped systemd-sysctl.service. Apr 12 18:22:52.849487 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 12 18:22:52.849545 systemd[1]: Stopped systemd-modules-load.service. Apr 12 18:22:52.867000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.850450 systemd[1]: Stopping systemd-udevd.service... Apr 12 18:22:52.869000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.856746 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Apr 12 18:22:52.870000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.859461 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 12 18:22:52.859577 systemd[1]: Stopped network-cleanup.service. Apr 12 18:22:52.875000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.862324 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 12 18:22:52.862444 systemd[1]: Stopped systemd-udevd.service. Apr 12 18:22:52.878000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.878000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.863534 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 12 18:22:52.863571 systemd[1]: Closed systemd-udevd-control.socket. Apr 12 18:22:52.864821 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 12 18:22:52.864853 systemd[1]: Closed systemd-udevd-kernel.socket. Apr 12 18:22:52.866251 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 12 18:22:52.866298 systemd[1]: Stopped dracut-pre-udev.service. Apr 12 18:22:52.867797 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 12 18:22:52.867841 systemd[1]: Stopped dracut-cmdline.service. Apr 12 18:22:52.869949 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 12 18:22:52.869990 systemd[1]: Stopped dracut-cmdline-ask.service. Apr 12 18:22:52.872137 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Apr 12 18:22:52.873679 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 12 18:22:52.873740 systemd[1]: Stopped systemd-vconsole-setup.service. Apr 12 18:22:52.877362 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 12 18:22:52.877458 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Apr 12 18:22:52.916442 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 12 18:22:52.916557 systemd[1]: Stopped sysroot-boot.service. Apr 12 18:22:52.917000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.918136 systemd[1]: Reached target initrd-switch-root.target. Apr 12 18:22:52.919259 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 12 18:22:52.920000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:52.919309 systemd[1]: Stopped initrd-setup-root.service. Apr 12 18:22:52.921335 systemd[1]: Starting initrd-switch-root.service... Apr 12 18:22:52.927066 systemd[1]: Switching root. Apr 12 18:22:52.927000 audit: BPF prog-id=5 op=UNLOAD Apr 12 18:22:52.927000 audit: BPF prog-id=4 op=UNLOAD Apr 12 18:22:52.927000 audit: BPF prog-id=3 op=UNLOAD Apr 12 18:22:52.928000 audit: BPF prog-id=8 op=UNLOAD Apr 12 18:22:52.928000 audit: BPF prog-id=7 op=UNLOAD Apr 12 18:22:52.944946 systemd-journald[251]: Journal stopped Apr 12 18:22:55.011588 systemd-journald[251]: Received SIGTERM from PID 1 (systemd). Apr 12 18:22:55.011638 kernel: SELinux: Class mctp_socket not defined in policy. Apr 12 18:22:55.011653 kernel: SELinux: Class anon_inode not defined in policy. Apr 12 18:22:55.011663 kernel: SELinux: the above unknown classes and permissions will be allowed Apr 12 18:22:55.011677 kernel: SELinux: policy capability network_peer_controls=1 Apr 12 18:22:55.011687 kernel: SELinux: policy capability open_perms=1 Apr 12 18:22:55.011696 kernel: SELinux: policy capability extended_socket_class=1 Apr 12 18:22:55.011709 kernel: SELinux: policy capability always_check_network=0 Apr 12 18:22:55.011719 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 12 18:22:55.011729 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 12 18:22:55.011738 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 12 18:22:55.011762 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 12 18:22:55.011775 systemd[1]: Successfully loaded SELinux policy in 33.943ms. Apr 12 18:22:55.011793 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.851ms. Apr 12 18:22:55.011808 systemd[1]: systemd 252 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) Apr 12 18:22:55.011820 systemd[1]: Detected virtualization kvm. Apr 12 18:22:55.011830 systemd[1]: Detected architecture arm64. Apr 12 18:22:55.011840 systemd[1]: Detected first boot. Apr 12 18:22:55.011851 systemd[1]: Initializing machine ID from VM UUID. Apr 12 18:22:55.011862 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Apr 12 18:22:55.011872 systemd[1]: Populated /etc with preset unit settings. Apr 12 18:22:55.011883 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Apr 12 18:22:55.011896 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Apr 12 18:22:55.011908 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Apr 12 18:22:55.011920 systemd[1]: Queued start job for default target multi-user.target. Apr 12 18:22:55.011932 systemd[1]: Unnecessary job was removed for dev-vda6.device. Apr 12 18:22:55.011944 systemd[1]: Created slice system-addon\x2dconfig.slice. Apr 12 18:22:55.011954 systemd[1]: Created slice system-addon\x2drun.slice. Apr 12 18:22:55.011965 systemd[1]: Created slice system-getty.slice. Apr 12 18:22:55.011975 systemd[1]: Created slice system-modprobe.slice. Apr 12 18:22:55.011986 systemd[1]: Created slice system-serial\x2dgetty.slice. Apr 12 18:22:55.011997 systemd[1]: Created slice system-system\x2dcloudinit.slice. Apr 12 18:22:55.012008 systemd[1]: Created slice system-systemd\x2dfsck.slice. Apr 12 18:22:55.012018 systemd[1]: Created slice user.slice. Apr 12 18:22:55.012030 systemd[1]: Started systemd-ask-password-console.path. Apr 12 18:22:55.012041 systemd[1]: Started systemd-ask-password-wall.path. Apr 12 18:22:55.012051 systemd[1]: Set up automount boot.automount. Apr 12 18:22:55.012062 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Apr 12 18:22:55.012073 systemd[1]: Reached target integritysetup.target. Apr 12 18:22:55.012083 systemd[1]: Reached target remote-cryptsetup.target. Apr 12 18:22:55.012094 systemd[1]: Reached target remote-fs.target. Apr 12 18:22:55.012105 systemd[1]: Reached target slices.target. Apr 12 18:22:55.012116 systemd[1]: Reached target swap.target. Apr 12 18:22:55.012137 systemd[1]: Reached target torcx.target. Apr 12 18:22:55.012148 systemd[1]: Reached target veritysetup.target. Apr 12 18:22:55.012159 systemd[1]: Listening on systemd-coredump.socket. Apr 12 18:22:55.012169 systemd[1]: Listening on systemd-initctl.socket. Apr 12 18:22:55.012180 systemd[1]: Listening on systemd-journald-audit.socket. Apr 12 18:22:55.012191 systemd[1]: Listening on systemd-journald-dev-log.socket. Apr 12 18:22:55.012202 systemd[1]: Listening on systemd-journald.socket. Apr 12 18:22:55.012213 systemd[1]: Listening on systemd-networkd.socket. Apr 12 18:22:55.012223 systemd[1]: Listening on systemd-udevd-control.socket. Apr 12 18:22:55.012235 systemd[1]: Listening on systemd-udevd-kernel.socket. Apr 12 18:22:55.012246 systemd[1]: Listening on systemd-userdbd.socket. Apr 12 18:22:55.012256 systemd[1]: Mounting dev-hugepages.mount... Apr 12 18:22:55.012267 systemd[1]: Mounting dev-mqueue.mount... Apr 12 18:22:55.012278 systemd[1]: Mounting media.mount... Apr 12 18:22:55.012288 systemd[1]: Mounting sys-kernel-debug.mount... Apr 12 18:22:55.012299 systemd[1]: Mounting sys-kernel-tracing.mount... Apr 12 18:22:55.012309 systemd[1]: Mounting tmp.mount... Apr 12 18:22:55.012320 systemd[1]: Starting flatcar-tmpfiles.service... Apr 12 18:22:55.012331 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Apr 12 18:22:55.012343 systemd[1]: Starting kmod-static-nodes.service... Apr 12 18:22:55.012353 systemd[1]: Starting modprobe@configfs.service... Apr 12 18:22:55.012365 systemd[1]: Starting modprobe@dm_mod.service... Apr 12 18:22:55.012376 systemd[1]: Starting modprobe@drm.service... Apr 12 18:22:55.012386 systemd[1]: Starting modprobe@efi_pstore.service... Apr 12 18:22:55.012397 systemd[1]: Starting modprobe@fuse.service... Apr 12 18:22:55.012408 systemd[1]: Starting modprobe@loop.service... Apr 12 18:22:55.012419 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 12 18:22:55.012432 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Apr 12 18:22:55.012443 systemd[1]: (This warning is only shown for the first unit using IP firewalling.) Apr 12 18:22:55.012454 systemd[1]: Starting systemd-journald.service... Apr 12 18:22:55.012465 kernel: fuse: init (API version 7.34) Apr 12 18:22:55.012476 systemd[1]: Starting systemd-modules-load.service... Apr 12 18:22:55.012489 systemd[1]: Starting systemd-network-generator.service... Apr 12 18:22:55.012500 systemd[1]: Starting systemd-remount-fs.service... Apr 12 18:22:55.012526 systemd[1]: Starting systemd-udev-trigger.service... Apr 12 18:22:55.012536 systemd[1]: Mounted dev-hugepages.mount. Apr 12 18:22:55.012547 systemd[1]: Mounted dev-mqueue.mount. Apr 12 18:22:55.012557 systemd[1]: Mounted media.mount. Apr 12 18:22:55.012569 systemd[1]: Mounted sys-kernel-debug.mount. Apr 12 18:22:55.012583 systemd-journald[993]: Journal started Apr 12 18:22:55.012623 systemd-journald[993]: Runtime Journal (/run/log/journal/35011497ff6145de8237c7c5f1945980) is 6.0M, max 48.7M, 42.6M free. Apr 12 18:22:55.010000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Apr 12 18:22:55.010000 audit[993]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffdb1169f0 a2=4000 a3=1 items=0 ppid=1 pid=993 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Apr 12 18:22:55.010000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Apr 12 18:22:55.014015 systemd[1]: Mounted sys-kernel-tracing.mount. Apr 12 18:22:55.016576 systemd[1]: Started systemd-journald.service. Apr 12 18:22:55.015000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.016495 systemd[1]: Mounted tmp.mount. Apr 12 18:22:55.021563 kernel: loop: module loaded Apr 12 18:22:55.018000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.020000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.020000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.018697 systemd[1]: Finished kmod-static-nodes.service. Apr 12 18:22:55.019807 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 12 18:22:55.019955 systemd[1]: Finished modprobe@configfs.service. Apr 12 18:22:55.021191 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 12 18:22:55.021385 systemd[1]: Finished modprobe@dm_mod.service. Apr 12 18:22:55.021000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.021000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.022481 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 12 18:22:55.022686 systemd[1]: Finished modprobe@drm.service. Apr 12 18:22:55.022000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.022000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.024000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.024000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.023928 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 12 18:22:55.024124 systemd[1]: Finished modprobe@efi_pstore.service. Apr 12 18:22:55.025278 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 12 18:22:55.025465 systemd[1]: Finished modprobe@fuse.service. Apr 12 18:22:55.025000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.025000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.026482 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 12 18:22:55.026715 systemd[1]: Finished modprobe@loop.service. Apr 12 18:22:55.026000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.026000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.027988 systemd[1]: Finished systemd-modules-load.service. Apr 12 18:22:55.028000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.029370 systemd[1]: Finished systemd-network-generator.service. Apr 12 18:22:55.029000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.030756 systemd[1]: Finished systemd-remount-fs.service. Apr 12 18:22:55.031000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.032137 systemd[1]: Reached target network-pre.target. Apr 12 18:22:55.034344 systemd[1]: Mounting sys-fs-fuse-connections.mount... Apr 12 18:22:55.036393 systemd[1]: Mounting sys-kernel-config.mount... Apr 12 18:22:55.037233 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 12 18:22:55.038821 systemd[1]: Starting systemd-hwdb-update.service... Apr 12 18:22:55.040817 systemd[1]: Starting systemd-journal-flush.service... Apr 12 18:22:55.041709 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 12 18:22:55.042836 systemd[1]: Starting systemd-random-seed.service... Apr 12 18:22:55.043784 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Apr 12 18:22:55.050232 systemd-journald[993]: Time spent on flushing to /var/log/journal/35011497ff6145de8237c7c5f1945980 is 15.398ms for 937 entries. Apr 12 18:22:55.050232 systemd-journald[993]: System Journal (/var/log/journal/35011497ff6145de8237c7c5f1945980) is 8.0M, max 195.6M, 187.6M free. Apr 12 18:22:55.070563 systemd-journald[993]: Received client request to flush runtime journal. Apr 12 18:22:55.054000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.057000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.065000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.050740 systemd[1]: Starting systemd-sysctl.service... Apr 12 18:22:55.054126 systemd[1]: Finished flatcar-tmpfiles.service. Apr 12 18:22:55.055131 systemd[1]: Mounted sys-fs-fuse-connections.mount. Apr 12 18:22:55.056086 systemd[1]: Mounted sys-kernel-config.mount. Apr 12 18:22:55.057210 systemd[1]: Finished systemd-random-seed.service. Apr 12 18:22:55.058340 systemd[1]: Reached target first-boot-complete.target. Apr 12 18:22:55.060292 systemd[1]: Starting systemd-sysusers.service... Apr 12 18:22:55.065476 systemd[1]: Finished systemd-sysctl.service. Apr 12 18:22:55.071000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.071669 systemd[1]: Finished systemd-udev-trigger.service. Apr 12 18:22:55.073611 systemd[1]: Starting systemd-udev-settle.service... Apr 12 18:22:55.077000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.077397 systemd[1]: Finished systemd-journal-flush.service. Apr 12 18:22:55.082198 udevadm[1043]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 12 18:22:55.088464 systemd[1]: Finished systemd-sysusers.service. Apr 12 18:22:55.088000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.090528 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Apr 12 18:22:55.109352 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Apr 12 18:22:55.109000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.505005 systemd[1]: Finished systemd-hwdb-update.service. Apr 12 18:22:55.505000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.507061 systemd[1]: Starting systemd-udevd.service... Apr 12 18:22:55.528023 systemd-udevd[1050]: Using default interface naming scheme 'v252'. Apr 12 18:22:55.539876 systemd[1]: Started systemd-udevd.service. Apr 12 18:22:55.540000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.544008 systemd[1]: Starting systemd-networkd.service... Apr 12 18:22:55.549495 systemd[1]: Starting systemd-userdbd.service... Apr 12 18:22:55.559651 systemd[1]: Found device dev-ttyAMA0.device. Apr 12 18:22:55.590367 systemd[1]: Started systemd-userdbd.service. Apr 12 18:22:55.590000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.604977 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Apr 12 18:22:55.638233 systemd-networkd[1063]: lo: Link UP Apr 12 18:22:55.638245 systemd-networkd[1063]: lo: Gained carrier Apr 12 18:22:55.638658 systemd-networkd[1063]: Enumeration completed Apr 12 18:22:55.638774 systemd-networkd[1063]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 12 18:22:55.638789 systemd[1]: Started systemd-networkd.service. Apr 12 18:22:55.638000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.639948 systemd[1]: Finished systemd-udev-settle.service. Apr 12 18:22:55.640000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.641612 systemd-networkd[1063]: eth0: Link UP Apr 12 18:22:55.641624 systemd-networkd[1063]: eth0: Gained carrier Apr 12 18:22:55.642017 systemd[1]: Starting lvm2-activation-early.service... Apr 12 18:22:55.651918 lvm[1084]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 12 18:22:55.675684 systemd-networkd[1063]: eth0: DHCPv4 address 10.0.0.56/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 12 18:22:55.689384 systemd[1]: Finished lvm2-activation-early.service. Apr 12 18:22:55.689000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.690395 systemd[1]: Reached target cryptsetup.target. Apr 12 18:22:55.692322 systemd[1]: Starting lvm2-activation.service... Apr 12 18:22:55.695822 lvm[1086]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 12 18:22:55.730301 systemd[1]: Finished lvm2-activation.service. Apr 12 18:22:55.730000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.731211 systemd[1]: Reached target local-fs-pre.target. Apr 12 18:22:55.732050 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Apr 12 18:22:55.732081 systemd[1]: Reached target local-fs.target. Apr 12 18:22:55.732837 systemd[1]: Reached target machines.target. Apr 12 18:22:55.734700 systemd[1]: Starting ldconfig.service... Apr 12 18:22:55.735715 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Apr 12 18:22:55.735772 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Apr 12 18:22:55.736953 systemd[1]: Starting systemd-boot-update.service... Apr 12 18:22:55.738693 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Apr 12 18:22:55.740834 systemd[1]: Starting systemd-machine-id-commit.service... Apr 12 18:22:55.741756 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Apr 12 18:22:55.741809 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Apr 12 18:22:55.742880 systemd[1]: Starting systemd-tmpfiles-setup.service... Apr 12 18:22:55.743961 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1089 (bootctl) Apr 12 18:22:55.746393 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Apr 12 18:22:55.753771 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Apr 12 18:22:55.755000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.764552 systemd-tmpfiles[1092]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Apr 12 18:22:55.766939 systemd-tmpfiles[1092]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 12 18:22:55.768413 systemd-tmpfiles[1092]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 12 18:22:55.812981 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 12 18:22:55.813694 systemd[1]: Finished systemd-machine-id-commit.service. Apr 12 18:22:55.814000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.830340 systemd-fsck[1098]: fsck.fat 4.2 (2021-01-31) Apr 12 18:22:55.830340 systemd-fsck[1098]: /dev/vda1: 236 files, 117047/258078 clusters Apr 12 18:22:55.832087 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Apr 12 18:22:55.833000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.834984 systemd[1]: Mounting boot.mount... Apr 12 18:22:55.843912 systemd[1]: Mounted boot.mount. Apr 12 18:22:55.851541 systemd[1]: Finished systemd-boot-update.service. Apr 12 18:22:55.851000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.895640 ldconfig[1088]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 12 18:22:55.899984 systemd[1]: Finished ldconfig.service. Apr 12 18:22:55.900000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.921319 systemd[1]: Finished systemd-tmpfiles-setup.service. Apr 12 18:22:55.921000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.923519 systemd[1]: Starting audit-rules.service... Apr 12 18:22:55.925251 systemd[1]: Starting clean-ca-certificates.service... Apr 12 18:22:55.927165 systemd[1]: Starting systemd-journal-catalog-update.service... Apr 12 18:22:55.929617 systemd[1]: Starting systemd-resolved.service... Apr 12 18:22:55.931764 systemd[1]: Starting systemd-timesyncd.service... Apr 12 18:22:55.933607 systemd[1]: Starting systemd-update-utmp.service... Apr 12 18:22:55.936042 systemd[1]: Finished clean-ca-certificates.service. Apr 12 18:22:55.937000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.937912 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Apr 12 18:22:55.940000 audit[1114]: SYSTEM_BOOT pid=1114 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.943618 systemd[1]: Finished systemd-update-utmp.service. Apr 12 18:22:55.943000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.947893 systemd[1]: Finished systemd-journal-catalog-update.service. Apr 12 18:22:55.948000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.950365 systemd[1]: Starting systemd-update-done.service... Apr 12 18:22:55.959101 systemd[1]: Finished systemd-update-done.service. Apr 12 18:22:55.959000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:22:55.969000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Apr 12 18:22:55.969000 audit[1132]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffec9b7540 a2=420 a3=0 items=0 ppid=1107 pid=1132 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Apr 12 18:22:55.969000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Apr 12 18:22:55.970271 augenrules[1132]: No rules Apr 12 18:22:55.970903 systemd[1]: Finished audit-rules.service. Apr 12 18:22:55.995288 systemd[1]: Started systemd-timesyncd.service. Apr 12 18:22:55.995888 systemd-resolved[1111]: Positive Trust Anchors: Apr 12 18:22:55.996069 systemd-timesyncd[1113]: Contacted time server 10.0.0.1:123 (10.0.0.1). Apr 12 18:22:55.996118 systemd-timesyncd[1113]: Initial clock synchronization to Fri 2024-04-12 18:22:55.688855 UTC. Apr 12 18:22:55.996329 systemd-resolved[1111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 12 18:22:55.996406 systemd-resolved[1111]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Apr 12 18:22:55.996445 systemd[1]: Reached target time-set.target. Apr 12 18:22:56.012966 systemd-resolved[1111]: Defaulting to hostname 'linux'. Apr 12 18:22:56.014369 systemd[1]: Started systemd-resolved.service. Apr 12 18:22:56.015174 systemd[1]: Reached target network.target. Apr 12 18:22:56.015886 systemd[1]: Reached target nss-lookup.target. Apr 12 18:22:56.016618 systemd[1]: Reached target sysinit.target. Apr 12 18:22:56.017372 systemd[1]: Started motdgen.path. Apr 12 18:22:56.018066 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Apr 12 18:22:56.019189 systemd[1]: Started logrotate.timer. Apr 12 18:22:56.019924 systemd[1]: Started mdadm.timer. Apr 12 18:22:56.020538 systemd[1]: Started systemd-tmpfiles-clean.timer. Apr 12 18:22:56.021270 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 12 18:22:56.021304 systemd[1]: Reached target paths.target. Apr 12 18:22:56.021991 systemd[1]: Reached target timers.target. Apr 12 18:22:56.022956 systemd[1]: Listening on dbus.socket. Apr 12 18:22:56.024679 systemd[1]: Starting docker.socket... Apr 12 18:22:56.026169 systemd[1]: Listening on sshd.socket. Apr 12 18:22:56.026945 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Apr 12 18:22:56.027231 systemd[1]: Listening on docker.socket. Apr 12 18:22:56.027971 systemd[1]: Reached target sockets.target. Apr 12 18:22:56.028661 systemd[1]: Reached target basic.target. Apr 12 18:22:56.029442 systemd[1]: System is tainted: cgroupsv1 Apr 12 18:22:56.029495 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Apr 12 18:22:56.029523 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Apr 12 18:22:56.030513 systemd[1]: Starting containerd.service... Apr 12 18:22:56.032113 systemd[1]: Starting dbus.service... Apr 12 18:22:56.033647 systemd[1]: Starting enable-oem-cloudinit.service... Apr 12 18:22:56.035385 systemd[1]: Starting extend-filesystems.service... Apr 12 18:22:56.036241 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Apr 12 18:22:56.037319 systemd[1]: Starting motdgen.service... Apr 12 18:22:56.039074 systemd[1]: Starting prepare-cni-plugins.service... Apr 12 18:22:56.039787 jq[1144]: false Apr 12 18:22:56.041254 systemd[1]: Starting prepare-critools.service... Apr 12 18:22:56.043043 systemd[1]: Starting ssh-key-proc-cmdline.service... Apr 12 18:22:56.044836 systemd[1]: Starting sshd-keygen.service... Apr 12 18:22:56.047058 systemd[1]: Starting systemd-logind.service... Apr 12 18:22:56.048009 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Apr 12 18:22:56.048070 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 12 18:22:56.049101 systemd[1]: Starting update-engine.service... Apr 12 18:22:56.051991 systemd[1]: Starting update-ssh-keys-after-ignition.service... Apr 12 18:22:56.054217 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 12 18:22:56.056385 jq[1164]: true Apr 12 18:22:56.054599 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Apr 12 18:22:56.057733 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 12 18:22:56.059796 systemd[1]: Finished ssh-key-proc-cmdline.service. Apr 12 18:22:56.065474 tar[1166]: ./ Apr 12 18:22:56.065474 tar[1166]: ./loopback Apr 12 18:22:56.075311 tar[1168]: crictl Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda1 Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda2 Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda3 Apr 12 18:22:56.075492 extend-filesystems[1145]: Found usr Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda4 Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda6 Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda7 Apr 12 18:22:56.075492 extend-filesystems[1145]: Found vda9 Apr 12 18:22:56.075492 extend-filesystems[1145]: Checking size of /dev/vda9 Apr 12 18:22:56.118429 jq[1171]: true Apr 12 18:22:56.095238 dbus-daemon[1143]: [system] SELinux support is enabled Apr 12 18:22:56.088221 systemd[1]: motdgen.service: Deactivated successfully. Apr 12 18:22:56.118993 extend-filesystems[1145]: Resized partition /dev/vda9 Apr 12 18:22:56.088462 systemd[1]: Finished motdgen.service. Apr 12 18:22:56.095404 systemd[1]: Started dbus.service. Apr 12 18:22:56.097571 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Apr 12 18:22:56.097628 systemd[1]: Reached target system-config.target. Apr 12 18:22:56.098371 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Apr 12 18:22:56.098388 systemd[1]: Reached target user-config.target. Apr 12 18:22:56.122772 tar[1166]: ./bandwidth Apr 12 18:22:56.129055 extend-filesystems[1205]: resize2fs 1.46.5 (30-Dec-2021) Apr 12 18:22:56.140741 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Apr 12 18:22:56.139461 systemd-logind[1158]: Watching system buttons on /dev/input/event0 (Power Button) Apr 12 18:22:56.139702 systemd-logind[1158]: New seat seat0. Apr 12 18:22:56.141208 systemd[1]: Started systemd-logind.service. Apr 12 18:22:56.150961 update_engine[1161]: I0412 18:22:56.150739 1161 main.cc:92] Flatcar Update Engine starting Apr 12 18:22:56.153259 systemd[1]: Started update-engine.service. Apr 12 18:22:56.153451 update_engine[1161]: I0412 18:22:56.153420 1161 update_check_scheduler.cc:74] Next update check in 4m53s Apr 12 18:22:56.156002 systemd[1]: Started locksmithd.service. Apr 12 18:22:56.165206 bash[1209]: Updated "/home/core/.ssh/authorized_keys" Apr 12 18:22:56.167206 systemd[1]: Finished update-ssh-keys-after-ignition.service. Apr 12 18:22:56.170608 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Apr 12 18:22:56.178050 extend-filesystems[1205]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Apr 12 18:22:56.178050 extend-filesystems[1205]: old_desc_blocks = 1, new_desc_blocks = 1 Apr 12 18:22:56.178050 extend-filesystems[1205]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Apr 12 18:22:56.183173 extend-filesystems[1145]: Resized filesystem in /dev/vda9 Apr 12 18:22:56.179396 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 12 18:22:56.179635 systemd[1]: Finished extend-filesystems.service. Apr 12 18:22:56.209538 env[1173]: time="2024-04-12T18:22:56.209234282Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Apr 12 18:22:56.215609 tar[1166]: ./ptp Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.228789936Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.228926600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.230440095Z" 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/5.15.154-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.230464443Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.230727347Z" 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 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.230747117Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.230759734Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.230769003Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.230841086Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:22:56.234610 env[1173]: time="2024-04-12T18:22:56.231088488Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.231217498Z" 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 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.231232422Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.231278964Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.231291234Z" level=info msg="metadata content store policy set" policy=shared Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234243333Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234269797Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234282067Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234307184Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234319339Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234332801Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234346533Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234803336Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.234854 env[1173]: time="2024-04-12T18:22:56.234824645Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.235073 env[1173]: time="2024-04-12T18:22:56.234837030Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.235073 env[1173]: time="2024-04-12T18:22:56.234848762Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.235073 env[1173]: time="2024-04-12T18:22:56.234861994Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 12 18:22:56.235073 env[1173]: time="2024-04-12T18:22:56.234964347Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 12 18:22:56.235073 env[1173]: time="2024-04-12T18:22:56.235042545Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 12 18:22:56.235369 env[1173]: time="2024-04-12T18:22:56.235348837Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 12 18:22:56.235401 env[1173]: time="2024-04-12T18:22:56.235380070Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235401 env[1173]: time="2024-04-12T18:22:56.235392148Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 12 18:22:56.235567 env[1173]: time="2024-04-12T18:22:56.235552967Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235597 env[1173]: time="2024-04-12T18:22:56.235571276Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235597 env[1173]: time="2024-04-12T18:22:56.235583315Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235597 env[1173]: time="2024-04-12T18:22:56.235594932Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235668 env[1173]: time="2024-04-12T18:22:56.235606817Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235668 env[1173]: time="2024-04-12T18:22:56.235618741Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235668 env[1173]: time="2024-04-12T18:22:56.235629703Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235668 env[1173]: time="2024-04-12T18:22:56.235639973Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235668 env[1173]: time="2024-04-12T18:22:56.235651974Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 12 18:22:56.235781 env[1173]: time="2024-04-12T18:22:56.235761598Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235811 env[1173]: time="2024-04-12T18:22:56.235785561Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235811 env[1173]: time="2024-04-12T18:22:56.235797946Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.235847 env[1173]: time="2024-04-12T18:22:56.235809563Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 12 18:22:56.235847 env[1173]: time="2024-04-12T18:22:56.235823294Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Apr 12 18:22:56.235847 env[1173]: time="2024-04-12T18:22:56.235833103Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 12 18:22:56.235904 env[1173]: time="2024-04-12T18:22:56.235848296Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Apr 12 18:22:56.235904 env[1173]: time="2024-04-12T18:22:56.235879837Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 12 18:22:56.236097 env[1173]: time="2024-04-12T18:22:56.236048388Z" 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 BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 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} 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}" Apr 12 18:22:56.239565 env[1173]: time="2024-04-12T18:22:56.236103161Z" level=info msg="Connect containerd service" Apr 12 18:22:56.239565 env[1173]: time="2024-04-12T18:22:56.236130355Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 12 18:22:56.239565 env[1173]: time="2024-04-12T18:22:56.236694742Z" 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" Apr 12 18:22:56.239565 env[1173]: time="2024-04-12T18:22:56.237199164Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 12 18:22:56.239565 env[1173]: time="2024-04-12T18:22:56.237236705Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 12 18:22:56.239565 env[1173]: time="2024-04-12T18:22:56.237284785Z" level=info msg="containerd successfully booted in 0.028884s" Apr 12 18:22:56.238487 systemd[1]: Started containerd.service. Apr 12 18:22:56.240284 env[1173]: time="2024-04-12T18:22:56.240161456Z" level=info msg="Start subscribing containerd event" Apr 12 18:22:56.240480 env[1173]: time="2024-04-12T18:22:56.240461516Z" level=info msg="Start recovering state" Apr 12 18:22:56.240710 env[1173]: time="2024-04-12T18:22:56.240602527Z" level=info msg="Start event monitor" Apr 12 18:22:56.244247 env[1173]: time="2024-04-12T18:22:56.240766308Z" level=info msg="Start snapshots syncer" Apr 12 18:22:56.244247 env[1173]: time="2024-04-12T18:22:56.244116090Z" level=info msg="Start cni network conf syncer for default" Apr 12 18:22:56.244247 env[1173]: time="2024-04-12T18:22:56.244125475Z" level=info msg="Start streaming server" Apr 12 18:22:56.253525 tar[1166]: ./vlan Apr 12 18:22:56.265640 locksmithd[1210]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 12 18:22:56.277500 tar[1166]: ./host-device Apr 12 18:22:56.304333 tar[1166]: ./tuning Apr 12 18:22:56.328454 tar[1166]: ./vrf Apr 12 18:22:56.353555 tar[1166]: ./sbr Apr 12 18:22:56.378054 tar[1166]: ./tap Apr 12 18:22:56.406330 tar[1166]: ./dhcp Apr 12 18:22:56.475363 tar[1166]: ./static Apr 12 18:22:56.495743 tar[1166]: ./firewall Apr 12 18:22:56.515371 systemd[1]: Finished prepare-critools.service. Apr 12 18:22:56.526570 tar[1166]: ./macvlan Apr 12 18:22:56.554606 tar[1166]: ./dummy Apr 12 18:22:56.582153 tar[1166]: ./bridge Apr 12 18:22:56.612123 tar[1166]: ./ipvlan Apr 12 18:22:56.639618 tar[1166]: ./portmap Apr 12 18:22:56.665863 tar[1166]: ./host-local Apr 12 18:22:56.698056 systemd[1]: Finished prepare-cni-plugins.service. Apr 12 18:22:57.019700 systemd-networkd[1063]: eth0: Gained IPv6LL Apr 12 18:22:59.226938 sshd_keygen[1182]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 12 18:22:59.243358 systemd[1]: Finished sshd-keygen.service. Apr 12 18:22:59.245643 systemd[1]: Starting issuegen.service... Apr 12 18:22:59.249918 systemd[1]: issuegen.service: Deactivated successfully. Apr 12 18:22:59.250105 systemd[1]: Finished issuegen.service. Apr 12 18:22:59.252088 systemd[1]: Starting systemd-user-sessions.service... Apr 12 18:22:59.264762 systemd[1]: Finished systemd-user-sessions.service. Apr 12 18:22:59.266876 systemd[1]: Started getty@tty1.service. Apr 12 18:22:59.272975 systemd[1]: Started serial-getty@ttyAMA0.service. Apr 12 18:22:59.273932 systemd[1]: Reached target getty.target. Apr 12 18:22:59.274694 systemd[1]: Reached target multi-user.target. Apr 12 18:22:59.277721 systemd[1]: Starting systemd-update-utmp-runlevel.service... Apr 12 18:22:59.286442 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 12 18:22:59.286676 systemd[1]: Finished systemd-update-utmp-runlevel.service. Apr 12 18:22:59.287655 systemd[1]: Startup finished in 5.998s (kernel) + 6.284s (userspace) = 12.283s. Apr 12 18:23:00.512911 systemd[1]: Created slice system-sshd.slice. Apr 12 18:23:00.513953 systemd[1]: Started sshd@0-10.0.0.56:22-10.0.0.1:52064.service. Apr 12 18:23:00.563078 sshd[1251]: Accepted publickey for core from 10.0.0.1 port 52064 ssh2: RSA SHA256:QUhY8l8fo09wOQgBdU1SXiqM8N1XKRTa5W0hOYR625c Apr 12 18:23:00.564908 sshd[1251]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:23:00.576610 systemd-logind[1158]: New session 1 of user core. Apr 12 18:23:00.577344 systemd[1]: Created slice user-500.slice. Apr 12 18:23:00.578178 systemd[1]: Starting user-runtime-dir@500.service... Apr 12 18:23:00.585938 systemd[1]: Finished user-runtime-dir@500.service. Apr 12 18:23:00.586995 systemd[1]: Starting user@500.service... Apr 12 18:23:00.589647 (systemd)[1256]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:23:00.647765 systemd[1256]: Queued start job for default target default.target. Apr 12 18:23:00.647950 systemd[1256]: Reached target paths.target. Apr 12 18:23:00.647964 systemd[1256]: Reached target sockets.target. Apr 12 18:23:00.647975 systemd[1256]: Reached target timers.target. Apr 12 18:23:00.647997 systemd[1256]: Reached target basic.target. Apr 12 18:23:00.648035 systemd[1256]: Reached target default.target. Apr 12 18:23:00.648056 systemd[1256]: Startup finished in 53ms. Apr 12 18:23:00.648483 systemd[1]: Started user@500.service. Apr 12 18:23:00.649244 systemd[1]: Started session-1.scope. Apr 12 18:23:00.698259 systemd[1]: Started sshd@1-10.0.0.56:22-10.0.0.1:52066.service. Apr 12 18:23:00.732627 sshd[1265]: Accepted publickey for core from 10.0.0.1 port 52066 ssh2: RSA SHA256:QUhY8l8fo09wOQgBdU1SXiqM8N1XKRTa5W0hOYR625c Apr 12 18:23:00.733903 sshd[1265]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:23:00.737182 systemd-logind[1158]: New session 2 of user core. Apr 12 18:23:00.737897 systemd[1]: Started session-2.scope. Apr 12 18:23:00.791087 sshd[1265]: pam_unix(sshd:session): session closed for user core Apr 12 18:23:00.793183 systemd[1]: Started sshd@2-10.0.0.56:22-10.0.0.1:52082.service. Apr 12 18:23:00.794789 systemd[1]: sshd@1-10.0.0.56:22-10.0.0.1:52066.service: Deactivated successfully. Apr 12 18:23:00.795601 systemd-logind[1158]: Session 2 logged out. Waiting for processes to exit. Apr 12 18:23:00.795626 systemd[1]: session-2.scope: Deactivated successfully. Apr 12 18:23:00.796316 systemd-logind[1158]: Removed session 2. Apr 12 18:23:00.829320 sshd[1270]: Accepted publickey for core from 10.0.0.1 port 52082 ssh2: RSA SHA256:QUhY8l8fo09wOQgBdU1SXiqM8N1XKRTa5W0hOYR625c Apr 12 18:23:00.830314 sshd[1270]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:23:00.833071 systemd-logind[1158]: New session 3 of user core. Apr 12 18:23:00.833768 systemd[1]: Started session-3.scope. Apr 12 18:23:00.881251 sshd[1270]: pam_unix(sshd:session): session closed for user core Apr 12 18:23:00.882997 systemd[1]: Started sshd@3-10.0.0.56:22-10.0.0.1:52084.service. Apr 12 18:23:00.883402 systemd[1]: sshd@2-10.0.0.56:22-10.0.0.1:52082.service: Deactivated successfully. Apr 12 18:23:00.884412 systemd-logind[1158]: Session 3 logged out. Waiting for processes to exit. Apr 12 18:23:00.884435 systemd[1]: session-3.scope: Deactivated successfully. Apr 12 18:23:00.885298 systemd-logind[1158]: Removed session 3. Apr 12 18:23:00.918307 sshd[1277]: Accepted publickey for core from 10.0.0.1 port 52084 ssh2: RSA SHA256:QUhY8l8fo09wOQgBdU1SXiqM8N1XKRTa5W0hOYR625c Apr 12 18:23:00.919639 sshd[1277]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:23:00.922386 systemd-logind[1158]: New session 4 of user core. Apr 12 18:23:00.923081 systemd[1]: Started session-4.scope. Apr 12 18:23:00.973642 sshd[1277]: pam_unix(sshd:session): session closed for user core Apr 12 18:23:00.976021 systemd[1]: Started sshd@4-10.0.0.56:22-10.0.0.1:52094.service. Apr 12 18:23:00.976410 systemd[1]: sshd@3-10.0.0.56:22-10.0.0.1:52084.service: Deactivated successfully. Apr 12 18:23:00.977276 systemd-logind[1158]: Session 4 logged out. Waiting for processes to exit. Apr 12 18:23:00.977330 systemd[1]: session-4.scope: Deactivated successfully. Apr 12 18:23:00.978079 systemd-logind[1158]: Removed session 4. Apr 12 18:23:01.010164 sshd[1285]: Accepted publickey for core from 10.0.0.1 port 52094 ssh2: RSA SHA256:QUhY8l8fo09wOQgBdU1SXiqM8N1XKRTa5W0hOYR625c Apr 12 18:23:01.011150 sshd[1285]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:23:01.014826 systemd[1]: Started session-5.scope. Apr 12 18:23:01.015073 systemd-logind[1158]: New session 5 of user core. Apr 12 18:23:01.071921 sudo[1290]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 12 18:23:01.072813 sudo[1290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Apr 12 18:23:01.581021 systemd[1]: Reloading. Apr 12 18:23:01.624421 /usr/lib/systemd/system-generators/torcx-generator[1320]: time="2024-04-12T18:23:01Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.3 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.3 /var/lib/torcx/store]" Apr 12 18:23:01.624448 /usr/lib/systemd/system-generators/torcx-generator[1320]: time="2024-04-12T18:23:01Z" level=info msg="torcx already run" Apr 12 18:23:01.680736 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Apr 12 18:23:01.680753 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Apr 12 18:23:01.695309 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Apr 12 18:23:01.749616 systemd[1]: Starting systemd-networkd-wait-online.service... Apr 12 18:23:01.756428 systemd[1]: Finished systemd-networkd-wait-online.service. Apr 12 18:23:01.756835 systemd[1]: Reached target network-online.target. Apr 12 18:23:01.758106 systemd[1]: Started kubelet.service. Apr 12 18:23:01.766517 systemd[1]: Starting coreos-metadata.service... Apr 12 18:23:01.773008 systemd[1]: coreos-metadata.service: Deactivated successfully. Apr 12 18:23:01.773212 systemd[1]: Finished coreos-metadata.service. Apr 12 18:23:01.892002 kubelet[1365]: E0412 18:23:01.891876 1365 run.go:74] "command failed" err="failed to load kubelet config file, 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, path: /var/lib/kubelet/config.yaml" Apr 12 18:23:01.894285 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 12 18:23:01.894432 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 12 18:23:02.023049 systemd[1]: Stopped kubelet.service. Apr 12 18:23:02.036850 systemd[1]: Reloading. Apr 12 18:23:02.073923 /usr/lib/systemd/system-generators/torcx-generator[1436]: time="2024-04-12T18:23:02Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.3 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.3 /var/lib/torcx/store]" Apr 12 18:23:02.074229 /usr/lib/systemd/system-generators/torcx-generator[1436]: time="2024-04-12T18:23:02Z" level=info msg="torcx already run" Apr 12 18:23:02.134040 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Apr 12 18:23:02.134186 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Apr 12 18:23:02.149269 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Apr 12 18:23:02.209974 systemd[1]: Started kubelet.service. Apr 12 18:23:02.251326 kubelet[1481]: 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. Apr 12 18:23:02.251326 kubelet[1481]: 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. Apr 12 18:23:02.251326 kubelet[1481]: 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. Apr 12 18:23:02.251642 kubelet[1481]: I0412 18:23:02.251366 1481 server.go:199] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Apr 12 18:23:02.940563 kubelet[1481]: I0412 18:23:02.940536 1481 server.go:415] "Kubelet version" kubeletVersion="v1.27.2" Apr 12 18:23:02.940563 kubelet[1481]: I0412 18:23:02.940562 1481 server.go:417] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 12 18:23:02.940807 kubelet[1481]: I0412 18:23:02.940774 1481 server.go:837] "Client rotation is on, will bootstrap in background" Apr 12 18:23:02.943895 kubelet[1481]: I0412 18:23:02.943876 1481 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 12 18:23:02.946515 kubelet[1481]: W0412 18:23:02.946481 1481 machine.go:65] Cannot read vendor id correctly, set empty. Apr 12 18:23:02.947248 kubelet[1481]: I0412 18:23:02.947214 1481 server.go:662] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Apr 12 18:23:02.947590 kubelet[1481]: I0412 18:23:02.947567 1481 container_manager_linux.go:266] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 12 18:23:02.947645 kubelet[1481]: I0412 18:23:02.947635 1481 container_manager_linux.go:271] "Creating Container Manager object based on Node Config" nodeConfig={RuntimeCgroupsName: SystemCgroupsName: KubeletCgroupsName: KubeletOOMScoreAdj:-999 ContainerRuntime: CgroupsPerQOS:true CgroupRoot:/ CgroupDriver:cgroupfs KubeletRootDir:/var/lib/kubelet ProtectKernelDefaults:false NodeAllocatableConfig:{KubeReservedCgroupName: SystemReservedCgroupName: ReservedSystemCPUs: EnforceNodeAllocatable:map[pods:{}] KubeReserved:map[] SystemReserved:map[] HardEvictionThresholds:[{Signal:memory.available Operator:LessThan Value:{Quantity:100Mi Percentage:0} GracePeriod:0s MinReclaim:} {Signal:nodefs.available Operator:LessThan Value:{Quantity: Percentage:0.1} GracePeriod:0s MinReclaim:} {Signal:nodefs.inodesFree Operator:LessThan Value:{Quantity: Percentage:0.05} GracePeriod:0s MinReclaim:} {Signal:imagefs.available Operator:LessThan Value:{Quantity: Percentage:0.15} GracePeriod:0s MinReclaim:}]} QOSReserved:map[] CPUManagerPolicy:none CPUManagerPolicyOptions:map[] TopologyManagerScope:container CPUManagerReconcilePeriod:10s ExperimentalMemoryManagerPolicy:None ExperimentalMemoryManagerReservedMemory:[] PodPidsLimit:-1 EnforceCPULimits:true CPUCFSQuotaPeriod:100ms TopologyManagerPolicy:none ExperimentalTopologyManagerPolicyOptions:map[]} Apr 12 18:23:02.947711 kubelet[1481]: I0412 18:23:02.947650 1481 topology_manager.go:136] "Creating topology manager with policy per scope" topologyPolicyName="none" topologyScopeName="container" Apr 12 18:23:02.947711 kubelet[1481]: I0412 18:23:02.947660 1481 container_manager_linux.go:302] "Creating device plugin manager" Apr 12 18:23:02.947766 kubelet[1481]: I0412 18:23:02.947741 1481 state_mem.go:36] "Initialized new in-memory state store" Apr 12 18:23:02.953288 kubelet[1481]: I0412 18:23:02.952440 1481 kubelet.go:405] "Attempting to sync node with API server" Apr 12 18:23:02.953288 kubelet[1481]: I0412 18:23:02.952470 1481 kubelet.go:298] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 12 18:23:02.953288 kubelet[1481]: I0412 18:23:02.952497 1481 kubelet.go:309] "Adding apiserver pod source" Apr 12 18:23:02.953288 kubelet[1481]: I0412 18:23:02.952523 1481 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 12 18:23:02.953288 kubelet[1481]: E0412 18:23:02.953242 1481 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:02.953288 kubelet[1481]: E0412 18:23:02.953295 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:02.954017 kubelet[1481]: I0412 18:23:02.953943 1481 kuberuntime_manager.go:257] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Apr 12 18:23:02.954381 kubelet[1481]: W0412 18:23:02.954366 1481 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 12 18:23:02.955020 kubelet[1481]: I0412 18:23:02.954996 1481 server.go:1168] "Started kubelet" Apr 12 18:23:02.955646 kubelet[1481]: I0412 18:23:02.955624 1481 ratelimit.go:65] "Setting rate limiting for podresources endpoint" qps=100 burstTokens=10 Apr 12 18:23:02.956236 kubelet[1481]: I0412 18:23:02.955919 1481 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Apr 12 18:23:02.956236 kubelet[1481]: E0412 18:23:02.955952 1481 cri_stats_provider.go:455] "Failed to get the info of the filesystem with mountpoint" err="unable to find data in memory cache" mountpoint="/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs" Apr 12 18:23:02.956236 kubelet[1481]: E0412 18:23:02.955972 1481 kubelet.go:1400] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 12 18:23:02.956508 kubelet[1481]: I0412 18:23:02.956475 1481 server.go:461] "Adding debug handlers to kubelet server" Apr 12 18:23:02.957146 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Apr 12 18:23:02.957399 kubelet[1481]: I0412 18:23:02.957323 1481 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Apr 12 18:23:02.957860 kubelet[1481]: I0412 18:23:02.957492 1481 volume_manager.go:284] "Starting Kubelet Volume Manager" Apr 12 18:23:02.957860 kubelet[1481]: I0412 18:23:02.957615 1481 desired_state_of_world_populator.go:145] "Desired state populator starts to run" Apr 12 18:23:02.957860 kubelet[1481]: E0412 18:23:02.957825 1481 kubelet_node_status.go:458] "Error getting the current node from lister" err="node \"10.0.0.56\" not found" Apr 12 18:23:02.963147 kubelet[1481]: W0412 18:23:02.963117 1481 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Apr 12 18:23:02.963250 kubelet[1481]: E0412 18:23:02.963237 1481 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Apr 12 18:23:02.963374 kubelet[1481]: E0412 18:23:02.963359 1481 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.56\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Apr 12 18:23:02.963584 kubelet[1481]: W0412 18:23:02.963566 1481 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes "10.0.0.56" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 12 18:23:02.963677 kubelet[1481]: E0412 18:23:02.963665 1481 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.56" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 12 18:23:02.963779 kubelet[1481]: W0412 18:23:02.963765 1481 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 12 18:23:02.963846 kubelet[1481]: E0412 18:23:02.963836 1481 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 12 18:23:02.964081 kubelet[1481]: E0412 18:23:02.963990 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a5264944c", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"Starting", Message:"Starting kubelet.", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 954980428, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 954980428, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:02.967206 kubelet[1481]: E0412 18:23:02.966764 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a52739650", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"InvalidDiskCapacity", Message:"invalid capacity 0 on image filesystem", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 955963984, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 955963984, time.Local), Count:1, Type:"Warning", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:02.992812 kubelet[1481]: I0412 18:23:02.992788 1481 cpu_manager.go:214] "Starting CPU manager" policy="none" Apr 12 18:23:02.992812 kubelet[1481]: I0412 18:23:02.992809 1481 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Apr 12 18:23:02.992942 kubelet[1481]: I0412 18:23:02.992827 1481 state_mem.go:36] "Initialized new in-memory state store" Apr 12 18:23:02.993353 kubelet[1481]: E0412 18:23:02.993267 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c411d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.56 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992183581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992183581, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:02.994105 kubelet[1481]: E0412 18:23:02.994035 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c73f1", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.56 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992196593, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992196593, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:02.994499 kubelet[1481]: I0412 18:23:02.994473 1481 policy_none.go:49] "None policy: Start" Apr 12 18:23:02.994783 kubelet[1481]: E0412 18:23:02.994717 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c7f9d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.56 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992199581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992199581, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:02.995119 kubelet[1481]: I0412 18:23:02.995089 1481 memory_manager.go:169] "Starting memorymanager" policy="None" Apr 12 18:23:02.995119 kubelet[1481]: I0412 18:23:02.995116 1481 state_mem.go:35] "Initializing new in-memory state store" Apr 12 18:23:03.000656 kubelet[1481]: I0412 18:23:03.000634 1481 manager.go:455] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Apr 12 18:23:03.000857 kubelet[1481]: I0412 18:23:03.000843 1481 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Apr 12 18:23:03.001877 kubelet[1481]: E0412 18:23:03.001852 1481 eviction_manager.go:262] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.56\" not found" Apr 12 18:23:03.002094 kubelet[1481]: E0412 18:23:03.002030 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a5525c004", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeAllocatableEnforced", Message:"Updated Node Allocatable limit across pods", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 1194500, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 1194500, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.044060 kubelet[1481]: I0412 18:23:03.044038 1481 kubelet_network_linux.go:63] "Initialized iptables rules." protocol=IPv4 Apr 12 18:23:03.045006 kubelet[1481]: I0412 18:23:03.044991 1481 kubelet_network_linux.go:63] "Initialized iptables rules." protocol=IPv6 Apr 12 18:23:03.045046 kubelet[1481]: I0412 18:23:03.045021 1481 status_manager.go:207] "Starting to sync pod status with apiserver" Apr 12 18:23:03.045046 kubelet[1481]: I0412 18:23:03.045039 1481 kubelet.go:2257] "Starting kubelet main sync loop" Apr 12 18:23:03.045111 kubelet[1481]: E0412 18:23:03.045088 1481 kubelet.go:2281] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Apr 12 18:23:03.046709 kubelet[1481]: W0412 18:23:03.046690 1481 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Apr 12 18:23:03.046809 kubelet[1481]: E0412 18:23:03.046796 1481 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.RuntimeClass: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Apr 12 18:23:03.058734 kubelet[1481]: I0412 18:23:03.058712 1481 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.56" Apr 12 18:23:03.059824 kubelet[1481]: E0412 18:23:03.059750 1481 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.56" Apr 12 18:23:03.060086 kubelet[1481]: E0412 18:23:03.060017 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c411d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.56 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992183581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 58673864, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c411d" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.061018 kubelet[1481]: E0412 18:23:03.060953 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c73f1", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.56 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992196593, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 58687771, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c73f1" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.061799 kubelet[1481]: E0412 18:23:03.061743 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c7f9d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.56 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992199581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 58691238, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c7f9d" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.165022 kubelet[1481]: E0412 18:23:03.164995 1481 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.56\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="400ms" Apr 12 18:23:03.260885 kubelet[1481]: I0412 18:23:03.260809 1481 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.56" Apr 12 18:23:03.262519 kubelet[1481]: E0412 18:23:03.262402 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c411d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.56 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992183581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 260762537, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c411d" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.263249 kubelet[1481]: E0412 18:23:03.263216 1481 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.56" Apr 12 18:23:03.263520 kubelet[1481]: E0412 18:23:03.263451 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c73f1", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.56 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992196593, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 260784599, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c73f1" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.264278 kubelet[1481]: E0412 18:23:03.264215 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c7f9d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.56 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992199581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 260787633, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c7f9d" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.566591 kubelet[1481]: E0412 18:23:03.566419 1481 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.56\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="800ms" Apr 12 18:23:03.664428 kubelet[1481]: I0412 18:23:03.664405 1481 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.56" Apr 12 18:23:03.665673 kubelet[1481]: E0412 18:23:03.665546 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c411d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.56 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992183581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 664362914, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c411d" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.665794 kubelet[1481]: E0412 18:23:03.665763 1481 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.56" Apr 12 18:23:03.666507 kubelet[1481]: E0412 18:23:03.666446 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c73f1", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.56 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992196593, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 664376743, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c73f1" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.667330 kubelet[1481]: E0412 18:23:03.667264 1481 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.56.17c59b7a549c7f9d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"10.0.0.56", UID:"10.0.0.56", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.56 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.56"}, FirstTimestamp:time.Date(2024, time.April, 12, 18, 23, 2, 992199581, time.Local), LastTimestamp:time.Date(2024, time.April, 12, 18, 23, 3, 664382258, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"", ReportingInstance:""}': 'events "10.0.0.56.17c59b7a549c7f9d" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Apr 12 18:23:03.802834 kubelet[1481]: W0412 18:23:03.802789 1481 reflector.go:533] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 12 18:23:03.802834 kubelet[1481]: E0412 18:23:03.802824 1481 reflector.go:148] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 12 18:23:03.942216 kubelet[1481]: I0412 18:23:03.942102 1481 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 12 18:23:03.953400 kubelet[1481]: E0412 18:23:03.953364 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:04.310784 kubelet[1481]: E0412 18:23:04.310647 1481 csi_plugin.go:295] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.56" not found Apr 12 18:23:04.370885 kubelet[1481]: E0412 18:23:04.370833 1481 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.56\" not found" node="10.0.0.56" Apr 12 18:23:04.467158 kubelet[1481]: I0412 18:23:04.467129 1481 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.56" Apr 12 18:23:04.471380 kubelet[1481]: I0412 18:23:04.471347 1481 kubelet_node_status.go:73] "Successfully registered node" node="10.0.0.56" Apr 12 18:23:04.480512 kubelet[1481]: I0412 18:23:04.480461 1481 kuberuntime_manager.go:1460] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 12 18:23:04.480806 env[1173]: time="2024-04-12T18:23:04.480750941Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 12 18:23:04.481064 kubelet[1481]: I0412 18:23:04.480917 1481 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 12 18:23:04.723345 sudo[1290]: pam_unix(sudo:session): session closed for user root Apr 12 18:23:04.725134 sshd[1285]: pam_unix(sshd:session): session closed for user core Apr 12 18:23:04.727568 systemd[1]: sshd@4-10.0.0.56:22-10.0.0.1:52094.service: Deactivated successfully. Apr 12 18:23:04.728598 systemd[1]: session-5.scope: Deactivated successfully. Apr 12 18:23:04.728980 systemd-logind[1158]: Session 5 logged out. Waiting for processes to exit. Apr 12 18:23:04.729726 systemd-logind[1158]: Removed session 5. Apr 12 18:23:04.953475 kubelet[1481]: I0412 18:23:04.953433 1481 apiserver.go:52] "Watching apiserver" Apr 12 18:23:04.953475 kubelet[1481]: E0412 18:23:04.953460 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:04.956128 kubelet[1481]: I0412 18:23:04.956103 1481 topology_manager.go:212] "Topology Admit Handler" Apr 12 18:23:04.956285 kubelet[1481]: I0412 18:23:04.956269 1481 topology_manager.go:212] "Topology Admit Handler" Apr 12 18:23:04.960374 kubelet[1481]: I0412 18:23:04.960335 1481 desired_state_of_world_populator.go:153] "Finished populating initial desired state of world" Apr 12 18:23:04.967967 kubelet[1481]: I0412 18:23:04.967937 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f9e6be95-e7b7-45bd-9b2d-400074ffa4f0-cni\") pod \"kube-flannel-ds-p8vr6\" (UID: \"f9e6be95-e7b7-45bd-9b2d-400074ffa4f0\") " pod="kube-flannel/kube-flannel-ds-p8vr6" Apr 12 18:23:04.968109 kubelet[1481]: I0412 18:23:04.968095 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2b5nl\" (UniqueName: \"kubernetes.io/projected/f9e6be95-e7b7-45bd-9b2d-400074ffa4f0-kube-api-access-2b5nl\") pod \"kube-flannel-ds-p8vr6\" (UID: \"f9e6be95-e7b7-45bd-9b2d-400074ffa4f0\") " pod="kube-flannel/kube-flannel-ds-p8vr6" Apr 12 18:23:04.968251 kubelet[1481]: I0412 18:23:04.968216 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/052d6adc-ba8e-4926-adaf-20110103d8e7-lib-modules\") pod \"kube-proxy-2mg29\" (UID: \"052d6adc-ba8e-4926-adaf-20110103d8e7\") " pod="kube-system/kube-proxy-2mg29" Apr 12 18:23:04.968294 kubelet[1481]: I0412 18:23:04.968278 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hsw9q\" (UniqueName: \"kubernetes.io/projected/052d6adc-ba8e-4926-adaf-20110103d8e7-kube-api-access-hsw9q\") pod \"kube-proxy-2mg29\" (UID: \"052d6adc-ba8e-4926-adaf-20110103d8e7\") " pod="kube-system/kube-proxy-2mg29" Apr 12 18:23:04.968319 kubelet[1481]: I0412 18:23:04.968298 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f9e6be95-e7b7-45bd-9b2d-400074ffa4f0-run\") pod \"kube-flannel-ds-p8vr6\" (UID: \"f9e6be95-e7b7-45bd-9b2d-400074ffa4f0\") " pod="kube-flannel/kube-flannel-ds-p8vr6" Apr 12 18:23:04.968365 kubelet[1481]: I0412 18:23:04.968334 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f9e6be95-e7b7-45bd-9b2d-400074ffa4f0-cni-plugin\") pod \"kube-flannel-ds-p8vr6\" (UID: \"f9e6be95-e7b7-45bd-9b2d-400074ffa4f0\") " pod="kube-flannel/kube-flannel-ds-p8vr6" Apr 12 18:23:04.968365 kubelet[1481]: I0412 18:23:04.968352 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f9e6be95-e7b7-45bd-9b2d-400074ffa4f0-flannel-cfg\") pod \"kube-flannel-ds-p8vr6\" (UID: \"f9e6be95-e7b7-45bd-9b2d-400074ffa4f0\") " pod="kube-flannel/kube-flannel-ds-p8vr6" Apr 12 18:23:04.968410 kubelet[1481]: I0412 18:23:04.968377 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f9e6be95-e7b7-45bd-9b2d-400074ffa4f0-xtables-lock\") pod \"kube-flannel-ds-p8vr6\" (UID: \"f9e6be95-e7b7-45bd-9b2d-400074ffa4f0\") " pod="kube-flannel/kube-flannel-ds-p8vr6" Apr 12 18:23:04.968410 kubelet[1481]: I0412 18:23:04.968408 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/052d6adc-ba8e-4926-adaf-20110103d8e7-kube-proxy\") pod \"kube-proxy-2mg29\" (UID: \"052d6adc-ba8e-4926-adaf-20110103d8e7\") " pod="kube-system/kube-proxy-2mg29" Apr 12 18:23:04.968450 kubelet[1481]: I0412 18:23:04.968428 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/052d6adc-ba8e-4926-adaf-20110103d8e7-xtables-lock\") pod \"kube-proxy-2mg29\" (UID: \"052d6adc-ba8e-4926-adaf-20110103d8e7\") " pod="kube-system/kube-proxy-2mg29" Apr 12 18:23:04.968450 kubelet[1481]: I0412 18:23:04.968440 1481 reconciler.go:41] "Reconciler: start to sync state" Apr 12 18:23:05.259483 kubelet[1481]: E0412 18:23:05.259437 1481 dns.go:158] "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" Apr 12 18:23:05.259732 kubelet[1481]: E0412 18:23:05.259712 1481 dns.go:158] "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" Apr 12 18:23:05.260183 env[1173]: time="2024-04-12T18:23:05.260119941Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-p8vr6,Uid:f9e6be95-e7b7-45bd-9b2d-400074ffa4f0,Namespace:kube-flannel,Attempt:0,}" Apr 12 18:23:05.260287 env[1173]: time="2024-04-12T18:23:05.260138643Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-2mg29,Uid:052d6adc-ba8e-4926-adaf-20110103d8e7,Namespace:kube-system,Attempt:0,}" Apr 12 18:23:05.746018 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1778680272.mount: Deactivated successfully. Apr 12 18:23:05.750529 env[1173]: time="2024-04-12T18:23:05.750484622Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.751630 env[1173]: time="2024-04-12T18:23:05.751599681Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.752598 env[1173]: time="2024-04-12T18:23:05.752570623Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.754157 env[1173]: time="2024-04-12T18:23:05.754115977Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.754821 env[1173]: time="2024-04-12T18:23:05.754795679Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.757147 env[1173]: time="2024-04-12T18:23:05.757121203Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.758477 env[1173]: time="2024-04-12T18:23:05.758450797Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.759220 env[1173]: time="2024-04-12T18:23:05.759185062Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:05.788384 env[1173]: time="2024-04-12T18:23:05.787985109Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 12 18:23:05.788384 env[1173]: time="2024-04-12T18:23:05.788352064Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 12 18:23:05.788384 env[1173]: time="2024-04-12T18:23:05.788368038Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 12 18:23:05.788384 env[1173]: time="2024-04-12T18:23:05.788295643Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 12 18:23:05.788563 env[1173]: time="2024-04-12T18:23:05.788380769Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 12 18:23:05.788563 env[1173]: time="2024-04-12T18:23:05.788397217Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 12 18:23:05.788659 env[1173]: time="2024-04-12T18:23:05.788615547Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/e960e73ea742d4642c84079d13b671aa2943b99ff6d60634994210d2f54cbc71 pid=1540 runtime=io.containerd.runc.v2 Apr 12 18:23:05.788787 env[1173]: time="2024-04-12T18:23:05.788733253Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a pid=1543 runtime=io.containerd.runc.v2 Apr 12 18:23:05.861927 env[1173]: time="2024-04-12T18:23:05.861174875Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-2mg29,Uid:052d6adc-ba8e-4926-adaf-20110103d8e7,Namespace:kube-system,Attempt:0,} returns sandbox id \"e960e73ea742d4642c84079d13b671aa2943b99ff6d60634994210d2f54cbc71\"" Apr 12 18:23:05.861927 env[1173]: time="2024-04-12T18:23:05.861882769Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-p8vr6,Uid:f9e6be95-e7b7-45bd-9b2d-400074ffa4f0,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a\"" Apr 12 18:23:05.862542 kubelet[1481]: E0412 18:23:05.862246 1481 dns.go:158] "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" Apr 12 18:23:05.862542 kubelet[1481]: E0412 18:23:05.862538 1481 dns.go:158] "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" Apr 12 18:23:05.863113 env[1173]: time="2024-04-12T18:23:05.863087975Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.27.12\"" Apr 12 18:23:05.953683 kubelet[1481]: E0412 18:23:05.953644 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:06.806087 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount413833842.mount: Deactivated successfully. Apr 12 18:23:06.954341 kubelet[1481]: E0412 18:23:06.954291 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:07.327941 env[1173]: time="2024-04-12T18:23:07.327900562Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.27.12,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:07.330038 env[1173]: time="2024-04-12T18:23:07.330005236Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7daec180765068529c26cc4c7c989513bebbe614cbbc58beebe1db17ae177e06,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:07.332864 env[1173]: time="2024-04-12T18:23:07.332836235Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.27.12,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:07.333537 env[1173]: time="2024-04-12T18:23:07.333492543Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:b0539f35b586abc54ca7660f9bb8a539d010b9e07d20e9e3d529cf0ca35d4ddf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:07.333999 env[1173]: time="2024-04-12T18:23:07.333955099Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.27.12\" returns image reference \"sha256:7daec180765068529c26cc4c7c989513bebbe614cbbc58beebe1db17ae177e06\"" Apr 12 18:23:07.334949 env[1173]: time="2024-04-12T18:23:07.334927984Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Apr 12 18:23:07.335799 env[1173]: time="2024-04-12T18:23:07.335771305Z" level=info msg="CreateContainer within sandbox \"e960e73ea742d4642c84079d13b671aa2943b99ff6d60634994210d2f54cbc71\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 12 18:23:07.345512 env[1173]: time="2024-04-12T18:23:07.345461981Z" level=info msg="CreateContainer within sandbox \"e960e73ea742d4642c84079d13b671aa2943b99ff6d60634994210d2f54cbc71\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"93d8ebe5dc150a6d3969ae8ca54f8c929686b949747a27828d32a51b36d81a84\"" Apr 12 18:23:07.345970 env[1173]: time="2024-04-12T18:23:07.345941704Z" level=info msg="StartContainer for \"93d8ebe5dc150a6d3969ae8ca54f8c929686b949747a27828d32a51b36d81a84\"" Apr 12 18:23:07.399700 env[1173]: time="2024-04-12T18:23:07.399653836Z" level=info msg="StartContainer for \"93d8ebe5dc150a6d3969ae8ca54f8c929686b949747a27828d32a51b36d81a84\" returns successfully" Apr 12 18:23:07.954426 kubelet[1481]: E0412 18:23:07.954396 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:08.054933 kubelet[1481]: E0412 18:23:08.054893 1481 dns.go:158] "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" Apr 12 18:23:08.062613 kubelet[1481]: I0412 18:23:08.062576 1481 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-2mg29" podStartSLOduration=2.590834186 podCreationTimestamp="2024-04-12 18:23:04 +0000 UTC" firstStartedPulling="2024-04-12 18:23:05.862731221 +0000 UTC m=+3.649711753" lastFinishedPulling="2024-04-12 18:23:07.334436962 +0000 UTC m=+5.121417495" observedRunningTime="2024-04-12 18:23:08.062190333 +0000 UTC m=+5.849170865" watchObservedRunningTime="2024-04-12 18:23:08.062539928 +0000 UTC m=+5.849520540" Apr 12 18:23:08.402484 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4167518813.mount: Deactivated successfully. Apr 12 18:23:08.457473 env[1173]: time="2024-04-12T18:23:08.457423725Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:08.459570 env[1173]: time="2024-04-12T18:23:08.459545629Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:08.462953 env[1173]: time="2024-04-12T18:23:08.462918783Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:08.465601 env[1173]: time="2024-04-12T18:23:08.465563016Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:08.466273 env[1173]: time="2024-04-12T18:23:08.466245974Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Apr 12 18:23:08.468299 env[1173]: time="2024-04-12T18:23:08.468267024Z" level=info msg="CreateContainer within sandbox \"b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 12 18:23:08.481571 env[1173]: time="2024-04-12T18:23:08.481530581Z" level=info msg="CreateContainer within sandbox \"b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"e3417c85be1890f5d10b3170a3dc73250cd6b7f4395f59ce69dd2fda8f84aaa0\"" Apr 12 18:23:08.481999 env[1173]: time="2024-04-12T18:23:08.481969600Z" level=info msg="StartContainer for \"e3417c85be1890f5d10b3170a3dc73250cd6b7f4395f59ce69dd2fda8f84aaa0\"" Apr 12 18:23:08.539782 env[1173]: time="2024-04-12T18:23:08.539727851Z" level=info msg="StartContainer for \"e3417c85be1890f5d10b3170a3dc73250cd6b7f4395f59ce69dd2fda8f84aaa0\" returns successfully" Apr 12 18:23:08.600026 env[1173]: time="2024-04-12T18:23:08.599978002Z" level=info msg="shim disconnected" id=e3417c85be1890f5d10b3170a3dc73250cd6b7f4395f59ce69dd2fda8f84aaa0 Apr 12 18:23:08.600026 env[1173]: time="2024-04-12T18:23:08.600024480Z" level=warning msg="cleaning up after shim disconnected" id=e3417c85be1890f5d10b3170a3dc73250cd6b7f4395f59ce69dd2fda8f84aaa0 namespace=k8s.io Apr 12 18:23:08.600026 env[1173]: time="2024-04-12T18:23:08.600033490Z" level=info msg="cleaning up dead shim" Apr 12 18:23:08.606286 env[1173]: time="2024-04-12T18:23:08.606244924Z" level=warning msg="cleanup warnings time=\"2024-04-12T18:23:08Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1809 runtime=io.containerd.runc.v2\n" Apr 12 18:23:08.954598 kubelet[1481]: E0412 18:23:08.954544 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:09.057975 kubelet[1481]: E0412 18:23:09.057941 1481 dns.go:158] "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" Apr 12 18:23:09.058529 kubelet[1481]: E0412 18:23:09.058492 1481 dns.go:158] "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" Apr 12 18:23:09.059282 env[1173]: time="2024-04-12T18:23:09.059254419Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Apr 12 18:23:09.342260 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e3417c85be1890f5d10b3170a3dc73250cd6b7f4395f59ce69dd2fda8f84aaa0-rootfs.mount: Deactivated successfully. Apr 12 18:23:09.954835 kubelet[1481]: E0412 18:23:09.954794 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:10.267251 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3033916926.mount: Deactivated successfully. Apr 12 18:23:10.955401 kubelet[1481]: E0412 18:23:10.955368 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:10.965962 env[1173]: time="2024-04-12T18:23:10.965918004Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:10.969030 env[1173]: time="2024-04-12T18:23:10.968995317Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:10.973301 env[1173]: time="2024-04-12T18:23:10.973263815Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:10.975096 env[1173]: time="2024-04-12T18:23:10.975061787Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:10.975902 env[1173]: time="2024-04-12T18:23:10.975802615Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Apr 12 18:23:10.980687 env[1173]: time="2024-04-12T18:23:10.980638495Z" level=info msg="CreateContainer within sandbox \"b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 12 18:23:10.989688 env[1173]: time="2024-04-12T18:23:10.989642629Z" level=info msg="CreateContainer within sandbox \"b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"51991eb7c2a32aae46a03946e91e54e7ee7021947afc1894dd142bb369d0cb77\"" Apr 12 18:23:10.990079 env[1173]: time="2024-04-12T18:23:10.990038750Z" level=info msg="StartContainer for \"51991eb7c2a32aae46a03946e91e54e7ee7021947afc1894dd142bb369d0cb77\"" Apr 12 18:23:11.087316 env[1173]: time="2024-04-12T18:23:11.087266120Z" level=info msg="StartContainer for \"51991eb7c2a32aae46a03946e91e54e7ee7021947afc1894dd142bb369d0cb77\" returns successfully" Apr 12 18:23:11.105535 kubelet[1481]: I0412 18:23:11.102536 1481 kubelet_node_status.go:493] "Fast updating node status as it just became ready" Apr 12 18:23:11.208956 env[1173]: time="2024-04-12T18:23:11.208458555Z" level=info msg="shim disconnected" id=51991eb7c2a32aae46a03946e91e54e7ee7021947afc1894dd142bb369d0cb77 Apr 12 18:23:11.209178 env[1173]: time="2024-04-12T18:23:11.209151865Z" level=warning msg="cleaning up after shim disconnected" id=51991eb7c2a32aae46a03946e91e54e7ee7021947afc1894dd142bb369d0cb77 namespace=k8s.io Apr 12 18:23:11.209248 env[1173]: time="2024-04-12T18:23:11.209229938Z" level=info msg="cleaning up dead shim" Apr 12 18:23:11.217262 env[1173]: time="2024-04-12T18:23:11.217222678Z" level=warning msg="cleanup warnings time=\"2024-04-12T18:23:11Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1870 runtime=io.containerd.runc.v2\n" Apr 12 18:23:11.955538 kubelet[1481]: E0412 18:23:11.955478 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:11.986300 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-51991eb7c2a32aae46a03946e91e54e7ee7021947afc1894dd142bb369d0cb77-rootfs.mount: Deactivated successfully. Apr 12 18:23:12.069162 kubelet[1481]: E0412 18:23:12.069126 1481 dns.go:158] "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" Apr 12 18:23:12.071138 env[1173]: time="2024-04-12T18:23:12.071103220Z" level=info msg="CreateContainer within sandbox \"b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 12 18:23:12.084065 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount253756710.mount: Deactivated successfully. Apr 12 18:23:12.089893 env[1173]: time="2024-04-12T18:23:12.089846713Z" level=info msg="CreateContainer within sandbox \"b110a515de46a7b96e3dd49067a0e1b85b0a2bc6b437ec8381c443bf5905bc3a\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"be8e4cb424a1cbd6cc72566d87c904559091f1b78fc2d2592928bdb4642f9d14\"" Apr 12 18:23:12.090572 env[1173]: time="2024-04-12T18:23:12.090549115Z" level=info msg="StartContainer for \"be8e4cb424a1cbd6cc72566d87c904559091f1b78fc2d2592928bdb4642f9d14\"" Apr 12 18:23:12.147102 env[1173]: time="2024-04-12T18:23:12.146549043Z" level=info msg="StartContainer for \"be8e4cb424a1cbd6cc72566d87c904559091f1b78fc2d2592928bdb4642f9d14\" returns successfully" Apr 12 18:23:12.955697 kubelet[1481]: E0412 18:23:12.955640 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:13.072295 kubelet[1481]: E0412 18:23:13.072219 1481 dns.go:158] "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" Apr 12 18:23:13.212688 systemd-networkd[1063]: flannel.1: Link UP Apr 12 18:23:13.212694 systemd-networkd[1063]: flannel.1: Gained carrier Apr 12 18:23:13.956669 kubelet[1481]: E0412 18:23:13.956621 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:14.073797 kubelet[1481]: E0412 18:23:14.073759 1481 dns.go:158] "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" Apr 12 18:23:14.811630 systemd-networkd[1063]: flannel.1: Gained IPv6LL Apr 12 18:23:14.957478 kubelet[1481]: E0412 18:23:14.957433 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:15.467280 kubelet[1481]: I0412 18:23:15.467243 1481 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-p8vr6" podStartSLOduration=6.354029052 podCreationTimestamp="2024-04-12 18:23:04 +0000 UTC" firstStartedPulling="2024-04-12 18:23:05.86293342 +0000 UTC m=+3.649913952" lastFinishedPulling="2024-04-12 18:23:10.97611241 +0000 UTC m=+8.763092943" observedRunningTime="2024-04-12 18:23:13.081286432 +0000 UTC m=+10.868266965" watchObservedRunningTime="2024-04-12 18:23:15.467208043 +0000 UTC m=+13.254188575" Apr 12 18:23:15.467641 kubelet[1481]: I0412 18:23:15.467619 1481 topology_manager.go:212] "Topology Admit Handler" Apr 12 18:23:15.538296 kubelet[1481]: I0412 18:23:15.538259 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-q47s5\" (UniqueName: \"kubernetes.io/projected/8daabc0d-c039-480c-bb35-ea555f4422a8-kube-api-access-q47s5\") pod \"nginx-deployment-845c78c8b9-b7thh\" (UID: \"8daabc0d-c039-480c-bb35-ea555f4422a8\") " pod="default/nginx-deployment-845c78c8b9-b7thh" Apr 12 18:23:15.770774 env[1173]: time="2024-04-12T18:23:15.770683260Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-845c78c8b9-b7thh,Uid:8daabc0d-c039-480c-bb35-ea555f4422a8,Namespace:default,Attempt:0,}" Apr 12 18:23:15.795185 systemd-networkd[1063]: cni0: Link UP Apr 12 18:23:15.795191 systemd-networkd[1063]: cni0: Gained carrier Apr 12 18:23:15.796741 systemd-networkd[1063]: cni0: Lost carrier Apr 12 18:23:15.802519 systemd-networkd[1063]: vethcc3b17cd: Link UP Apr 12 18:23:15.805846 kernel: cni0: port 1(vethcc3b17cd) entered blocking state Apr 12 18:23:15.805920 kernel: cni0: port 1(vethcc3b17cd) entered disabled state Apr 12 18:23:15.805938 kernel: device vethcc3b17cd entered promiscuous mode Apr 12 18:23:15.807137 kernel: cni0: port 1(vethcc3b17cd) entered blocking state Apr 12 18:23:15.807191 kernel: cni0: port 1(vethcc3b17cd) entered forwarding state Apr 12 18:23:15.809564 kernel: cni0: port 1(vethcc3b17cd) entered disabled state Apr 12 18:23:15.816924 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethcc3b17cd: link becomes ready Apr 12 18:23:15.816995 kernel: cni0: port 1(vethcc3b17cd) entered blocking state Apr 12 18:23:15.817021 kernel: cni0: port 1(vethcc3b17cd) entered forwarding state Apr 12 18:23:15.817003 systemd-networkd[1063]: vethcc3b17cd: Gained carrier Apr 12 18:23:15.817251 systemd-networkd[1063]: cni0: Gained carrier Apr 12 18:23:15.818381 env[1173]: 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"} Apr 12 18:23:15.818381 env[1173]: delegateAdd: netconf sent to delegate plugin: Apr 12 18:23:15.829876 env[1173]: {"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-04-12T18:23:15.829819659Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 12 18:23:15.830054 env[1173]: time="2024-04-12T18:23:15.829861771Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 12 18:23:15.830054 env[1173]: time="2024-04-12T18:23:15.829871661Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 12 18:23:15.830146 env[1173]: time="2024-04-12T18:23:15.830092110Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/5a0d8ffd7b1379745ccc85814fe6645c9bcb177ee429281626ad8ec25c4306e0 pid=2054 runtime=io.containerd.runc.v2 Apr 12 18:23:15.860174 systemd-resolved[1111]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 12 18:23:15.876576 env[1173]: time="2024-04-12T18:23:15.876541711Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-845c78c8b9-b7thh,Uid:8daabc0d-c039-480c-bb35-ea555f4422a8,Namespace:default,Attempt:0,} returns sandbox id \"5a0d8ffd7b1379745ccc85814fe6645c9bcb177ee429281626ad8ec25c4306e0\"" Apr 12 18:23:15.878079 env[1173]: time="2024-04-12T18:23:15.878050156Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 12 18:23:15.958578 kubelet[1481]: E0412 18:23:15.958528 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:16.925860 systemd-networkd[1063]: vethcc3b17cd: Gained IPv6LL Apr 12 18:23:16.959059 kubelet[1481]: E0412 18:23:16.959007 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:17.755633 systemd-networkd[1063]: cni0: Gained IPv6LL Apr 12 18:23:17.959566 kubelet[1481]: E0412 18:23:17.959517 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:17.966002 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3753606731.mount: Deactivated successfully. Apr 12 18:23:18.680709 env[1173]: time="2024-04-12T18:23:18.680663202Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:18.681984 env[1173]: time="2024-04-12T18:23:18.681929102Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7c9d4415537dd79835685ddfb3fdec1efecbf38aada406c280c76cc82afd6a56,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:18.683630 env[1173]: time="2024-04-12T18:23:18.683600176Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:18.685204 env[1173]: time="2024-04-12T18:23:18.685174726Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:c55d27e57a0d92ca01e7aba1a1812f9c8cc73708ce69fb58d4a42b6416a95f38,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:18.686665 env[1173]: time="2024-04-12T18:23:18.686631077Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c9d4415537dd79835685ddfb3fdec1efecbf38aada406c280c76cc82afd6a56\"" Apr 12 18:23:18.688141 env[1173]: time="2024-04-12T18:23:18.688110701Z" level=info msg="CreateContainer within sandbox \"5a0d8ffd7b1379745ccc85814fe6645c9bcb177ee429281626ad8ec25c4306e0\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 12 18:23:18.700586 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1222346420.mount: Deactivated successfully. Apr 12 18:23:18.703249 env[1173]: time="2024-04-12T18:23:18.703218505Z" level=info msg="CreateContainer within sandbox \"5a0d8ffd7b1379745ccc85814fe6645c9bcb177ee429281626ad8ec25c4306e0\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"b575c9901b07bcc2dc12e1809c673d0ec8fd09780afaaeb2f27c925c0b71c856\"" Apr 12 18:23:18.703765 env[1173]: time="2024-04-12T18:23:18.703738046Z" level=info msg="StartContainer for \"b575c9901b07bcc2dc12e1809c673d0ec8fd09780afaaeb2f27c925c0b71c856\"" Apr 12 18:23:18.756020 env[1173]: time="2024-04-12T18:23:18.755974086Z" level=info msg="StartContainer for \"b575c9901b07bcc2dc12e1809c673d0ec8fd09780afaaeb2f27c925c0b71c856\" returns successfully" Apr 12 18:23:18.960771 kubelet[1481]: E0412 18:23:18.960633 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:19.090952 kubelet[1481]: I0412 18:23:19.090914 1481 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-845c78c8b9-b7thh" podStartSLOduration=1.281684939 podCreationTimestamp="2024-04-12 18:23:15 +0000 UTC" firstStartedPulling="2024-04-12 18:23:15.877660463 +0000 UTC m=+13.664640996" lastFinishedPulling="2024-04-12 18:23:18.686856218 +0000 UTC m=+16.473836751" observedRunningTime="2024-04-12 18:23:19.090618961 +0000 UTC m=+16.877599494" watchObservedRunningTime="2024-04-12 18:23:19.090880694 +0000 UTC m=+16.877861227" Apr 12 18:23:19.961151 kubelet[1481]: E0412 18:23:19.961106 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:20.961829 kubelet[1481]: E0412 18:23:20.961786 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:21.751682 kubelet[1481]: I0412 18:23:21.751644 1481 topology_manager.go:212] "Topology Admit Handler" Apr 12 18:23:21.866429 kubelet[1481]: I0412 18:23:21.866393 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/9acce01e-754b-458d-bf85-a86a93d6cb2b-data\") pod \"nfs-server-provisioner-0\" (UID: \"9acce01e-754b-458d-bf85-a86a93d6cb2b\") " pod="default/nfs-server-provisioner-0" Apr 12 18:23:21.866672 kubelet[1481]: I0412 18:23:21.866657 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-222mc\" (UniqueName: \"kubernetes.io/projected/9acce01e-754b-458d-bf85-a86a93d6cb2b-kube-api-access-222mc\") pod \"nfs-server-provisioner-0\" (UID: \"9acce01e-754b-458d-bf85-a86a93d6cb2b\") " pod="default/nfs-server-provisioner-0" Apr 12 18:23:21.962759 kubelet[1481]: E0412 18:23:21.962727 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:22.056203 env[1173]: time="2024-04-12T18:23:22.056082208Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9acce01e-754b-458d-bf85-a86a93d6cb2b,Namespace:default,Attempt:0,}" Apr 12 18:23:22.074161 systemd-networkd[1063]: veth3a9949de: Link UP Apr 12 18:23:22.078071 kernel: cni0: port 2(veth3a9949de) entered blocking state Apr 12 18:23:22.078152 kernel: cni0: port 2(veth3a9949de) entered disabled state Apr 12 18:23:22.078185 kernel: device veth3a9949de entered promiscuous mode Apr 12 18:23:22.090976 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Apr 12 18:23:22.091059 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth3a9949de: link becomes ready Apr 12 18:23:22.091095 kernel: cni0: port 2(veth3a9949de) entered blocking state Apr 12 18:23:22.091111 kernel: cni0: port 2(veth3a9949de) entered forwarding state Apr 12 18:23:22.090925 systemd-networkd[1063]: veth3a9949de: Gained carrier Apr 12 18:23:22.092566 env[1173]: 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"} Apr 12 18:23:22.092566 env[1173]: delegateAdd: netconf sent to delegate plugin: Apr 12 18:23:22.102306 env[1173]: {"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-04-12T18:23:22.102238223Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 12 18:23:22.102426 env[1173]: time="2024-04-12T18:23:22.102323841Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 12 18:23:22.102426 env[1173]: time="2024-04-12T18:23:22.102353685Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 12 18:23:22.102579 env[1173]: time="2024-04-12T18:23:22.102545975Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/a439dd2ed6b6fba24424fa13f8ec7bcfd17856b331eb639b8faa6fb22b01f852 pid=2217 runtime=io.containerd.runc.v2 Apr 12 18:23:22.146925 systemd-resolved[1111]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 12 18:23:22.167653 env[1173]: time="2024-04-12T18:23:22.167610775Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9acce01e-754b-458d-bf85-a86a93d6cb2b,Namespace:default,Attempt:0,} returns sandbox id \"a439dd2ed6b6fba24424fa13f8ec7bcfd17856b331eb639b8faa6fb22b01f852\"" Apr 12 18:23:22.169616 env[1173]: time="2024-04-12T18:23:22.169575590Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 12 18:23:22.952841 kubelet[1481]: E0412 18:23:22.952792 1481 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:22.964207 kubelet[1481]: E0412 18:23:22.964178 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:22.979024 systemd[1]: run-containerd-runc-k8s.io-a439dd2ed6b6fba24424fa13f8ec7bcfd17856b331eb639b8faa6fb22b01f852-runc.gxNpE0.mount: Deactivated successfully. Apr 12 18:23:23.963619 systemd-networkd[1063]: veth3a9949de: Gained IPv6LL Apr 12 18:23:23.964342 kubelet[1481]: E0412 18:23:23.964309 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:24.369681 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3799872241.mount: Deactivated successfully. Apr 12 18:23:24.967152 kubelet[1481]: E0412 18:23:24.967102 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:25.967952 kubelet[1481]: E0412 18:23:25.967899 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:26.441811 env[1173]: time="2024-04-12T18:23:26.441672801Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:26.443579 env[1173]: time="2024-04-12T18:23:26.443538536Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:26.445123 env[1173]: time="2024-04-12T18:23:26.445092209Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:26.446847 env[1173]: time="2024-04-12T18:23:26.446817802Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:26.447578 env[1173]: time="2024-04-12T18:23:26.447548291Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Apr 12 18:23:26.449677 env[1173]: time="2024-04-12T18:23:26.449646543Z" level=info msg="CreateContainer within sandbox \"a439dd2ed6b6fba24424fa13f8ec7bcfd17856b331eb639b8faa6fb22b01f852\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 12 18:23:26.467625 env[1173]: time="2024-04-12T18:23:26.467575242Z" level=info msg="CreateContainer within sandbox \"a439dd2ed6b6fba24424fa13f8ec7bcfd17856b331eb639b8faa6fb22b01f852\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"b60512af517803dc59fce7babb667e8bc1b479b62306bf181a62db746ed82a13\"" Apr 12 18:23:26.468384 env[1173]: time="2024-04-12T18:23:26.468358374Z" level=info msg="StartContainer for \"b60512af517803dc59fce7babb667e8bc1b479b62306bf181a62db746ed82a13\"" Apr 12 18:23:26.526724 env[1173]: time="2024-04-12T18:23:26.526676781Z" level=info msg="StartContainer for \"b60512af517803dc59fce7babb667e8bc1b479b62306bf181a62db746ed82a13\" returns successfully" Apr 12 18:23:26.968756 kubelet[1481]: E0412 18:23:26.968711 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:27.109517 kubelet[1481]: I0412 18:23:27.109470 1481 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.8305926829999999 podCreationTimestamp="2024-04-12 18:23:21 +0000 UTC" firstStartedPulling="2024-04-12 18:23:22.1689976 +0000 UTC m=+19.955978093" lastFinishedPulling="2024-04-12 18:23:26.447839647 +0000 UTC m=+24.234820180" observedRunningTime="2024-04-12 18:23:27.108859482 +0000 UTC m=+24.895840015" watchObservedRunningTime="2024-04-12 18:23:27.10943477 +0000 UTC m=+24.896415303" Apr 12 18:23:27.969493 kubelet[1481]: E0412 18:23:27.969436 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:28.970547 kubelet[1481]: E0412 18:23:28.970487 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:29.971681 kubelet[1481]: E0412 18:23:29.971627 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:30.972359 kubelet[1481]: E0412 18:23:30.972312 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:31.972755 kubelet[1481]: E0412 18:23:31.972679 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:32.973244 kubelet[1481]: E0412 18:23:32.973206 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:33.973384 kubelet[1481]: E0412 18:23:33.973347 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:34.973970 kubelet[1481]: E0412 18:23:34.973918 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:35.974511 kubelet[1481]: E0412 18:23:35.974442 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:36.339810 kubelet[1481]: I0412 18:23:36.339735 1481 topology_manager.go:212] "Topology Admit Handler" Apr 12 18:23:36.439363 kubelet[1481]: I0412 18:23:36.439322 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-a4e47103-d1a1-4326-8fcb-141e23f28f84\" (UniqueName: \"kubernetes.io/nfs/f1e89514-9c32-47c6-89a1-5025c3a86956-pvc-a4e47103-d1a1-4326-8fcb-141e23f28f84\") pod \"test-pod-1\" (UID: \"f1e89514-9c32-47c6-89a1-5025c3a86956\") " pod="default/test-pod-1" Apr 12 18:23:36.439543 kubelet[1481]: I0412 18:23:36.439390 1481 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rj8sh\" (UniqueName: \"kubernetes.io/projected/f1e89514-9c32-47c6-89a1-5025c3a86956-kube-api-access-rj8sh\") pod \"test-pod-1\" (UID: \"f1e89514-9c32-47c6-89a1-5025c3a86956\") " pod="default/test-pod-1" Apr 12 18:23:36.560526 kernel: FS-Cache: Loaded Apr 12 18:23:36.586871 kernel: RPC: Registered named UNIX socket transport module. Apr 12 18:23:36.586960 kernel: RPC: Registered udp transport module. Apr 12 18:23:36.586984 kernel: RPC: Registered tcp transport module. Apr 12 18:23:36.588025 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 12 18:23:36.630522 kernel: FS-Cache: Netfs 'nfs' registered for caching Apr 12 18:23:36.758685 kernel: NFS: Registering the id_resolver key type Apr 12 18:23:36.758824 kernel: Key type id_resolver registered Apr 12 18:23:36.758848 kernel: Key type id_legacy registered Apr 12 18:23:36.782794 nfsidmap[2399]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Apr 12 18:23:36.785428 nfsidmap[2402]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Apr 12 18:23:36.943031 env[1173]: time="2024-04-12T18:23:36.942977002Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f1e89514-9c32-47c6-89a1-5025c3a86956,Namespace:default,Attempt:0,}" Apr 12 18:23:36.960744 kernel: cni0: port 3(vethaa5fa0b3) entered blocking state Apr 12 18:23:36.960823 kernel: cni0: port 3(vethaa5fa0b3) entered disabled state Apr 12 18:23:36.960861 kernel: device vethaa5fa0b3 entered promiscuous mode Apr 12 18:23:36.963183 systemd-networkd[1063]: vethaa5fa0b3: Link UP Apr 12 18:23:36.970868 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Apr 12 18:23:36.970945 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethaa5fa0b3: link becomes ready Apr 12 18:23:36.970965 kernel: cni0: port 3(vethaa5fa0b3) entered blocking state Apr 12 18:23:36.970979 kernel: cni0: port 3(vethaa5fa0b3) entered forwarding state Apr 12 18:23:36.970760 systemd-networkd[1063]: vethaa5fa0b3: Gained carrier Apr 12 18:23:36.972141 env[1173]: 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"} Apr 12 18:23:36.972141 env[1173]: delegateAdd: netconf sent to delegate plugin: Apr 12 18:23:36.975073 kubelet[1481]: E0412 18:23:36.975036 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:36.981172 env[1173]: {"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-04-12T18:23:36.981115395Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 12 18:23:36.981302 env[1173]: time="2024-04-12T18:23:36.981280093Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 12 18:23:36.981383 env[1173]: time="2024-04-12T18:23:36.981362542Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 12 18:23:36.981664 env[1173]: time="2024-04-12T18:23:36.981601369Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/d4f9655d3d5548e25febf5e9a2bc8162e451c651dc77e8498a19976f1d65e373 pid=2448 runtime=io.containerd.runc.v2 Apr 12 18:23:37.010473 systemd-resolved[1111]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 12 18:23:37.026933 env[1173]: time="2024-04-12T18:23:37.026884208Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f1e89514-9c32-47c6-89a1-5025c3a86956,Namespace:default,Attempt:0,} returns sandbox id \"d4f9655d3d5548e25febf5e9a2bc8162e451c651dc77e8498a19976f1d65e373\"" Apr 12 18:23:37.028246 env[1173]: time="2024-04-12T18:23:37.028215510Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 12 18:23:37.252718 env[1173]: time="2024-04-12T18:23:37.252659743Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:37.254107 env[1173]: time="2024-04-12T18:23:37.254085015Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7c9d4415537dd79835685ddfb3fdec1efecbf38aada406c280c76cc82afd6a56,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:37.256523 env[1173]: time="2024-04-12T18:23:37.256104709Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:37.257848 env[1173]: time="2024-04-12T18:23:37.257815891Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:c55d27e57a0d92ca01e7aba1a1812f9c8cc73708ce69fb58d4a42b6416a95f38,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Apr 12 18:23:37.258517 env[1173]: time="2024-04-12T18:23:37.258471160Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c9d4415537dd79835685ddfb3fdec1efecbf38aada406c280c76cc82afd6a56\"" Apr 12 18:23:37.261985 env[1173]: time="2024-04-12T18:23:37.261864481Z" level=info msg="CreateContainer within sandbox \"d4f9655d3d5548e25febf5e9a2bc8162e451c651dc77e8498a19976f1d65e373\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 12 18:23:37.271013 env[1173]: time="2024-04-12T18:23:37.270970728Z" level=info msg="CreateContainer within sandbox \"d4f9655d3d5548e25febf5e9a2bc8162e451c651dc77e8498a19976f1d65e373\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a9e0d4743613b918f660965e433c40a947904d842095f188fe86b0b763a9a58b\"" Apr 12 18:23:37.271554 env[1173]: time="2024-04-12T18:23:37.271525067Z" level=info msg="StartContainer for \"a9e0d4743613b918f660965e433c40a947904d842095f188fe86b0b763a9a58b\"" Apr 12 18:23:37.318710 env[1173]: time="2024-04-12T18:23:37.318663632Z" level=info msg="StartContainer for \"a9e0d4743613b918f660965e433c40a947904d842095f188fe86b0b763a9a58b\" returns successfully" Apr 12 18:23:37.976103 kubelet[1481]: E0412 18:23:37.976044 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:38.132073 kubelet[1481]: I0412 18:23:38.132029 1481 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.901171837 podCreationTimestamp="2024-04-12 18:23:22 +0000 UTC" firstStartedPulling="2024-04-12 18:23:37.027985725 +0000 UTC m=+34.814966218" lastFinishedPulling="2024-04-12 18:23:37.258804036 +0000 UTC m=+35.045784569" observedRunningTime="2024-04-12 18:23:38.131681996 +0000 UTC m=+35.918662649" watchObservedRunningTime="2024-04-12 18:23:38.131990188 +0000 UTC m=+35.918970721" Apr 12 18:23:38.235699 systemd-networkd[1063]: vethaa5fa0b3: Gained IPv6LL Apr 12 18:23:38.976380 kubelet[1481]: E0412 18:23:38.976321 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:39.977283 kubelet[1481]: E0412 18:23:39.977246 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:40.978572 kubelet[1481]: E0412 18:23:40.978528 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:41.030919 update_engine[1161]: I0412 18:23:41.030877 1161 update_attempter.cc:509] Updating boot flags... Apr 12 18:23:41.979208 kubelet[1481]: E0412 18:23:41.979163 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:42.952973 kubelet[1481]: E0412 18:23:42.952924 1481 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:42.979415 kubelet[1481]: E0412 18:23:42.979382 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 12 18:23:43.980529 kubelet[1481]: E0412 18:23:43.980472 1481 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"