Jul 2 00:47:58.747647 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 2 00:47:58.747669 kernel: Linux version 5.15.161-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 Mon Jul 1 23:37:37 -00 2024 Jul 2 00:47:58.747676 kernel: efi: EFI v2.70 by EDK II Jul 2 00:47:58.747682 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Jul 2 00:47:58.747734 kernel: random: crng init done Jul 2 00:47:58.747740 kernel: ACPI: Early table checksum verification disabled Jul 2 00:47:58.747747 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Jul 2 00:47:58.747755 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 2 00:47:58.747760 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747766 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747772 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747777 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747782 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747788 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747796 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747802 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747808 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:47:58.747813 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 2 00:47:58.747819 kernel: NUMA: Failed to initialise from firmware Jul 2 00:47:58.747825 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:47:58.747831 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Jul 2 00:47:58.747836 kernel: Zone ranges: Jul 2 00:47:58.747842 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:47:58.747849 kernel: DMA32 empty Jul 2 00:47:58.747855 kernel: Normal empty Jul 2 00:47:58.747860 kernel: Movable zone start for each node Jul 2 00:47:58.747866 kernel: Early memory node ranges Jul 2 00:47:58.747872 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Jul 2 00:47:58.747878 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Jul 2 00:47:58.747883 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Jul 2 00:47:58.747889 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Jul 2 00:47:58.747894 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Jul 2 00:47:58.747900 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Jul 2 00:47:58.747906 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Jul 2 00:47:58.747911 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:47:58.747919 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 2 00:47:58.747924 kernel: psci: probing for conduit method from ACPI. Jul 2 00:47:58.747930 kernel: psci: PSCIv1.1 detected in firmware. Jul 2 00:47:58.747936 kernel: psci: Using standard PSCI v0.2 function IDs Jul 2 00:47:58.747942 kernel: psci: Trusted OS migration not required Jul 2 00:47:58.747950 kernel: psci: SMC Calling Convention v1.1 Jul 2 00:47:58.747956 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 2 00:47:58.747964 kernel: ACPI: SRAT not present Jul 2 00:47:58.747970 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Jul 2 00:47:58.747976 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Jul 2 00:47:58.747983 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 2 00:47:58.747989 kernel: Detected PIPT I-cache on CPU0 Jul 2 00:47:58.747995 kernel: CPU features: detected: GIC system register CPU interface Jul 2 00:47:58.748001 kernel: CPU features: detected: Hardware dirty bit management Jul 2 00:47:58.748007 kernel: CPU features: detected: Spectre-v4 Jul 2 00:47:58.748013 kernel: CPU features: detected: Spectre-BHB Jul 2 00:47:58.748023 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 2 00:47:58.748030 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 2 00:47:58.748036 kernel: CPU features: detected: ARM erratum 1418040 Jul 2 00:47:58.748042 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Jul 2 00:47:58.748048 kernel: Policy zone: DMA Jul 2 00:47:58.748055 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=7b86ecfcd4701bdf4668db795601b20c118ac0b117c34a9b3836e0a5236b73b0 Jul 2 00:47:58.748062 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 2 00:47:58.748070 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 2 00:47:58.748076 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 2 00:47:58.748082 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 2 00:47:58.748089 kernel: Memory: 2457468K/2572288K available (9792K kernel code, 2092K rwdata, 7572K rodata, 36352K init, 777K bss, 114820K reserved, 0K cma-reserved) Jul 2 00:47:58.748097 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 2 00:47:58.748103 kernel: trace event string verifier disabled Jul 2 00:47:58.748110 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 2 00:47:58.748116 kernel: rcu: RCU event tracing is enabled. Jul 2 00:47:58.748123 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 2 00:47:58.748129 kernel: Trampoline variant of Tasks RCU enabled. Jul 2 00:47:58.748137 kernel: Tracing variant of Tasks RCU enabled. Jul 2 00:47:58.748144 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 2 00:47:58.748150 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 2 00:47:58.748156 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 2 00:47:58.748162 kernel: GICv3: 256 SPIs implemented Jul 2 00:47:58.748169 kernel: GICv3: 0 Extended SPIs implemented Jul 2 00:47:58.748176 kernel: GICv3: Distributor has no Range Selector support Jul 2 00:47:58.748182 kernel: Root IRQ handler: gic_handle_irq Jul 2 00:47:58.748188 kernel: GICv3: 16 PPIs implemented Jul 2 00:47:58.748194 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 2 00:47:58.748200 kernel: ACPI: SRAT not present Jul 2 00:47:58.748206 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 2 00:47:58.748212 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Jul 2 00:47:58.748220 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Jul 2 00:47:58.748227 kernel: GICv3: using LPI property table @0x00000000400d0000 Jul 2 00:47:58.748233 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Jul 2 00:47:58.748239 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:47:58.748246 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 2 00:47:58.748253 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 2 00:47:58.748259 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 2 00:47:58.748265 kernel: arm-pv: using stolen time PV Jul 2 00:47:58.748272 kernel: Console: colour dummy device 80x25 Jul 2 00:47:58.748278 kernel: ACPI: Core revision 20210730 Jul 2 00:47:58.748284 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 2 00:47:58.748291 kernel: pid_max: default: 32768 minimum: 301 Jul 2 00:47:58.748297 kernel: LSM: Security Framework initializing Jul 2 00:47:58.748303 kernel: SELinux: Initializing. Jul 2 00:47:58.748311 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 2 00:47:58.748319 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 2 00:47:58.748326 kernel: rcu: Hierarchical SRCU implementation. Jul 2 00:47:58.748332 kernel: Platform MSI: ITS@0x8080000 domain created Jul 2 00:47:58.748339 kernel: PCI/MSI: ITS@0x8080000 domain created Jul 2 00:47:58.748345 kernel: Remapping and enabling EFI services. Jul 2 00:47:58.748351 kernel: smp: Bringing up secondary CPUs ... Jul 2 00:47:58.748357 kernel: Detected PIPT I-cache on CPU1 Jul 2 00:47:58.748364 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 2 00:47:58.748371 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Jul 2 00:47:58.748378 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:47:58.748384 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 2 00:47:58.748391 kernel: Detected PIPT I-cache on CPU2 Jul 2 00:47:58.748397 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 2 00:47:58.748404 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Jul 2 00:47:58.748412 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:47:58.748418 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 2 00:47:58.748424 kernel: Detected PIPT I-cache on CPU3 Jul 2 00:47:58.748431 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 2 00:47:58.748438 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Jul 2 00:47:58.748445 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:47:58.748451 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 2 00:47:58.748457 kernel: smp: Brought up 1 node, 4 CPUs Jul 2 00:47:58.748468 kernel: SMP: Total of 4 processors activated. Jul 2 00:47:58.748476 kernel: CPU features: detected: 32-bit EL0 Support Jul 2 00:47:58.748483 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 2 00:47:58.748489 kernel: CPU features: detected: Common not Private translations Jul 2 00:47:58.748496 kernel: CPU features: detected: CRC32 instructions Jul 2 00:47:58.748503 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 2 00:47:58.748509 kernel: CPU features: detected: LSE atomic instructions Jul 2 00:47:58.748516 kernel: CPU features: detected: Privileged Access Never Jul 2 00:47:58.748524 kernel: CPU features: detected: RAS Extension Support Jul 2 00:47:58.748530 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 2 00:47:58.748537 kernel: CPU: All CPU(s) started at EL1 Jul 2 00:47:58.748543 kernel: alternatives: patching kernel code Jul 2 00:47:58.748550 kernel: devtmpfs: initialized Jul 2 00:47:58.748558 kernel: KASLR enabled Jul 2 00:47:58.748564 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 2 00:47:58.748577 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 2 00:47:58.748585 kernel: pinctrl core: initialized pinctrl subsystem Jul 2 00:47:58.748591 kernel: SMBIOS 3.0.0 present. Jul 2 00:47:58.748598 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Jul 2 00:47:58.748604 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 2 00:47:58.748611 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 2 00:47:58.748618 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 2 00:47:58.748627 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 2 00:47:58.748633 kernel: audit: initializing netlink subsys (disabled) Jul 2 00:47:58.748640 kernel: audit: type=2000 audit(0.034:1): state=initialized audit_enabled=0 res=1 Jul 2 00:47:58.748647 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 2 00:47:58.748653 kernel: cpuidle: using governor menu Jul 2 00:47:58.748660 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 2 00:47:58.748666 kernel: ASID allocator initialised with 32768 entries Jul 2 00:47:58.748673 kernel: ACPI: bus type PCI registered Jul 2 00:47:58.748679 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 2 00:47:58.748695 kernel: Serial: AMBA PL011 UART driver Jul 2 00:47:58.748711 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Jul 2 00:47:58.748718 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Jul 2 00:47:58.748725 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Jul 2 00:47:58.748731 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Jul 2 00:47:58.748738 kernel: cryptd: max_cpu_qlen set to 1000 Jul 2 00:47:58.748744 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 2 00:47:58.748751 kernel: ACPI: Added _OSI(Module Device) Jul 2 00:47:58.748758 kernel: ACPI: Added _OSI(Processor Device) Jul 2 00:47:58.748766 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jul 2 00:47:58.748773 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 2 00:47:58.748779 kernel: ACPI: Added _OSI(Linux-Dell-Video) Jul 2 00:47:58.748786 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Jul 2 00:47:58.748793 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Jul 2 00:47:58.748799 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 2 00:47:58.748806 kernel: ACPI: Interpreter enabled Jul 2 00:47:58.748812 kernel: ACPI: Using GIC for interrupt routing Jul 2 00:47:58.748819 kernel: ACPI: MCFG table detected, 1 entries Jul 2 00:47:58.748827 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 2 00:47:58.748833 kernel: printk: console [ttyAMA0] enabled Jul 2 00:47:58.748839 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 2 00:47:58.748970 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 2 00:47:58.749040 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 2 00:47:58.749099 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 2 00:47:58.749161 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 2 00:47:58.749223 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 2 00:47:58.749232 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 2 00:47:58.749239 kernel: PCI host bridge to bus 0000:00 Jul 2 00:47:58.749308 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 2 00:47:58.749364 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 2 00:47:58.749420 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 2 00:47:58.749474 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 2 00:47:58.749551 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Jul 2 00:47:58.749632 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Jul 2 00:47:58.749749 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Jul 2 00:47:58.749826 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Jul 2 00:47:58.749889 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Jul 2 00:47:58.749950 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Jul 2 00:47:58.750012 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Jul 2 00:47:58.750082 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Jul 2 00:47:58.750139 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 2 00:47:58.750194 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 2 00:47:58.750248 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 2 00:47:58.750258 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 2 00:47:58.750265 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 2 00:47:58.750272 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 2 00:47:58.750281 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 2 00:47:58.750288 kernel: iommu: Default domain type: Translated Jul 2 00:47:58.750294 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 2 00:47:58.750301 kernel: vgaarb: loaded Jul 2 00:47:58.750308 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 2 00:47:58.750315 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 2 00:47:58.750321 kernel: PTP clock support registered Jul 2 00:47:58.750328 kernel: Registered efivars operations Jul 2 00:47:58.750335 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 2 00:47:58.750341 kernel: VFS: Disk quotas dquot_6.6.0 Jul 2 00:47:58.750350 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 2 00:47:58.750357 kernel: pnp: PnP ACPI init Jul 2 00:47:58.750430 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 2 00:47:58.750440 kernel: pnp: PnP ACPI: found 1 devices Jul 2 00:47:58.750446 kernel: NET: Registered PF_INET protocol family Jul 2 00:47:58.750453 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 2 00:47:58.750460 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 2 00:47:58.750467 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 2 00:47:58.750475 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 2 00:47:58.750482 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Jul 2 00:47:58.750489 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 2 00:47:58.750496 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 2 00:47:58.750503 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 2 00:47:58.750510 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 2 00:47:58.750516 kernel: PCI: CLS 0 bytes, default 64 Jul 2 00:47:58.750523 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Jul 2 00:47:58.750530 kernel: kvm [1]: HYP mode not available Jul 2 00:47:58.750538 kernel: Initialise system trusted keyrings Jul 2 00:47:58.750545 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 2 00:47:58.750552 kernel: Key type asymmetric registered Jul 2 00:47:58.750558 kernel: Asymmetric key parser 'x509' registered Jul 2 00:47:58.750565 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 2 00:47:58.750579 kernel: io scheduler mq-deadline registered Jul 2 00:47:58.750586 kernel: io scheduler kyber registered Jul 2 00:47:58.750592 kernel: io scheduler bfq registered Jul 2 00:47:58.750599 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 2 00:47:58.750608 kernel: ACPI: button: Power Button [PWRB] Jul 2 00:47:58.750615 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 2 00:47:58.750693 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 2 00:47:58.750703 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 2 00:47:58.750710 kernel: thunder_xcv, ver 1.0 Jul 2 00:47:58.750726 kernel: thunder_bgx, ver 1.0 Jul 2 00:47:58.750733 kernel: nicpf, ver 1.0 Jul 2 00:47:58.750740 kernel: nicvf, ver 1.0 Jul 2 00:47:58.750816 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 2 00:47:58.750881 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-07-02T00:47:58 UTC (1719881278) Jul 2 00:47:58.750891 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 2 00:47:58.750898 kernel: NET: Registered PF_INET6 protocol family Jul 2 00:47:58.750904 kernel: Segment Routing with IPv6 Jul 2 00:47:58.750911 kernel: In-situ OAM (IOAM) with IPv6 Jul 2 00:47:58.750918 kernel: NET: Registered PF_PACKET protocol family Jul 2 00:47:58.750924 kernel: Key type dns_resolver registered Jul 2 00:47:58.750931 kernel: registered taskstats version 1 Jul 2 00:47:58.750939 kernel: Loading compiled-in X.509 certificates Jul 2 00:47:58.750946 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.161-flatcar: c418313b450e4055b23e41c11cb6dc415de0265d' Jul 2 00:47:58.750952 kernel: Key type .fscrypt registered Jul 2 00:47:58.750959 kernel: Key type fscrypt-provisioning registered Jul 2 00:47:58.750966 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 2 00:47:58.750972 kernel: ima: Allocated hash algorithm: sha1 Jul 2 00:47:58.750979 kernel: ima: No architecture policies found Jul 2 00:47:58.750985 kernel: clk: Disabling unused clocks Jul 2 00:47:58.750992 kernel: Freeing unused kernel memory: 36352K Jul 2 00:47:58.751000 kernel: Run /init as init process Jul 2 00:47:58.751006 kernel: with arguments: Jul 2 00:47:58.751013 kernel: /init Jul 2 00:47:58.751019 kernel: with environment: Jul 2 00:47:58.751025 kernel: HOME=/ Jul 2 00:47:58.751032 kernel: TERM=linux Jul 2 00:47:58.751038 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 2 00:47:58.751047 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) Jul 2 00:47:58.751057 systemd[1]: Detected virtualization kvm. Jul 2 00:47:58.751064 systemd[1]: Detected architecture arm64. Jul 2 00:47:58.751071 systemd[1]: Running in initrd. Jul 2 00:47:58.751078 systemd[1]: No hostname configured, using default hostname. Jul 2 00:47:58.751085 systemd[1]: Hostname set to . Jul 2 00:47:58.751092 systemd[1]: Initializing machine ID from VM UUID. Jul 2 00:47:58.751099 systemd[1]: Queued start job for default target initrd.target. Jul 2 00:47:58.751107 systemd[1]: Started systemd-ask-password-console.path. Jul 2 00:47:58.751115 systemd[1]: Reached target cryptsetup.target. Jul 2 00:47:58.751122 systemd[1]: Reached target paths.target. Jul 2 00:47:58.751129 systemd[1]: Reached target slices.target. Jul 2 00:47:58.751136 systemd[1]: Reached target swap.target. Jul 2 00:47:58.751143 systemd[1]: Reached target timers.target. Jul 2 00:47:58.751150 systemd[1]: Listening on iscsid.socket. Jul 2 00:47:58.751157 systemd[1]: Listening on iscsiuio.socket. Jul 2 00:47:58.751165 systemd[1]: Listening on systemd-journald-audit.socket. Jul 2 00:47:58.751173 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 2 00:47:58.751180 systemd[1]: Listening on systemd-journald.socket. Jul 2 00:47:58.751187 systemd[1]: Listening on systemd-networkd.socket. Jul 2 00:47:58.751194 systemd[1]: Listening on systemd-udevd-control.socket. Jul 2 00:47:58.751202 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 2 00:47:58.751209 systemd[1]: Reached target sockets.target. Jul 2 00:47:58.751216 systemd[1]: Starting kmod-static-nodes.service... Jul 2 00:47:58.751223 systemd[1]: Finished network-cleanup.service. Jul 2 00:47:58.751231 systemd[1]: Starting systemd-fsck-usr.service... Jul 2 00:47:58.751239 systemd[1]: Starting systemd-journald.service... Jul 2 00:47:58.751246 systemd[1]: Starting systemd-modules-load.service... Jul 2 00:47:58.751253 systemd[1]: Starting systemd-resolved.service... Jul 2 00:47:58.751260 systemd[1]: Starting systemd-vconsole-setup.service... Jul 2 00:47:58.751267 systemd[1]: Finished kmod-static-nodes.service. Jul 2 00:47:58.751274 systemd[1]: Finished systemd-fsck-usr.service. Jul 2 00:47:58.751281 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 2 00:47:58.751288 systemd[1]: Finished systemd-vconsole-setup.service. Jul 2 00:47:58.751297 kernel: audit: type=1130 audit(1719881278.744:2): 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' Jul 2 00:47:58.751304 systemd[1]: Starting dracut-cmdline-ask.service... Jul 2 00:47:58.751311 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 2 00:47:58.751319 kernel: audit: type=1130 audit(1719881278.749:3): 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' Jul 2 00:47:58.751329 systemd-journald[291]: Journal started Jul 2 00:47:58.751369 systemd-journald[291]: Runtime Journal (/run/log/journal/1aaee115f37844b4bf18a38f5a44795d) is 6.0M, max 48.7M, 42.6M free. Jul 2 00:47:58.744000 audit[1]: SERVICE_START 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' Jul 2 00:47:58.749000 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' Jul 2 00:47:58.747222 systemd-modules-load[292]: Inserted module 'overlay' Jul 2 00:47:58.752893 systemd[1]: Started systemd-journald.service. Jul 2 00:47:58.758721 kernel: audit: type=1130 audit(1719881278.752:4): 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' Jul 2 00:47:58.752000 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' Jul 2 00:47:58.766862 systemd-resolved[293]: Positive Trust Anchors: Jul 2 00:47:58.766873 systemd-resolved[293]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 2 00:47:58.766907 systemd-resolved[293]: 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 Jul 2 00:47:58.773925 systemd-resolved[293]: Defaulting to hostname 'linux'. Jul 2 00:47:58.776985 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 2 00:47:58.774969 systemd[1]: Started systemd-resolved.service. Jul 2 00:47:58.779695 kernel: audit: type=1130 audit(1719881278.776: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' Jul 2 00:47:58.776000 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' Jul 2 00:47:58.777584 systemd[1]: Reached target nss-lookup.target. Jul 2 00:47:58.782739 systemd[1]: Finished dracut-cmdline-ask.service. Jul 2 00:47:58.784304 systemd[1]: Starting dracut-cmdline.service... Jul 2 00:47:58.782000 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' Jul 2 00:47:58.787717 kernel: audit: type=1130 audit(1719881278.782:6): 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' Jul 2 00:47:58.787752 kernel: Bridge firewalling registered Jul 2 00:47:58.788034 systemd-modules-load[292]: Inserted module 'br_netfilter' Jul 2 00:47:58.795706 dracut-cmdline[309]: dracut-dracut-053 Jul 2 00:47:58.798145 dracut-cmdline[309]: 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=7b86ecfcd4701bdf4668db795601b20c118ac0b117c34a9b3836e0a5236b73b0 Jul 2 00:47:58.802776 kernel: SCSI subsystem initialized Jul 2 00:47:58.810729 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 2 00:47:58.810786 kernel: device-mapper: uevent: version 1.0.3 Jul 2 00:47:58.811946 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Jul 2 00:47:58.814353 systemd-modules-load[292]: Inserted module 'dm_multipath' Jul 2 00:47:58.815218 systemd[1]: Finished systemd-modules-load.service. Jul 2 00:47:58.815000 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' Jul 2 00:47:58.816675 systemd[1]: Starting systemd-sysctl.service... Jul 2 00:47:58.819310 kernel: audit: type=1130 audit(1719881278.815:7): 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' Jul 2 00:47:58.825154 systemd[1]: Finished systemd-sysctl.service. Jul 2 00:47:58.825000 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' Jul 2 00:47:58.828919 kernel: audit: type=1130 audit(1719881278.825:8): 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' Jul 2 00:47:58.868710 kernel: Loading iSCSI transport class v2.0-870. Jul 2 00:47:58.882719 kernel: iscsi: registered transport (tcp) Jul 2 00:47:58.900711 kernel: iscsi: registered transport (qla4xxx) Jul 2 00:47:58.900731 kernel: QLogic iSCSI HBA Driver Jul 2 00:47:58.944834 systemd[1]: Finished dracut-cmdline.service. Jul 2 00:47:58.944000 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' Jul 2 00:47:58.947779 kernel: audit: type=1130 audit(1719881278.944:9): 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' Jul 2 00:47:58.946384 systemd[1]: Starting dracut-pre-udev.service... Jul 2 00:47:58.993724 kernel: raid6: neonx8 gen() 13478 MB/s Jul 2 00:47:59.009719 kernel: raid6: neonx8 xor() 10636 MB/s Jul 2 00:47:59.026710 kernel: raid6: neonx4 gen() 13289 MB/s Jul 2 00:47:59.043706 kernel: raid6: neonx4 xor() 11060 MB/s Jul 2 00:47:59.060709 kernel: raid6: neonx2 gen() 12799 MB/s Jul 2 00:47:59.077718 kernel: raid6: neonx2 xor() 10086 MB/s Jul 2 00:47:59.094718 kernel: raid6: neonx1 gen() 10343 MB/s Jul 2 00:47:59.111711 kernel: raid6: neonx1 xor() 8602 MB/s Jul 2 00:47:59.128719 kernel: raid6: int64x8 gen() 6149 MB/s Jul 2 00:47:59.145709 kernel: raid6: int64x8 xor() 3471 MB/s Jul 2 00:47:59.162715 kernel: raid6: int64x4 gen() 7146 MB/s Jul 2 00:47:59.179720 kernel: raid6: int64x4 xor() 3777 MB/s Jul 2 00:47:59.196709 kernel: raid6: int64x2 gen() 6086 MB/s Jul 2 00:47:59.213706 kernel: raid6: int64x2 xor() 3258 MB/s Jul 2 00:47:59.230726 kernel: raid6: int64x1 gen() 4977 MB/s Jul 2 00:47:59.247915 kernel: raid6: int64x1 xor() 2627 MB/s Jul 2 00:47:59.247964 kernel: raid6: using algorithm neonx8 gen() 13478 MB/s Jul 2 00:47:59.247974 kernel: raid6: .... xor() 10636 MB/s, rmw enabled Jul 2 00:47:59.247983 kernel: raid6: using neon recovery algorithm Jul 2 00:47:59.258713 kernel: xor: measuring software checksum speed Jul 2 00:47:59.259991 kernel: 8regs : 17213 MB/sec Jul 2 00:47:59.260003 kernel: 32regs : 20744 MB/sec Jul 2 00:47:59.260709 kernel: arm64_neon : 27854 MB/sec Jul 2 00:47:59.260720 kernel: xor: using function: arm64_neon (27854 MB/sec) Jul 2 00:47:59.315726 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Jul 2 00:47:59.325271 systemd[1]: Finished dracut-pre-udev.service. Jul 2 00:47:59.325000 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' Jul 2 00:47:59.326806 systemd[1]: Starting systemd-udevd.service... Jul 2 00:47:59.329224 kernel: audit: type=1130 audit(1719881279.325:10): 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' Jul 2 00:47:59.325000 audit: BPF prog-id=7 op=LOAD Jul 2 00:47:59.325000 audit: BPF prog-id=8 op=LOAD Jul 2 00:47:59.343729 systemd-udevd[493]: Using default interface naming scheme 'v252'. Jul 2 00:47:59.347921 systemd[1]: Started systemd-udevd.service. Jul 2 00:47:59.347000 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' Jul 2 00:47:59.349287 systemd[1]: Starting dracut-pre-trigger.service... Jul 2 00:47:59.360222 dracut-pre-trigger[496]: rd.md=0: removing MD RAID activation Jul 2 00:47:59.387780 systemd[1]: Finished dracut-pre-trigger.service. Jul 2 00:47:59.387000 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' Jul 2 00:47:59.389225 systemd[1]: Starting systemd-udev-trigger.service... Jul 2 00:47:59.424035 systemd[1]: Finished systemd-udev-trigger.service. Jul 2 00:47:59.424000 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' Jul 2 00:47:59.454711 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 2 00:47:59.456880 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 2 00:47:59.456911 kernel: GPT:9289727 != 19775487 Jul 2 00:47:59.456921 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 2 00:47:59.458078 kernel: GPT:9289727 != 19775487 Jul 2 00:47:59.458093 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 2 00:47:59.458102 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:47:59.478711 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (546) Jul 2 00:47:59.481514 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Jul 2 00:47:59.482340 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Jul 2 00:47:59.490162 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 2 00:47:59.493294 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Jul 2 00:47:59.496443 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Jul 2 00:47:59.497951 systemd[1]: Starting disk-uuid.service... Jul 2 00:47:59.504955 disk-uuid[566]: Primary Header is updated. Jul 2 00:47:59.504955 disk-uuid[566]: Secondary Entries is updated. Jul 2 00:47:59.504955 disk-uuid[566]: Secondary Header is updated. Jul 2 00:47:59.509721 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:48:00.518788 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:48:00.519418 disk-uuid[567]: The operation has completed successfully. Jul 2 00:48:00.547215 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 2 00:48:00.547000 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' Jul 2 00:48:00.547000 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' Jul 2 00:48:00.547305 systemd[1]: Finished disk-uuid.service. Jul 2 00:48:00.548762 systemd[1]: Starting verity-setup.service... Jul 2 00:48:00.565711 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jul 2 00:48:00.592754 systemd[1]: Found device dev-mapper-usr.device. Jul 2 00:48:00.595050 systemd[1]: Mounting sysusr-usr.mount... Jul 2 00:48:00.597987 systemd[1]: Finished verity-setup.service. Jul 2 00:48:00.599000 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' Jul 2 00:48:00.652734 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Jul 2 00:48:00.653119 systemd[1]: Mounted sysusr-usr.mount. Jul 2 00:48:00.653843 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Jul 2 00:48:00.654526 systemd[1]: Starting ignition-setup.service... Jul 2 00:48:00.656840 systemd[1]: Starting parse-ip-for-networkd.service... Jul 2 00:48:00.663851 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:48:00.663886 kernel: BTRFS info (device vda6): using free space tree Jul 2 00:48:00.663896 kernel: BTRFS info (device vda6): has skinny extents Jul 2 00:48:00.673341 systemd[1]: mnt-oem.mount: Deactivated successfully. Jul 2 00:48:00.681677 systemd[1]: Finished ignition-setup.service. Jul 2 00:48:00.681000 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' Jul 2 00:48:00.683140 systemd[1]: Starting ignition-fetch-offline.service... Jul 2 00:48:00.744476 systemd[1]: Finished parse-ip-for-networkd.service. Jul 2 00:48:00.745000 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' Jul 2 00:48:00.745000 audit: BPF prog-id=9 op=LOAD Jul 2 00:48:00.746530 systemd[1]: Starting systemd-networkd.service... Jul 2 00:48:00.769533 systemd-networkd[742]: lo: Link UP Jul 2 00:48:00.769547 systemd-networkd[742]: lo: Gained carrier Jul 2 00:48:00.771000 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' Jul 2 00:48:00.769952 systemd-networkd[742]: Enumeration completed Jul 2 00:48:00.770055 systemd[1]: Started systemd-networkd.service. Jul 2 00:48:00.770127 systemd-networkd[742]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 00:48:00.771241 systemd[1]: Reached target network.target. Jul 2 00:48:00.771263 systemd-networkd[742]: eth0: Link UP Jul 2 00:48:00.771267 systemd-networkd[742]: eth0: Gained carrier Jul 2 00:48:00.772940 systemd[1]: Starting iscsiuio.service... Jul 2 00:48:00.782459 systemd[1]: Started iscsiuio.service. Jul 2 00:48:00.783000 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' Jul 2 00:48:00.784319 systemd[1]: Starting iscsid.service... Jul 2 00:48:00.787963 iscsid[747]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Jul 2 00:48:00.787963 iscsid[747]: 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]. Jul 2 00:48:00.787963 iscsid[747]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Jul 2 00:48:00.787963 iscsid[747]: If using hardware iscsi like qla4xxx this message can be ignored. Jul 2 00:48:00.787963 iscsid[747]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Jul 2 00:48:00.787963 iscsid[747]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Jul 2 00:48:00.793000 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' Jul 2 00:48:00.788022 ignition[654]: Ignition 2.14.0 Jul 2 00:48:00.791089 systemd-networkd[742]: eth0: DHCPv4 address 10.0.0.69/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 2 00:48:00.788029 ignition[654]: Stage: fetch-offline Jul 2 00:48:00.793768 systemd[1]: Started iscsid.service. Jul 2 00:48:00.788072 ignition[654]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:48:00.795220 systemd[1]: Starting dracut-initqueue.service... Jul 2 00:48:00.788082 ignition[654]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:48:00.788216 ignition[654]: parsed url from cmdline: "" Jul 2 00:48:00.788220 ignition[654]: no config URL provided Jul 2 00:48:00.788224 ignition[654]: reading system config file "/usr/lib/ignition/user.ign" Jul 2 00:48:00.788231 ignition[654]: no config at "/usr/lib/ignition/user.ign" Jul 2 00:48:00.805000 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' Jul 2 00:48:00.805139 systemd[1]: Finished dracut-initqueue.service. Jul 2 00:48:00.788249 ignition[654]: op(1): [started] loading QEMU firmware config module Jul 2 00:48:00.805981 systemd[1]: Reached target remote-fs-pre.target. Jul 2 00:48:00.788254 ignition[654]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 2 00:48:00.807193 systemd[1]: Reached target remote-cryptsetup.target. Jul 2 00:48:00.794384 ignition[654]: op(1): [finished] loading QEMU firmware config module Jul 2 00:48:00.808281 systemd[1]: Reached target remote-fs.target. Jul 2 00:48:00.810257 systemd[1]: Starting dracut-pre-mount.service... Jul 2 00:48:00.813904 ignition[654]: parsing config with SHA512: 5f0cc8099f6beb414dee21a07f327c85ed0a3e44b6ab1defce65a233a6044bfcbc53fa6f58bd24bd7562c89cab43bd59f86a299fb6e1bef6fac5d74a790622b2 Jul 2 00:48:00.819034 systemd[1]: Finished dracut-pre-mount.service. Jul 2 00:48:00.818000 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' Jul 2 00:48:00.826295 unknown[654]: fetched base config from "system" Jul 2 00:48:00.826309 unknown[654]: fetched user config from "qemu" Jul 2 00:48:00.829460 ignition[654]: fetch-offline: fetch-offline passed Jul 2 00:48:00.829892 ignition[654]: Ignition finished successfully Jul 2 00:48:00.830824 systemd[1]: Finished ignition-fetch-offline.service. Jul 2 00:48:00.830000 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' Jul 2 00:48:00.831540 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 2 00:48:00.832301 systemd[1]: Starting ignition-kargs.service... Jul 2 00:48:00.840878 ignition[763]: Ignition 2.14.0 Jul 2 00:48:00.840887 ignition[763]: Stage: kargs Jul 2 00:48:00.840973 ignition[763]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:48:00.842790 systemd[1]: Finished ignition-kargs.service. Jul 2 00:48:00.843000 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' Jul 2 00:48:00.840982 ignition[763]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:48:00.841628 ignition[763]: kargs: kargs passed Jul 2 00:48:00.844748 systemd[1]: Starting ignition-disks.service... Jul 2 00:48:00.841666 ignition[763]: Ignition finished successfully Jul 2 00:48:00.851503 ignition[769]: Ignition 2.14.0 Jul 2 00:48:00.851512 ignition[769]: Stage: disks Jul 2 00:48:00.851616 ignition[769]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:48:00.853379 systemd[1]: Finished ignition-disks.service. Jul 2 00:48:00.853000 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' Jul 2 00:48:00.851627 ignition[769]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:48:00.854513 systemd[1]: Reached target initrd-root-device.target. Jul 2 00:48:00.852312 ignition[769]: disks: disks passed Jul 2 00:48:00.855417 systemd[1]: Reached target local-fs-pre.target. Jul 2 00:48:00.852354 ignition[769]: Ignition finished successfully Jul 2 00:48:00.856596 systemd[1]: Reached target local-fs.target. Jul 2 00:48:00.857633 systemd[1]: Reached target sysinit.target. Jul 2 00:48:00.858532 systemd[1]: Reached target basic.target. Jul 2 00:48:00.860363 systemd[1]: Starting systemd-fsck-root.service... Jul 2 00:48:00.871555 systemd-fsck[777]: ROOT: clean, 614/553520 files, 56019/553472 blocks Jul 2 00:48:00.875619 systemd[1]: Finished systemd-fsck-root.service. Jul 2 00:48:00.875000 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' Jul 2 00:48:00.877263 systemd[1]: Mounting sysroot.mount... Jul 2 00:48:00.883707 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Jul 2 00:48:00.883861 systemd[1]: Mounted sysroot.mount. Jul 2 00:48:00.884431 systemd[1]: Reached target initrd-root-fs.target. Jul 2 00:48:00.886355 systemd[1]: Mounting sysroot-usr.mount... Jul 2 00:48:00.887098 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Jul 2 00:48:00.887136 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 2 00:48:00.887157 systemd[1]: Reached target ignition-diskful.target. Jul 2 00:48:00.889189 systemd[1]: Mounted sysroot-usr.mount. Jul 2 00:48:00.891329 systemd[1]: Starting initrd-setup-root.service... Jul 2 00:48:00.895671 initrd-setup-root[787]: cut: /sysroot/etc/passwd: No such file or directory Jul 2 00:48:00.900437 initrd-setup-root[795]: cut: /sysroot/etc/group: No such file or directory Jul 2 00:48:00.904664 initrd-setup-root[803]: cut: /sysroot/etc/shadow: No such file or directory Jul 2 00:48:00.908786 initrd-setup-root[811]: cut: /sysroot/etc/gshadow: No such file or directory Jul 2 00:48:00.938158 systemd[1]: Finished initrd-setup-root.service. Jul 2 00:48:00.938000 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' Jul 2 00:48:00.939538 systemd[1]: Starting ignition-mount.service... Jul 2 00:48:00.940759 systemd[1]: Starting sysroot-boot.service... Jul 2 00:48:00.944879 bash[828]: umount: /sysroot/usr/share/oem: not mounted. Jul 2 00:48:00.953657 ignition[830]: INFO : Ignition 2.14.0 Jul 2 00:48:00.953657 ignition[830]: INFO : Stage: mount Jul 2 00:48:00.954944 ignition[830]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:48:00.954944 ignition[830]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:48:00.954944 ignition[830]: INFO : mount: mount passed Jul 2 00:48:00.954944 ignition[830]: INFO : Ignition finished successfully Jul 2 00:48:00.955000 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' Jul 2 00:48:00.955325 systemd[1]: Finished ignition-mount.service. Jul 2 00:48:00.968486 systemd[1]: Finished sysroot-boot.service. Jul 2 00:48:00.968000 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' Jul 2 00:48:01.612770 systemd[1]: Mounting sysroot-usr-share-oem.mount... Jul 2 00:48:01.620586 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (838) Jul 2 00:48:01.622051 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:48:01.622074 kernel: BTRFS info (device vda6): using free space tree Jul 2 00:48:01.622084 kernel: BTRFS info (device vda6): has skinny extents Jul 2 00:48:01.627346 systemd[1]: Mounted sysroot-usr-share-oem.mount. Jul 2 00:48:01.628712 systemd[1]: Starting ignition-files.service... Jul 2 00:48:01.644513 ignition[858]: INFO : Ignition 2.14.0 Jul 2 00:48:01.644513 ignition[858]: INFO : Stage: files Jul 2 00:48:01.645660 ignition[858]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:48:01.645660 ignition[858]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:48:01.645660 ignition[858]: DEBUG : files: compiled without relabeling support, skipping Jul 2 00:48:01.651947 ignition[858]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 2 00:48:01.651947 ignition[858]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 2 00:48:01.655108 ignition[858]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 2 00:48:01.656014 ignition[858]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 2 00:48:01.657400 unknown[858]: wrote ssh authorized keys file for user: core Jul 2 00:48:01.658256 ignition[858]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 2 00:48:01.658256 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Jul 2 00:48:01.658256 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Jul 2 00:48:01.658256 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Jul 2 00:48:01.658256 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Jul 2 00:48:01.664319 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 2 00:48:01.664319 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 2 00:48:01.664319 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:48:01.664319 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:48:01.664319 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:48:01.664319 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.28.7-arm64.raw: attempt #1 Jul 2 00:48:01.967089 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Jul 2 00:48:02.276153 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-arm64.raw" Jul 2 00:48:02.276153 ignition[858]: INFO : files: op(8): [started] processing unit "containerd.service" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(8): [finished] processing unit "containerd.service" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(a): [started] processing unit "coreos-metadata.service" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(a): op(b): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(a): op(b): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(a): [finished] processing unit "coreos-metadata.service" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(c): [started] setting preset to disabled for "coreos-metadata.service" Jul 2 00:48:02.278835 ignition[858]: INFO : files: op(c): op(d): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 2 00:48:02.332653 ignition[858]: INFO : files: op(c): op(d): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 2 00:48:02.334615 ignition[858]: INFO : files: op(c): [finished] setting preset to disabled for "coreos-metadata.service" Jul 2 00:48:02.334615 ignition[858]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 2 00:48:02.334615 ignition[858]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 2 00:48:02.334615 ignition[858]: INFO : files: files passed Jul 2 00:48:02.334615 ignition[858]: INFO : Ignition finished successfully Jul 2 00:48:02.335000 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' Jul 2 00:48:02.334736 systemd[1]: Finished ignition-files.service. Jul 2 00:48:02.340774 systemd[1]: Starting initrd-setup-root-after-ignition.service... Jul 2 00:48:02.343792 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Jul 2 00:48:02.344578 systemd[1]: Starting ignition-quench.service... Jul 2 00:48:02.347391 initrd-setup-root-after-ignition[883]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Jul 2 00:48:02.348600 systemd[1]: Finished initrd-setup-root-after-ignition.service. Jul 2 00:48:02.348000 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' Jul 2 00:48:02.350532 initrd-setup-root-after-ignition[885]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 2 00:48:02.349641 systemd[1]: Reached target ignition-complete.target. Jul 2 00:48:02.351714 systemd[1]: Starting initrd-parse-etc.service... Jul 2 00:48:02.352000 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' Jul 2 00:48:02.352000 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' Jul 2 00:48:02.352655 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 2 00:48:02.352790 systemd[1]: Finished ignition-quench.service. Jul 2 00:48:02.364963 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 2 00:48:02.365062 systemd[1]: Finished initrd-parse-etc.service. Jul 2 00:48:02.365000 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' Jul 2 00:48:02.365000 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' Jul 2 00:48:02.366372 systemd[1]: Reached target initrd-fs.target. Jul 2 00:48:02.367627 systemd[1]: Reached target initrd.target. Jul 2 00:48:02.368603 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Jul 2 00:48:02.369327 systemd[1]: Starting dracut-pre-pivot.service... Jul 2 00:48:02.379912 systemd[1]: Finished dracut-pre-pivot.service. Jul 2 00:48:02.379000 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' Jul 2 00:48:02.381361 systemd[1]: Starting initrd-cleanup.service... Jul 2 00:48:02.390119 systemd[1]: Stopped target network.target. Jul 2 00:48:02.390830 systemd[1]: Stopped target nss-lookup.target. Jul 2 00:48:02.391807 systemd[1]: Stopped target remote-cryptsetup.target. Jul 2 00:48:02.392843 systemd[1]: Stopped target timers.target. Jul 2 00:48:02.393821 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 2 00:48:02.394000 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' Jul 2 00:48:02.393934 systemd[1]: Stopped dracut-pre-pivot.service. Jul 2 00:48:02.394911 systemd[1]: Stopped target initrd.target. Jul 2 00:48:02.396060 systemd[1]: Stopped target basic.target. Jul 2 00:48:02.397048 systemd[1]: Stopped target ignition-complete.target. Jul 2 00:48:02.398065 systemd[1]: Stopped target ignition-diskful.target. Jul 2 00:48:02.399090 systemd[1]: Stopped target initrd-root-device.target. Jul 2 00:48:02.400274 systemd[1]: Stopped target remote-fs.target. Jul 2 00:48:02.401279 systemd[1]: Stopped target remote-fs-pre.target. Jul 2 00:48:02.402389 systemd[1]: Stopped target sysinit.target. Jul 2 00:48:02.403327 systemd[1]: Stopped target local-fs.target. Jul 2 00:48:02.404300 systemd[1]: Stopped target local-fs-pre.target. Jul 2 00:48:02.405332 systemd[1]: Stopped target swap.target. Jul 2 00:48:02.406000 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' Jul 2 00:48:02.406248 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 2 00:48:02.406353 systemd[1]: Stopped dracut-pre-mount.service. Jul 2 00:48:02.408000 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' Jul 2 00:48:02.407369 systemd[1]: Stopped target cryptsetup.target. Jul 2 00:48:02.409000 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' Jul 2 00:48:02.408239 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 2 00:48:02.408331 systemd[1]: Stopped dracut-initqueue.service. Jul 2 00:48:02.409519 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 2 00:48:02.409618 systemd[1]: Stopped ignition-fetch-offline.service. Jul 2 00:48:02.410654 systemd[1]: Stopped target paths.target. Jul 2 00:48:02.411530 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 2 00:48:02.414728 systemd[1]: Stopped systemd-ask-password-console.path. Jul 2 00:48:02.415796 systemd[1]: Stopped target slices.target. Jul 2 00:48:02.417031 systemd[1]: Stopped target sockets.target. Jul 2 00:48:02.418108 systemd[1]: iscsid.socket: Deactivated successfully. Jul 2 00:48:02.418178 systemd[1]: Closed iscsid.socket. Jul 2 00:48:02.420000 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' Jul 2 00:48:02.418988 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 2 00:48:02.421000 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' Jul 2 00:48:02.419049 systemd[1]: Closed iscsiuio.socket. Jul 2 00:48:02.419927 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 2 00:48:02.420020 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Jul 2 00:48:02.424000 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' Jul 2 00:48:02.421133 systemd[1]: ignition-files.service: Deactivated successfully. Jul 2 00:48:02.421219 systemd[1]: Stopped ignition-files.service. Jul 2 00:48:02.422924 systemd[1]: Stopping ignition-mount.service... Jul 2 00:48:02.423490 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 2 00:48:02.423614 systemd[1]: Stopped kmod-static-nodes.service. Jul 2 00:48:02.429000 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' Jul 2 00:48:02.425573 systemd[1]: Stopping sysroot-boot.service... Jul 2 00:48:02.430000 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' Jul 2 00:48:02.426864 systemd[1]: Stopping systemd-networkd.service... Jul 2 00:48:02.427857 systemd[1]: Stopping systemd-resolved.service... Jul 2 00:48:02.428801 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 2 00:48:02.428963 systemd[1]: Stopped systemd-udev-trigger.service. Jul 2 00:48:02.438000 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' Jul 2 00:48:02.438000 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' Jul 2 00:48:02.440208 ignition[899]: INFO : Ignition 2.14.0 Jul 2 00:48:02.440208 ignition[899]: INFO : Stage: umount Jul 2 00:48:02.440208 ignition[899]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:48:02.440208 ignition[899]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:48:02.440208 ignition[899]: INFO : umount: umount passed Jul 2 00:48:02.440208 ignition[899]: INFO : Ignition finished successfully Jul 2 00:48:02.439000 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' Jul 2 00:48:02.441000 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' Jul 2 00:48:02.442000 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' Jul 2 00:48:02.443000 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' Jul 2 00:48:02.445000 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' Jul 2 00:48:02.430198 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 2 00:48:02.430340 systemd[1]: Stopped dracut-pre-trigger.service. Jul 2 00:48:02.448000 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' Jul 2 00:48:02.435622 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 2 00:48:02.449000 audit: BPF prog-id=6 op=UNLOAD Jul 2 00:48:02.435716 systemd[1]: Finished initrd-cleanup.service. Jul 2 00:48:02.439767 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 2 00:48:02.439853 systemd[1]: Stopped systemd-resolved.service. Jul 2 00:48:02.441129 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 2 00:48:02.453000 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' Jul 2 00:48:02.441205 systemd[1]: Stopped ignition-mount.service. Jul 2 00:48:02.454000 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' Jul 2 00:48:02.442387 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 2 00:48:02.456000 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' Jul 2 00:48:02.442425 systemd[1]: Stopped ignition-disks.service. Jul 2 00:48:02.442860 systemd-networkd[742]: eth0: DHCPv6 lease lost Jul 2 00:48:02.459000 audit: BPF prog-id=9 op=UNLOAD Jul 2 00:48:02.443421 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 2 00:48:02.443454 systemd[1]: Stopped ignition-kargs.service. Jul 2 00:48:02.444705 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 2 00:48:02.444743 systemd[1]: Stopped ignition-setup.service. Jul 2 00:48:02.447510 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 2 00:48:02.447909 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 2 00:48:02.447998 systemd[1]: Stopped systemd-networkd.service. Jul 2 00:48:02.449268 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 2 00:48:02.449295 systemd[1]: Closed systemd-networkd.socket. Jul 2 00:48:02.468000 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' Jul 2 00:48:02.452461 systemd[1]: Stopping network-cleanup.service... Jul 2 00:48:02.453111 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 2 00:48:02.453176 systemd[1]: Stopped parse-ip-for-networkd.service. Jul 2 00:48:02.471000 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' Jul 2 00:48:02.454253 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 2 00:48:02.454297 systemd[1]: Stopped systemd-sysctl.service. Jul 2 00:48:02.474000 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' Jul 2 00:48:02.455872 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 2 00:48:02.477000 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' Jul 2 00:48:02.455916 systemd[1]: Stopped systemd-modules-load.service. Jul 2 00:48:02.478000 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' Jul 2 00:48:02.460849 systemd[1]: Stopping systemd-udevd.service... Jul 2 00:48:02.464822 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 2 00:48:02.468089 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 2 00:48:02.468237 systemd[1]: Stopped systemd-udevd.service. Jul 2 00:48:02.482000 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' Jul 2 00:48:02.469605 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 2 00:48:02.469714 systemd[1]: Stopped network-cleanup.service. Jul 2 00:48:02.471996 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 2 00:48:02.484000 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' Jul 2 00:48:02.472038 systemd[1]: Closed systemd-udevd-control.socket. Jul 2 00:48:02.486000 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' Jul 2 00:48:02.473111 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 2 00:48:02.487000 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' Jul 2 00:48:02.487000 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' Jul 2 00:48:02.473144 systemd[1]: Closed systemd-udevd-kernel.socket. Jul 2 00:48:02.474334 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 2 00:48:02.474384 systemd[1]: Stopped dracut-pre-udev.service. Jul 2 00:48:02.475561 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 2 00:48:02.475608 systemd[1]: Stopped dracut-cmdline.service. Jul 2 00:48:02.478102 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 2 00:48:02.478149 systemd[1]: Stopped dracut-cmdline-ask.service. Jul 2 00:48:02.480023 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Jul 2 00:48:02.481083 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 2 00:48:02.481162 systemd[1]: Stopped systemd-vconsole-setup.service. Jul 2 00:48:02.484652 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 2 00:48:02.484781 systemd[1]: Stopped sysroot-boot.service. Jul 2 00:48:02.485881 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 2 00:48:02.485922 systemd[1]: Stopped initrd-setup-root.service. Jul 2 00:48:02.487209 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 2 00:48:02.487292 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Jul 2 00:48:02.488288 systemd[1]: Reached target initrd-switch-root.target. Jul 2 00:48:02.491618 systemd[1]: Starting initrd-switch-root.service... Jul 2 00:48:02.507889 systemd[1]: Switching root. Jul 2 00:48:02.510000 audit: BPF prog-id=8 op=UNLOAD Jul 2 00:48:02.510000 audit: BPF prog-id=7 op=UNLOAD Jul 2 00:48:02.510000 audit: BPF prog-id=5 op=UNLOAD Jul 2 00:48:02.510000 audit: BPF prog-id=4 op=UNLOAD Jul 2 00:48:02.510000 audit: BPF prog-id=3 op=UNLOAD Jul 2 00:48:02.526668 iscsid[747]: iscsid shutting down. Jul 2 00:48:02.527216 systemd-journald[291]: Received SIGTERM from PID 1 (n/a). Jul 2 00:48:02.527244 systemd-journald[291]: Journal stopped Jul 2 00:48:04.568938 kernel: SELinux: Class mctp_socket not defined in policy. Jul 2 00:48:04.568992 kernel: SELinux: Class anon_inode not defined in policy. Jul 2 00:48:04.569004 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 2 00:48:04.569019 kernel: SELinux: policy capability network_peer_controls=1 Jul 2 00:48:04.569032 kernel: SELinux: policy capability open_perms=1 Jul 2 00:48:04.569042 kernel: SELinux: policy capability extended_socket_class=1 Jul 2 00:48:04.569052 kernel: SELinux: policy capability always_check_network=0 Jul 2 00:48:04.569062 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 2 00:48:04.569071 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 2 00:48:04.569081 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 2 00:48:04.569091 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 2 00:48:04.569102 systemd[1]: Successfully loaded SELinux policy in 41.305ms. Jul 2 00:48:04.569125 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 8.262ms. Jul 2 00:48:04.569137 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) Jul 2 00:48:04.569149 systemd[1]: Detected virtualization kvm. Jul 2 00:48:04.569159 systemd[1]: Detected architecture arm64. Jul 2 00:48:04.569170 systemd[1]: Detected first boot. Jul 2 00:48:04.569209 systemd[1]: Initializing machine ID from VM UUID. Jul 2 00:48:04.569222 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 2 00:48:04.569233 systemd[1]: Populated /etc with preset unit settings. Jul 2 00:48:04.569246 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 2 00:48:04.569258 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 2 00:48:04.569270 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. Jul 2 00:48:04.569281 systemd[1]: Queued start job for default target multi-user.target. Jul 2 00:48:04.569292 systemd[1]: Unnecessary job was removed for dev-vda6.device. Jul 2 00:48:04.569302 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 2 00:48:04.569327 systemd[1]: Created slice system-addon\x2drun.slice. Jul 2 00:48:04.569339 systemd[1]: Created slice system-getty.slice. Jul 2 00:48:04.569351 systemd[1]: Created slice system-modprobe.slice. Jul 2 00:48:04.569361 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 2 00:48:04.569373 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 2 00:48:04.569384 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 2 00:48:04.569394 systemd[1]: Created slice user.slice. Jul 2 00:48:04.569405 systemd[1]: Started systemd-ask-password-console.path. Jul 2 00:48:04.569417 systemd[1]: Started systemd-ask-password-wall.path. Jul 2 00:48:04.569428 systemd[1]: Set up automount boot.automount. Jul 2 00:48:04.569438 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 2 00:48:04.569450 systemd[1]: Reached target integritysetup.target. Jul 2 00:48:04.569461 systemd[1]: Reached target remote-cryptsetup.target. Jul 2 00:48:04.569472 systemd[1]: Reached target remote-fs.target. Jul 2 00:48:04.569483 systemd[1]: Reached target slices.target. Jul 2 00:48:04.569494 systemd[1]: Reached target swap.target. Jul 2 00:48:04.569506 systemd[1]: Reached target torcx.target. Jul 2 00:48:04.569518 systemd[1]: Reached target veritysetup.target. Jul 2 00:48:04.569529 systemd[1]: Listening on systemd-coredump.socket. Jul 2 00:48:04.569539 systemd[1]: Listening on systemd-initctl.socket. Jul 2 00:48:04.569550 kernel: kauditd_printk_skb: 78 callbacks suppressed Jul 2 00:48:04.569560 systemd[1]: Listening on systemd-journald-audit.socket. Jul 2 00:48:04.569577 kernel: audit: type=1400 audit(1719881284.483:82): avc: denied { audit_read } for pid=1 comm="systemd" capability=37 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Jul 2 00:48:04.569587 kernel: audit: type=1335 audit(1719881284.483:83): pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Jul 2 00:48:04.569599 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 2 00:48:04.569610 systemd[1]: Listening on systemd-journald.socket. Jul 2 00:48:04.569622 systemd[1]: Listening on systemd-networkd.socket. Jul 2 00:48:04.569632 systemd[1]: Listening on systemd-udevd-control.socket. Jul 2 00:48:04.569643 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 2 00:48:04.569654 systemd[1]: Listening on systemd-userdbd.socket. Jul 2 00:48:04.569665 systemd[1]: Mounting dev-hugepages.mount... Jul 2 00:48:04.569681 systemd[1]: Mounting dev-mqueue.mount... Jul 2 00:48:04.569698 systemd[1]: Mounting media.mount... Jul 2 00:48:04.569709 systemd[1]: Mounting sys-kernel-debug.mount... Jul 2 00:48:04.569722 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 2 00:48:04.569733 systemd[1]: Mounting tmp.mount... Jul 2 00:48:04.569743 systemd[1]: Starting flatcar-tmpfiles.service... Jul 2 00:48:04.569754 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 2 00:48:04.569765 systemd[1]: Starting kmod-static-nodes.service... Jul 2 00:48:04.569776 systemd[1]: Starting modprobe@configfs.service... Jul 2 00:48:04.569787 systemd[1]: Starting modprobe@dm_mod.service... Jul 2 00:48:04.569798 systemd[1]: Starting modprobe@drm.service... Jul 2 00:48:04.569809 systemd[1]: Starting modprobe@efi_pstore.service... Jul 2 00:48:04.569821 systemd[1]: Starting modprobe@fuse.service... Jul 2 00:48:04.569832 systemd[1]: Starting modprobe@loop.service... Jul 2 00:48:04.569843 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 2 00:48:04.569854 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Jul 2 00:48:04.569865 systemd[1]: (This warning is only shown for the first unit using IP firewalling.) Jul 2 00:48:04.569876 systemd[1]: Starting systemd-journald.service... Jul 2 00:48:04.569887 systemd[1]: Starting systemd-modules-load.service... Jul 2 00:48:04.569899 systemd[1]: Starting systemd-network-generator.service... Jul 2 00:48:04.569910 systemd[1]: Starting systemd-remount-fs.service... Jul 2 00:48:04.569922 systemd[1]: Starting systemd-udev-trigger.service... Jul 2 00:48:04.569933 systemd[1]: Mounted dev-hugepages.mount. Jul 2 00:48:04.569944 systemd[1]: Mounted dev-mqueue.mount. Jul 2 00:48:04.569956 systemd[1]: Mounted media.mount. Jul 2 00:48:04.569967 systemd[1]: Mounted sys-kernel-debug.mount. Jul 2 00:48:04.569978 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 2 00:48:04.569988 kernel: audit: type=1305 audit(1719881284.565:84): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 2 00:48:04.569999 kernel: audit: type=1300 audit(1719881284.565:84): arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffc6d64f20 a2=4000 a3=1 items=0 ppid=1 pid=1030 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) Jul 2 00:48:04.570012 kernel: audit: type=1327 audit(1719881284.565:84): proctitle="/usr/lib/systemd/systemd-journald" Jul 2 00:48:04.570025 systemd-journald[1030]: Journal started Jul 2 00:48:04.570068 systemd-journald[1030]: Runtime Journal (/run/log/journal/1aaee115f37844b4bf18a38f5a44795d) is 6.0M, max 48.7M, 42.6M free. Jul 2 00:48:04.483000 audit[1]: AVC avc: denied { audit_read } for pid=1 comm="systemd" capability=37 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Jul 2 00:48:04.483000 audit[1]: EVENT_LISTENER pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Jul 2 00:48:04.565000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 2 00:48:04.565000 audit[1030]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffc6d64f20 a2=4000 a3=1 items=0 ppid=1 pid=1030 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) Jul 2 00:48:04.565000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 2 00:48:04.574875 systemd[1]: Started systemd-journald.service. Jul 2 00:48:04.577000 kernel: fuse: init (API version 7.34) Jul 2 00:48:04.577028 kernel: audit: type=1130 audit(1719881284.574:85): 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' Jul 2 00:48:04.574000 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' Jul 2 00:48:04.575640 systemd[1]: Mounted tmp.mount. Jul 2 00:48:04.578486 kernel: loop: module loaded Jul 2 00:48:04.581225 systemd[1]: Finished kmod-static-nodes.service. Jul 2 00:48:04.581000 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' Jul 2 00:48:04.582135 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 2 00:48:04.582373 systemd[1]: Finished modprobe@configfs.service. Jul 2 00:48:04.586247 kernel: audit: type=1130 audit(1719881284.581:86): 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' Jul 2 00:48:04.586289 kernel: audit: type=1130 audit(1719881284.583:87): 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' Jul 2 00:48:04.586303 kernel: audit: type=1131 audit(1719881284.583:88): 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' Jul 2 00:48:04.583000 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' Jul 2 00:48:04.583000 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' Jul 2 00:48:04.584674 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:48:04.584891 systemd[1]: Finished modprobe@dm_mod.service. Jul 2 00:48:04.588000 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' Jul 2 00:48:04.588000 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' Jul 2 00:48:04.590713 kernel: audit: type=1130 audit(1719881284.588:89): 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' Jul 2 00:48:04.588975 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 2 00:48:04.589187 systemd[1]: Finished modprobe@drm.service. Jul 2 00:48:04.590000 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' Jul 2 00:48:04.590000 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' Jul 2 00:48:04.591549 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:48:04.592031 systemd[1]: Finished modprobe@efi_pstore.service. Jul 2 00:48:04.591000 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' Jul 2 00:48:04.591000 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' Jul 2 00:48:04.592935 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 2 00:48:04.593132 systemd[1]: Finished modprobe@fuse.service. Jul 2 00:48:04.592000 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' Jul 2 00:48:04.593000 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' Jul 2 00:48:04.593948 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:48:04.594137 systemd[1]: Finished modprobe@loop.service. Jul 2 00:48:04.593000 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' Jul 2 00:48:04.594000 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' Jul 2 00:48:04.595057 systemd[1]: Finished systemd-modules-load.service. Jul 2 00:48:04.594000 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' Jul 2 00:48:04.596278 systemd[1]: Finished systemd-network-generator.service. Jul 2 00:48:04.596000 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' Jul 2 00:48:04.597367 systemd[1]: Finished systemd-remount-fs.service. Jul 2 00:48:04.597000 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' Jul 2 00:48:04.598524 systemd[1]: Reached target network-pre.target. Jul 2 00:48:04.600322 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 2 00:48:04.601963 systemd[1]: Mounting sys-kernel-config.mount... Jul 2 00:48:04.602506 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 2 00:48:04.605952 systemd[1]: Starting systemd-hwdb-update.service... Jul 2 00:48:04.608008 systemd[1]: Starting systemd-journal-flush.service... Jul 2 00:48:04.608778 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 00:48:04.609914 systemd[1]: Starting systemd-random-seed.service... Jul 2 00:48:04.610765 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 2 00:48:04.612301 systemd[1]: Starting systemd-sysctl.service... Jul 2 00:48:04.614903 systemd[1]: Finished flatcar-tmpfiles.service. Jul 2 00:48:04.615000 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' Jul 2 00:48:04.618219 systemd[1]: Finished systemd-udev-trigger.service. Jul 2 00:48:04.618000 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' Jul 2 00:48:04.619123 systemd-journald[1030]: Time spent on flushing to /var/log/journal/1aaee115f37844b4bf18a38f5a44795d is 15.252ms for 908 entries. Jul 2 00:48:04.619123 systemd-journald[1030]: System Journal (/var/log/journal/1aaee115f37844b4bf18a38f5a44795d) is 8.0M, max 195.6M, 187.6M free. Jul 2 00:48:04.644227 systemd-journald[1030]: Received client request to flush runtime journal. Jul 2 00:48:04.628000 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' Jul 2 00:48:04.637000 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' Jul 2 00:48:04.619124 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 2 00:48:04.621324 systemd[1]: Mounted sys-kernel-config.mount. Jul 2 00:48:04.644873 udevadm[1085]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Jul 2 00:48:04.624094 systemd[1]: Starting systemd-sysusers.service... Jul 2 00:48:04.625902 systemd[1]: Starting systemd-udev-settle.service... Jul 2 00:48:04.628585 systemd[1]: Finished systemd-random-seed.service. Jul 2 00:48:04.629769 systemd[1]: Reached target first-boot-complete.target. Jul 2 00:48:04.637444 systemd[1]: Finished systemd-sysctl.service. Jul 2 00:48:04.646547 systemd[1]: Finished systemd-journal-flush.service. Jul 2 00:48:04.646000 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' Jul 2 00:48:04.654936 systemd[1]: Finished systemd-sysusers.service. Jul 2 00:48:04.654000 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' Jul 2 00:48:04.656966 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 2 00:48:04.674844 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 2 00:48:04.674000 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' Jul 2 00:48:04.986459 systemd[1]: Finished systemd-hwdb-update.service. Jul 2 00:48:04.986000 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' Jul 2 00:48:04.988431 systemd[1]: Starting systemd-udevd.service... Jul 2 00:48:05.004605 systemd-udevd[1095]: Using default interface naming scheme 'v252'. Jul 2 00:48:05.019891 systemd[1]: Started systemd-udevd.service. Jul 2 00:48:05.019000 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' Jul 2 00:48:05.022515 systemd[1]: Starting systemd-networkd.service... Jul 2 00:48:05.033653 systemd[1]: Starting systemd-userdbd.service... Jul 2 00:48:05.060968 systemd[1]: Found device dev-ttyAMA0.device. Jul 2 00:48:05.071080 systemd[1]: Started systemd-userdbd.service. Jul 2 00:48:05.071000 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' Jul 2 00:48:05.103054 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 2 00:48:05.140913 systemd-networkd[1103]: lo: Link UP Jul 2 00:48:05.141233 systemd-networkd[1103]: lo: Gained carrier Jul 2 00:48:05.141672 systemd-networkd[1103]: Enumeration completed Jul 2 00:48:05.141874 systemd-networkd[1103]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 00:48:05.141899 systemd[1]: Started systemd-networkd.service. Jul 2 00:48:05.141000 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' Jul 2 00:48:05.143000 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' Jul 2 00:48:05.143187 systemd[1]: Finished systemd-udev-settle.service. Jul 2 00:48:05.145386 systemd[1]: Starting lvm2-activation-early.service... Jul 2 00:48:05.149182 systemd-networkd[1103]: eth0: Link UP Jul 2 00:48:05.149271 systemd-networkd[1103]: eth0: Gained carrier Jul 2 00:48:05.171823 lvm[1129]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 00:48:05.173816 systemd-networkd[1103]: eth0: DHCPv4 address 10.0.0.69/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 2 00:48:05.195624 systemd[1]: Finished lvm2-activation-early.service. Jul 2 00:48:05.195000 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' Jul 2 00:48:05.196489 systemd[1]: Reached target cryptsetup.target. Jul 2 00:48:05.198537 systemd[1]: Starting lvm2-activation.service... Jul 2 00:48:05.202341 lvm[1131]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 00:48:05.233734 systemd[1]: Finished lvm2-activation.service. Jul 2 00:48:05.233000 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' Jul 2 00:48:05.234467 systemd[1]: Reached target local-fs-pre.target. Jul 2 00:48:05.235116 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 2 00:48:05.235146 systemd[1]: Reached target local-fs.target. Jul 2 00:48:05.235710 systemd[1]: Reached target machines.target. Jul 2 00:48:05.237644 systemd[1]: Starting ldconfig.service... Jul 2 00:48:05.238918 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.238978 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). Jul 2 00:48:05.240142 systemd[1]: Starting systemd-boot-update.service... Jul 2 00:48:05.242229 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 2 00:48:05.244671 systemd[1]: Starting systemd-machine-id-commit.service... Jul 2 00:48:05.246782 systemd[1]: Starting systemd-sysext.service... Jul 2 00:48:05.248090 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1135 (bootctl) Jul 2 00:48:05.249214 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 2 00:48:05.255062 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 2 00:48:05.255000 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' Jul 2 00:48:05.260538 systemd[1]: Unmounting usr-share-oem.mount... Jul 2 00:48:05.268210 systemd[1]: usr-share-oem.mount: Deactivated successfully. Jul 2 00:48:05.268471 systemd[1]: Unmounted usr-share-oem.mount. Jul 2 00:48:05.288758 kernel: loop0: detected capacity change from 0 to 193208 Jul 2 00:48:05.332119 systemd[1]: Finished systemd-machine-id-commit.service. Jul 2 00:48:05.333000 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' Jul 2 00:48:05.344712 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 2 00:48:05.347992 systemd-fsck[1146]: fsck.fat 4.2 (2021-01-31) Jul 2 00:48:05.347992 systemd-fsck[1146]: /dev/vda1: 236 files, 117047/258078 clusters Jul 2 00:48:05.350111 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 2 00:48:05.350000 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' Jul 2 00:48:05.365770 kernel: loop1: detected capacity change from 0 to 193208 Jul 2 00:48:05.376178 (sd-sysext)[1153]: Using extensions 'kubernetes'. Jul 2 00:48:05.377394 (sd-sysext)[1153]: Merged extensions into '/usr'. Jul 2 00:48:05.393990 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.396001 systemd[1]: Starting modprobe@dm_mod.service... Jul 2 00:48:05.398990 systemd[1]: Starting modprobe@efi_pstore.service... Jul 2 00:48:05.401406 systemd[1]: Starting modprobe@loop.service... Jul 2 00:48:05.402129 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.402256 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). Jul 2 00:48:05.403569 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:48:05.403944 systemd[1]: Finished modprobe@dm_mod.service. Jul 2 00:48:05.404000 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' Jul 2 00:48:05.404000 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' Jul 2 00:48:05.405486 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:48:05.405666 systemd[1]: Finished modprobe@efi_pstore.service. Jul 2 00:48:05.405000 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' Jul 2 00:48:05.405000 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' Jul 2 00:48:05.406661 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:48:05.406836 systemd[1]: Finished modprobe@loop.service. Jul 2 00:48:05.406000 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' Jul 2 00:48:05.406000 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' Jul 2 00:48:05.407825 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 00:48:05.407930 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.464998 ldconfig[1134]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 2 00:48:05.468407 systemd[1]: Finished ldconfig.service. Jul 2 00:48:05.468000 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' Jul 2 00:48:05.560553 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 2 00:48:05.562531 systemd[1]: Mounting boot.mount... Jul 2 00:48:05.564606 systemd[1]: Mounting usr-share-oem.mount... Jul 2 00:48:05.569396 systemd[1]: Mounted usr-share-oem.mount. Jul 2 00:48:05.571162 systemd[1]: Finished systemd-sysext.service. Jul 2 00:48:05.571000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:48:05.573271 systemd[1]: Starting ensure-sysext.service... Jul 2 00:48:05.574948 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 2 00:48:05.579187 systemd[1]: Mounted boot.mount. Jul 2 00:48:05.581973 systemd[1]: Reloading. Jul 2 00:48:05.584548 systemd-tmpfiles[1170]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 2 00:48:05.585736 systemd-tmpfiles[1170]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 2 00:48:05.587345 systemd-tmpfiles[1170]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 2 00:48:05.619943 /usr/lib/systemd/system-generators/torcx-generator[1191]: time="2024-07-02T00:48:05Z" 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.5 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.5 /var/lib/torcx/store]" Jul 2 00:48:05.619976 /usr/lib/systemd/system-generators/torcx-generator[1191]: time="2024-07-02T00:48:05Z" level=info msg="torcx already run" Jul 2 00:48:05.685001 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 2 00:48:05.685019 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 2 00:48:05.700967 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. Jul 2 00:48:05.747884 systemd[1]: Finished systemd-boot-update.service. Jul 2 00:48:05.747000 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' Jul 2 00:48:05.749734 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 2 00:48:05.749000 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' Jul 2 00:48:05.752606 systemd[1]: Starting audit-rules.service... Jul 2 00:48:05.754367 systemd[1]: Starting clean-ca-certificates.service... Jul 2 00:48:05.756436 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 2 00:48:05.758717 systemd[1]: Starting systemd-resolved.service... Jul 2 00:48:05.760935 systemd[1]: Starting systemd-timesyncd.service... Jul 2 00:48:05.762873 systemd[1]: Starting systemd-update-utmp.service... Jul 2 00:48:05.764197 systemd[1]: Finished clean-ca-certificates.service. Jul 2 00:48:05.764000 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' Jul 2 00:48:05.767044 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 2 00:48:05.770354 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.770000 audit[1243]: SYSTEM_BOOT pid=1243 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' Jul 2 00:48:05.771536 systemd[1]: Starting modprobe@dm_mod.service... Jul 2 00:48:05.773510 systemd[1]: Starting modprobe@efi_pstore.service... Jul 2 00:48:05.775393 systemd[1]: Starting modprobe@loop.service... Jul 2 00:48:05.776018 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.776153 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). Jul 2 00:48:05.776274 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 2 00:48:05.777049 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:48:05.777206 systemd[1]: Finished modprobe@dm_mod.service. Jul 2 00:48:05.777000 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' Jul 2 00:48:05.777000 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' Jul 2 00:48:05.778430 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:48:05.778579 systemd[1]: Finished modprobe@loop.service. Jul 2 00:48:05.779000 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' Jul 2 00:48:05.779000 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' Jul 2 00:48:05.781351 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:48:05.781525 systemd[1]: Finished modprobe@efi_pstore.service. Jul 2 00:48:05.782705 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 00:48:05.782855 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.782000 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' Jul 2 00:48:05.782000 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' Jul 2 00:48:05.784848 systemd[1]: Finished systemd-update-utmp.service. Jul 2 00:48:05.788000 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' Jul 2 00:48:05.790160 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.791535 systemd[1]: Starting modprobe@dm_mod.service... Jul 2 00:48:05.793533 systemd[1]: Starting modprobe@efi_pstore.service... Jul 2 00:48:05.795388 systemd[1]: Starting modprobe@loop.service... Jul 2 00:48:05.796083 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.796266 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). Jul 2 00:48:05.796375 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 2 00:48:05.797377 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:48:05.797547 systemd[1]: Finished modprobe@dm_mod.service. Jul 2 00:48:05.798000 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' Jul 2 00:48:05.798000 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' Jul 2 00:48:05.798929 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 2 00:48:05.799000 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' Jul 2 00:48:05.800000 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' Jul 2 00:48:05.800000 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' Jul 2 00:48:05.800082 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:48:05.800285 systemd[1]: Finished modprobe@loop.service. Jul 2 00:48:05.803384 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.804914 systemd[1]: Starting modprobe@dm_mod.service... Jul 2 00:48:05.810789 systemd[1]: Starting modprobe@drm.service... Jul 2 00:48:05.812649 systemd[1]: Starting modprobe@loop.service... Jul 2 00:48:05.813459 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.813639 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). Jul 2 00:48:05.815033 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 2 00:48:05.817524 systemd[1]: Starting systemd-update-done.service... Jul 2 00:48:05.818216 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 2 00:48:05.823256 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:48:05.823467 systemd[1]: Finished modprobe@efi_pstore.service. Jul 2 00:48:05.824000 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' Jul 2 00:48:05.824000 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' Jul 2 00:48:05.824657 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:48:05.824833 systemd[1]: Finished modprobe@dm_mod.service. Jul 2 00:48:05.824000 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' Jul 2 00:48:05.824000 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' Jul 2 00:48:05.825968 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 2 00:48:05.826133 systemd[1]: Finished modprobe@drm.service. Jul 2 00:48:05.826000 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' Jul 2 00:48:05.826000 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' Jul 2 00:48:05.827143 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:48:05.827318 systemd[1]: Finished modprobe@loop.service. Jul 2 00:48:05.827000 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' Jul 2 00:48:05.827000 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' Jul 2 00:48:05.828760 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 00:48:05.828859 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.829205 systemd[1]: Finished systemd-update-done.service. Jul 2 00:48:05.829000 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' Jul 2 00:48:05.830324 systemd[1]: Finished ensure-sysext.service. Jul 2 00:48:05.830000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ensure-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:48:05.844000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Jul 2 00:48:05.844000 audit[1284]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffd85f1360 a2=420 a3=0 items=0 ppid=1236 pid=1284 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) Jul 2 00:48:05.844000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 2 00:48:05.845145 augenrules[1284]: No rules Jul 2 00:48:05.846138 systemd[1]: Finished audit-rules.service. Jul 2 00:48:05.848157 systemd[1]: Started systemd-timesyncd.service. Jul 2 00:48:05.849239 systemd[1]: Reached target time-set.target. Jul 2 00:48:05.851545 systemd-resolved[1241]: Positive Trust Anchors: Jul 2 00:48:05.851798 systemd-resolved[1241]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 2 00:48:05.851877 systemd-resolved[1241]: 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 Jul 2 00:48:05.852675 systemd-timesyncd[1242]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 2 00:48:05.852754 systemd-timesyncd[1242]: Initial clock synchronization to Tue 2024-07-02 00:48:06.198118 UTC. Jul 2 00:48:05.873784 systemd-resolved[1241]: Defaulting to hostname 'linux'. Jul 2 00:48:05.875248 systemd[1]: Started systemd-resolved.service. Jul 2 00:48:05.875955 systemd[1]: Reached target network.target. Jul 2 00:48:05.876492 systemd[1]: Reached target nss-lookup.target. Jul 2 00:48:05.877089 systemd[1]: Reached target sysinit.target. Jul 2 00:48:05.877707 systemd[1]: Started motdgen.path. Jul 2 00:48:05.878214 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 2 00:48:05.879156 systemd[1]: Started logrotate.timer. Jul 2 00:48:05.879804 systemd[1]: Started mdadm.timer. Jul 2 00:48:05.880281 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 2 00:48:05.880883 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 2 00:48:05.880911 systemd[1]: Reached target paths.target. Jul 2 00:48:05.881409 systemd[1]: Reached target timers.target. Jul 2 00:48:05.882264 systemd[1]: Listening on dbus.socket. Jul 2 00:48:05.884091 systemd[1]: Starting docker.socket... Jul 2 00:48:05.887539 systemd[1]: Listening on sshd.socket. Jul 2 00:48:05.888241 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). Jul 2 00:48:05.888665 systemd[1]: Listening on docker.socket. Jul 2 00:48:05.889309 systemd[1]: Reached target sockets.target. Jul 2 00:48:05.889884 systemd[1]: Reached target basic.target. Jul 2 00:48:05.890538 systemd[1]: System is tainted: cgroupsv1 Jul 2 00:48:05.890596 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.890617 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 2 00:48:05.891772 systemd[1]: Starting containerd.service... Jul 2 00:48:05.893492 systemd[1]: Starting dbus.service... Jul 2 00:48:05.895286 systemd[1]: Starting enable-oem-cloudinit.service... Jul 2 00:48:05.897343 systemd[1]: Starting extend-filesystems.service... Jul 2 00:48:05.898099 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 2 00:48:05.899618 systemd[1]: Starting motdgen.service... Jul 2 00:48:05.901504 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 2 00:48:05.903481 systemd[1]: Starting sshd-keygen.service... Jul 2 00:48:05.906350 systemd[1]: Starting systemd-logind.service... Jul 2 00:48:05.907355 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 2 00:48:05.907433 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 2 00:48:05.908665 systemd[1]: Starting update-engine.service... Jul 2 00:48:05.913527 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 2 00:48:05.916488 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 2 00:48:05.916899 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 2 00:48:05.917931 jq[1311]: true Jul 2 00:48:05.923463 jq[1296]: false Jul 2 00:48:05.927144 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 2 00:48:05.927395 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 2 00:48:05.931649 systemd[1]: motdgen.service: Deactivated successfully. Jul 2 00:48:05.931918 systemd[1]: Finished motdgen.service. Jul 2 00:48:05.933679 extend-filesystems[1297]: Found loop1 Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda1 Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda2 Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda3 Jul 2 00:48:05.933679 extend-filesystems[1297]: Found usr Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda4 Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda6 Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda7 Jul 2 00:48:05.933679 extend-filesystems[1297]: Found vda9 Jul 2 00:48:05.933679 extend-filesystems[1297]: Checking size of /dev/vda9 Jul 2 00:48:05.946568 jq[1317]: true Jul 2 00:48:05.946359 systemd[1]: Started dbus.service. Jul 2 00:48:05.946118 dbus-daemon[1295]: [system] SELinux support is enabled Jul 2 00:48:05.949185 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). Jul 2 00:48:05.949217 systemd[1]: Reached target system-config.target. Jul 2 00:48:05.950010 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 2 00:48:05.950033 systemd[1]: Reached target user-config.target. Jul 2 00:48:05.955435 extend-filesystems[1297]: Resized partition /dev/vda9 Jul 2 00:48:05.972515 extend-filesystems[1337]: resize2fs 1.46.5 (30-Dec-2021) Jul 2 00:48:05.992922 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 2 00:48:06.002747 systemd-logind[1304]: Watching system buttons on /dev/input/event0 (Power Button) Jul 2 00:48:06.003130 systemd-logind[1304]: New seat seat0. Jul 2 00:48:06.005275 systemd[1]: Started systemd-logind.service. Jul 2 00:48:06.026922 update_engine[1307]: I0702 00:48:06.011902 1307 main.cc:92] Flatcar Update Engine starting Jul 2 00:48:06.026922 update_engine[1307]: I0702 00:48:06.020457 1307 update_check_scheduler.cc:74] Next update check in 2m39s Jul 2 00:48:06.016276 systemd[1]: Started update-engine.service. Jul 2 00:48:06.019030 systemd[1]: Started locksmithd.service. Jul 2 00:48:06.033504 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 2 00:48:06.065340 extend-filesystems[1337]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 2 00:48:06.065340 extend-filesystems[1337]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 2 00:48:06.065340 extend-filesystems[1337]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 2 00:48:06.068621 extend-filesystems[1297]: Resized filesystem in /dev/vda9 Jul 2 00:48:06.068061 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 2 00:48:06.068339 systemd[1]: Finished extend-filesystems.service. Jul 2 00:48:06.070907 env[1316]: time="2024-07-02T00:48:06.070840619Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 2 00:48:06.073212 bash[1348]: Updated "/home/core/.ssh/authorized_keys" Jul 2 00:48:06.074353 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 2 00:48:06.099573 env[1316]: time="2024-07-02T00:48:06.099370135Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 2 00:48:06.099573 env[1316]: time="2024-07-02T00:48:06.099555865Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:48:06.100900 env[1316]: time="2024-07-02T00:48:06.100856559Z" 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.161-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 2 00:48:06.100900 env[1316]: time="2024-07-02T00:48:06.100896326Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:48:06.101205 env[1316]: time="2024-07-02T00:48:06.101170977Z" 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 Jul 2 00:48:06.101205 env[1316]: time="2024-07-02T00:48:06.101198559Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 2 00:48:06.101286 env[1316]: time="2024-07-02T00:48:06.101213581Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 2 00:48:06.101286 env[1316]: time="2024-07-02T00:48:06.101224472Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 2 00:48:06.101329 env[1316]: time="2024-07-02T00:48:06.101300333Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:48:06.101654 env[1316]: time="2024-07-02T00:48:06.101622846Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:48:06.101838 env[1316]: time="2024-07-02T00:48:06.101805989Z" 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 Jul 2 00:48:06.101838 env[1316]: time="2024-07-02T00:48:06.101829190Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 2 00:48:06.101902 env[1316]: time="2024-07-02T00:48:06.101885272Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 2 00:48:06.101929 env[1316]: time="2024-07-02T00:48:06.101904008Z" level=info msg="metadata content store policy set" policy=shared Jul 2 00:48:06.105113 env[1316]: time="2024-07-02T00:48:06.105081196Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 2 00:48:06.105113 env[1316]: time="2024-07-02T00:48:06.105115037Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 2 00:48:06.105204 env[1316]: time="2024-07-02T00:48:06.105128807Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 2 00:48:06.105204 env[1316]: time="2024-07-02T00:48:06.105167781Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105204 env[1316]: time="2024-07-02T00:48:06.105182970Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105204 env[1316]: time="2024-07-02T00:48:06.105197407Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105312 env[1316]: time="2024-07-02T00:48:06.105210176Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105621 env[1316]: time="2024-07-02T00:48:06.105599996Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105659 env[1316]: time="2024-07-02T00:48:06.105625492Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105659 env[1316]: time="2024-07-02T00:48:06.105641515Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105659 env[1316]: time="2024-07-02T00:48:06.105655077Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.105752 env[1316]: time="2024-07-02T00:48:06.105668221Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 2 00:48:06.105839 env[1316]: time="2024-07-02T00:48:06.105818858Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 2 00:48:06.105930 env[1316]: time="2024-07-02T00:48:06.105907154Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 2 00:48:06.106302 env[1316]: time="2024-07-02T00:48:06.106283788Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 2 00:48:06.106335 env[1316]: time="2024-07-02T00:48:06.106322052Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106363 env[1316]: time="2024-07-02T00:48:06.106337283Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 2 00:48:06.106464 env[1316]: time="2024-07-02T00:48:06.106450448Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106497 env[1316]: time="2024-07-02T00:48:06.106468183Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106497 env[1316]: time="2024-07-02T00:48:06.106481869Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106548 env[1316]: time="2024-07-02T00:48:06.106494596Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106572 env[1316]: time="2024-07-02T00:48:06.106555185Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106572 env[1316]: time="2024-07-02T00:48:06.106568997Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106622 env[1316]: time="2024-07-02T00:48:06.106580722Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106622 env[1316]: time="2024-07-02T00:48:06.106593282Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106622 env[1316]: time="2024-07-02T00:48:06.106606009Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 2 00:48:06.106784 env[1316]: time="2024-07-02T00:48:06.106764867Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106812 env[1316]: time="2024-07-02T00:48:06.106789987Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106812 env[1316]: time="2024-07-02T00:48:06.106803298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.106853 env[1316]: time="2024-07-02T00:48:06.106815190Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 2 00:48:06.106853 env[1316]: time="2024-07-02T00:48:06.106829711Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Jul 2 00:48:06.106853 env[1316]: time="2024-07-02T00:48:06.106840936Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 2 00:48:06.106920 env[1316]: time="2024-07-02T00:48:06.106857878Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 2 00:48:06.106920 env[1316]: time="2024-07-02T00:48:06.106892178Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 2 00:48:06.107143 env[1316]: time="2024-07-02T00:48:06.107093723Z" 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}" Jul 2 00:48:06.107766 env[1316]: time="2024-07-02T00:48:06.107153018Z" level=info msg="Connect containerd service" Jul 2 00:48:06.107766 env[1316]: time="2024-07-02T00:48:06.107183145Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 2 00:48:06.107912 env[1316]: time="2024-07-02T00:48:06.107885839Z" 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" Jul 2 00:48:06.108208 env[1316]: time="2024-07-02T00:48:06.108150602Z" level=info msg="Start subscribing containerd event" Jul 2 00:48:06.108240 env[1316]: time="2024-07-02T00:48:06.108231345Z" level=info msg="Start recovering state" Jul 2 00:48:06.108264 env[1316]: time="2024-07-02T00:48:06.108252501Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 2 00:48:06.108305 env[1316]: time="2024-07-02T00:48:06.108292726Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 2 00:48:06.108353 env[1316]: time="2024-07-02T00:48:06.108341214Z" level=info msg="containerd successfully booted in 0.060376s" Jul 2 00:48:06.108450 systemd[1]: Started containerd.service. Jul 2 00:48:06.109380 env[1316]: time="2024-07-02T00:48:06.108299361Z" level=info msg="Start event monitor" Jul 2 00:48:06.109424 env[1316]: time="2024-07-02T00:48:06.109386910Z" level=info msg="Start snapshots syncer" Jul 2 00:48:06.109424 env[1316]: time="2024-07-02T00:48:06.109398510Z" level=info msg="Start cni network conf syncer for default" Jul 2 00:48:06.109424 env[1316]: time="2024-07-02T00:48:06.109406981Z" level=info msg="Start streaming server" Jul 2 00:48:06.113552 locksmithd[1349]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 2 00:48:06.492977 systemd-networkd[1103]: eth0: Gained IPv6LL Jul 2 00:48:06.494818 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 2 00:48:06.495860 systemd[1]: Reached target network-online.target. Jul 2 00:48:06.498602 systemd[1]: Starting kubelet.service... Jul 2 00:48:06.862889 sshd_keygen[1312]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 2 00:48:06.882245 systemd[1]: Finished sshd-keygen.service. Jul 2 00:48:06.884593 systemd[1]: Starting issuegen.service... Jul 2 00:48:06.889511 systemd[1]: issuegen.service: Deactivated successfully. Jul 2 00:48:06.889742 systemd[1]: Finished issuegen.service. Jul 2 00:48:06.891799 systemd[1]: Starting systemd-user-sessions.service... Jul 2 00:48:06.897501 systemd[1]: Finished systemd-user-sessions.service. Jul 2 00:48:06.899665 systemd[1]: Started getty@tty1.service. Jul 2 00:48:06.901626 systemd[1]: Started serial-getty@ttyAMA0.service. Jul 2 00:48:06.902843 systemd[1]: Reached target getty.target. Jul 2 00:48:07.090353 systemd[1]: Started kubelet.service. Jul 2 00:48:07.091527 systemd[1]: Reached target multi-user.target. Jul 2 00:48:07.093560 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 2 00:48:07.099744 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 2 00:48:07.099971 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 2 00:48:07.100878 systemd[1]: Startup finished in 4.574s (kernel) + 4.512s (userspace) = 9.087s. Jul 2 00:48:07.648288 kubelet[1389]: E0702 00:48:07.648200 1389 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 2 00:48:07.650738 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 2 00:48:07.650893 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 2 00:48:11.092178 systemd[1]: Created slice system-sshd.slice. Jul 2 00:48:11.093364 systemd[1]: Started sshd@0-10.0.0.69:22-10.0.0.1:40010.service. Jul 2 00:48:11.145114 sshd[1401]: Accepted publickey for core from 10.0.0.1 port 40010 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:48:11.147342 sshd[1401]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:48:11.158744 systemd-logind[1304]: New session 1 of user core. Jul 2 00:48:11.159626 systemd[1]: Created slice user-500.slice. Jul 2 00:48:11.160747 systemd[1]: Starting user-runtime-dir@500.service... Jul 2 00:48:11.170504 systemd[1]: Finished user-runtime-dir@500.service. Jul 2 00:48:11.171899 systemd[1]: Starting user@500.service... Jul 2 00:48:11.175194 (systemd)[1406]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:48:11.238877 systemd[1406]: Queued start job for default target default.target. Jul 2 00:48:11.239115 systemd[1406]: Reached target paths.target. Jul 2 00:48:11.239130 systemd[1406]: Reached target sockets.target. Jul 2 00:48:11.239141 systemd[1406]: Reached target timers.target. Jul 2 00:48:11.239163 systemd[1406]: Reached target basic.target. Jul 2 00:48:11.239205 systemd[1406]: Reached target default.target. Jul 2 00:48:11.239228 systemd[1406]: Startup finished in 58ms. Jul 2 00:48:11.239466 systemd[1]: Started user@500.service. Jul 2 00:48:11.240446 systemd[1]: Started session-1.scope. Jul 2 00:48:11.294667 systemd[1]: Started sshd@1-10.0.0.69:22-10.0.0.1:40022.service. Jul 2 00:48:11.336501 sshd[1415]: Accepted publickey for core from 10.0.0.1 port 40022 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:48:11.337848 sshd[1415]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:48:11.341539 systemd-logind[1304]: New session 2 of user core. Jul 2 00:48:11.342903 systemd[1]: Started session-2.scope. Jul 2 00:48:11.398859 sshd[1415]: pam_unix(sshd:session): session closed for user core Jul 2 00:48:11.401184 systemd[1]: Started sshd@2-10.0.0.69:22-10.0.0.1:40038.service. Jul 2 00:48:11.401624 systemd[1]: sshd@1-10.0.0.69:22-10.0.0.1:40022.service: Deactivated successfully. Jul 2 00:48:11.402667 systemd-logind[1304]: Session 2 logged out. Waiting for processes to exit. Jul 2 00:48:11.402741 systemd[1]: session-2.scope: Deactivated successfully. Jul 2 00:48:11.403594 systemd-logind[1304]: Removed session 2. Jul 2 00:48:11.444722 sshd[1420]: Accepted publickey for core from 10.0.0.1 port 40038 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:48:11.446060 sshd[1420]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:48:11.449741 systemd-logind[1304]: New session 3 of user core. Jul 2 00:48:11.450609 systemd[1]: Started session-3.scope. Jul 2 00:48:11.500944 sshd[1420]: pam_unix(sshd:session): session closed for user core Jul 2 00:48:11.503323 systemd[1]: Started sshd@3-10.0.0.69:22-10.0.0.1:40052.service. Jul 2 00:48:11.503881 systemd[1]: sshd@2-10.0.0.69:22-10.0.0.1:40038.service: Deactivated successfully. Jul 2 00:48:11.504814 systemd[1]: session-3.scope: Deactivated successfully. Jul 2 00:48:11.504821 systemd-logind[1304]: Session 3 logged out. Waiting for processes to exit. Jul 2 00:48:11.505833 systemd-logind[1304]: Removed session 3. Jul 2 00:48:11.549941 sshd[1427]: Accepted publickey for core from 10.0.0.1 port 40052 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:48:11.551668 sshd[1427]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:48:11.555456 systemd-logind[1304]: New session 4 of user core. Jul 2 00:48:11.555896 systemd[1]: Started session-4.scope. Jul 2 00:48:11.611524 sshd[1427]: pam_unix(sshd:session): session closed for user core Jul 2 00:48:11.613882 systemd[1]: Started sshd@4-10.0.0.69:22-10.0.0.1:40062.service. Jul 2 00:48:11.614726 systemd[1]: sshd@3-10.0.0.69:22-10.0.0.1:40052.service: Deactivated successfully. Jul 2 00:48:11.615549 systemd-logind[1304]: Session 4 logged out. Waiting for processes to exit. Jul 2 00:48:11.615599 systemd[1]: session-4.scope: Deactivated successfully. Jul 2 00:48:11.616384 systemd-logind[1304]: Removed session 4. Jul 2 00:48:11.656940 sshd[1434]: Accepted publickey for core from 10.0.0.1 port 40062 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:48:11.658083 sshd[1434]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:48:11.661353 systemd-logind[1304]: New session 5 of user core. Jul 2 00:48:11.662160 systemd[1]: Started session-5.scope. Jul 2 00:48:11.720137 sudo[1440]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 2 00:48:11.720359 sudo[1440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 2 00:48:11.731660 systemd[1]: Starting coreos-metadata.service... Jul 2 00:48:11.738447 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 2 00:48:11.738660 systemd[1]: Finished coreos-metadata.service. Jul 2 00:48:12.223772 systemd[1]: Stopped kubelet.service. Jul 2 00:48:12.225911 systemd[1]: Starting kubelet.service... Jul 2 00:48:12.244351 systemd[1]: Reloading. Jul 2 00:48:12.294613 /usr/lib/systemd/system-generators/torcx-generator[1512]: time="2024-07-02T00:48:12Z" 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.5 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.5 /var/lib/torcx/store]" Jul 2 00:48:12.294661 /usr/lib/systemd/system-generators/torcx-generator[1512]: time="2024-07-02T00:48:12Z" level=info msg="torcx already run" Jul 2 00:48:12.397172 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 2 00:48:12.397195 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 2 00:48:12.413112 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. Jul 2 00:48:12.476502 systemd[1]: Started kubelet.service. Jul 2 00:48:12.478041 systemd[1]: Stopping kubelet.service... Jul 2 00:48:12.478358 systemd[1]: kubelet.service: Deactivated successfully. Jul 2 00:48:12.478611 systemd[1]: Stopped kubelet.service. Jul 2 00:48:12.480266 systemd[1]: Starting kubelet.service... Jul 2 00:48:12.560524 systemd[1]: Started kubelet.service. Jul 2 00:48:12.606173 kubelet[1568]: 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. Jul 2 00:48:12.606173 kubelet[1568]: 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. Jul 2 00:48:12.606173 kubelet[1568]: 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. Jul 2 00:48:12.606558 kubelet[1568]: I0702 00:48:12.606216 1568 server.go:203] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jul 2 00:48:13.432506 kubelet[1568]: I0702 00:48:13.432462 1568 server.go:467] "Kubelet version" kubeletVersion="v1.28.7" Jul 2 00:48:13.432657 kubelet[1568]: I0702 00:48:13.432646 1568 server.go:469] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 2 00:48:13.432989 kubelet[1568]: I0702 00:48:13.432970 1568 server.go:895] "Client rotation is on, will bootstrap in background" Jul 2 00:48:13.471634 kubelet[1568]: I0702 00:48:13.471593 1568 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 2 00:48:13.491386 kubelet[1568]: W0702 00:48:13.491349 1568 machine.go:65] Cannot read vendor id correctly, set empty. Jul 2 00:48:13.492284 kubelet[1568]: I0702 00:48:13.492258 1568 server.go:725] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 2 00:48:13.492666 kubelet[1568]: I0702 00:48:13.492648 1568 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 2 00:48:13.492872 kubelet[1568]: I0702 00:48:13.492847 1568 container_manager_linux.go:270] "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,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Jul 2 00:48:13.492951 kubelet[1568]: I0702 00:48:13.492878 1568 topology_manager.go:138] "Creating topology manager with none policy" Jul 2 00:48:13.492951 kubelet[1568]: I0702 00:48:13.492888 1568 container_manager_linux.go:301] "Creating device plugin manager" Jul 2 00:48:13.493085 kubelet[1568]: I0702 00:48:13.493069 1568 state_mem.go:36] "Initialized new in-memory state store" Jul 2 00:48:13.497943 kubelet[1568]: I0702 00:48:13.497911 1568 kubelet.go:393] "Attempting to sync node with API server" Jul 2 00:48:13.497943 kubelet[1568]: I0702 00:48:13.497945 1568 kubelet.go:298] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 2 00:48:13.498131 kubelet[1568]: I0702 00:48:13.498111 1568 kubelet.go:309] "Adding apiserver pod source" Jul 2 00:48:13.498131 kubelet[1568]: I0702 00:48:13.498127 1568 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 2 00:48:13.498269 kubelet[1568]: E0702 00:48:13.498239 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:13.498311 kubelet[1568]: E0702 00:48:13.498291 1568 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:13.500024 kubelet[1568]: I0702 00:48:13.499981 1568 kuberuntime_manager.go:257] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Jul 2 00:48:13.503670 kubelet[1568]: W0702 00:48:13.503641 1568 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 2 00:48:13.504399 kubelet[1568]: I0702 00:48:13.504381 1568 server.go:1232] "Started kubelet" Jul 2 00:48:13.505255 kubelet[1568]: I0702 00:48:13.505234 1568 ratelimit.go:65] "Setting rate limiting for podresources endpoint" qps=100 burstTokens=10 Jul 2 00:48:13.505633 kubelet[1568]: I0702 00:48:13.505602 1568 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 2 00:48:13.505703 kubelet[1568]: I0702 00:48:13.505681 1568 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Jul 2 00:48:13.506524 kubelet[1568]: I0702 00:48:13.506493 1568 server.go:462] "Adding debug handlers to kubelet server" Jul 2 00:48:13.517878 kubelet[1568]: E0702 00:48:13.517004 1568 cri_stats_provider.go:448] "Failed to get the info of the filesystem with mountpoint" err="unable to find data in memory cache" mountpoint="/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs" Jul 2 00:48:13.517878 kubelet[1568]: W0702 00:48:13.517022 1568 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes "10.0.0.69" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 2 00:48:13.517878 kubelet[1568]: E0702 00:48:13.517031 1568 kubelet.go:1431] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 2 00:48:13.517878 kubelet[1568]: E0702 00:48:13.517047 1568 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.69" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 2 00:48:13.518025 kubelet[1568]: W0702 00:48:13.517977 1568 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Jul 2 00:48:13.518025 kubelet[1568]: E0702 00:48:13.517999 1568 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Jul 2 00:48:13.518682 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Jul 2 00:48:13.519443 kubelet[1568]: I0702 00:48:13.519336 1568 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 2 00:48:13.519504 kubelet[1568]: I0702 00:48:13.519460 1568 volume_manager.go:291] "Starting Kubelet Volume Manager" Jul 2 00:48:13.519551 kubelet[1568]: I0702 00:48:13.519531 1568 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Jul 2 00:48:13.519600 kubelet[1568]: I0702 00:48:13.519585 1568 reconciler_new.go:29] "Reconciler: start to sync state" Jul 2 00:48:13.520773 kubelet[1568]: W0702 00:48:13.520737 1568 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Jul 2 00:48:13.520862 kubelet[1568]: E0702 00:48:13.520780 1568 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Jul 2 00:48:13.521259 kubelet[1568]: E0702 00:48:13.521165 1568 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.69\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Jul 2 00:48:13.524366 kubelet[1568]: E0702 00:48:13.524243 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec932a628d", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"Starting", Message:"Starting kubelet.", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 504340621, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 504340621, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.529705 kubelet[1568]: E0702 00:48:13.528634 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec93ebde73", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"InvalidDiskCapacity", Message:"invalid capacity 0 on image filesystem", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 517020787, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 517020787, time.Local), Count:1, Type:"Warning", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.554056 kubelet[1568]: I0702 00:48:13.554032 1568 cpu_manager.go:214] "Starting CPU manager" policy="none" Jul 2 00:48:13.554212 kubelet[1568]: I0702 00:48:13.554204 1568 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jul 2 00:48:13.554275 kubelet[1568]: I0702 00:48:13.554265 1568 state_mem.go:36] "Initialized new in-memory state store" Jul 2 00:48:13.555001 kubelet[1568]: E0702 00:48:13.554912 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614d964", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.69 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553260900, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553260900, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.555977 kubelet[1568]: E0702 00:48:13.555898 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614f3ed", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.69 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553267693, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553267693, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.557590 kubelet[1568]: E0702 00:48:13.557532 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec96150357", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.69 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553271639, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553271639, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.620348 kubelet[1568]: I0702 00:48:13.620316 1568 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.69" Jul 2 00:48:13.624910 kubelet[1568]: E0702 00:48:13.624815 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614d964", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.69 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553260900, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 620272802, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec9614d964" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.625474 kubelet[1568]: E0702 00:48:13.625420 1568 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.69" Jul 2 00:48:13.626023 kubelet[1568]: E0702 00:48:13.625958 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614f3ed", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.69 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553267693, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 620279148, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec9614f3ed" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.627161 kubelet[1568]: E0702 00:48:13.627098 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec96150357", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.69 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553271639, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 620282484, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec96150357" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.652193 kubelet[1568]: I0702 00:48:13.652147 1568 policy_none.go:49] "None policy: Start" Jul 2 00:48:13.653063 kubelet[1568]: I0702 00:48:13.653048 1568 memory_manager.go:169] "Starting memorymanager" policy="None" Jul 2 00:48:13.653155 kubelet[1568]: I0702 00:48:13.653145 1568 state_mem.go:35] "Initializing new in-memory state store" Jul 2 00:48:13.660464 kubelet[1568]: I0702 00:48:13.660437 1568 manager.go:471] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 2 00:48:13.661043 kubelet[1568]: I0702 00:48:13.661000 1568 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 2 00:48:13.661622 kubelet[1568]: E0702 00:48:13.661604 1568 eviction_manager.go:258] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.69\" not found" Jul 2 00:48:13.664440 kubelet[1568]: E0702 00:48:13.664008 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9c841c59", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeAllocatableEnforced", Message:"Updated Node Allocatable limit across pods", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 661215833, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 661215833, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.700364 kubelet[1568]: I0702 00:48:13.700256 1568 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 2 00:48:13.701605 kubelet[1568]: I0702 00:48:13.701559 1568 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 2 00:48:13.701605 kubelet[1568]: I0702 00:48:13.701584 1568 status_manager.go:217] "Starting to sync pod status with apiserver" Jul 2 00:48:13.701605 kubelet[1568]: I0702 00:48:13.701608 1568 kubelet.go:2303] "Starting kubelet main sync loop" Jul 2 00:48:13.701808 kubelet[1568]: E0702 00:48:13.701660 1568 kubelet.go:2327] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Jul 2 00:48:13.703624 kubelet[1568]: W0702 00:48:13.703589 1568 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Jul 2 00:48:13.703624 kubelet[1568]: E0702 00:48:13.703622 1568 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.RuntimeClass: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Jul 2 00:48:13.723079 kubelet[1568]: E0702 00:48:13.723030 1568 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.69\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="400ms" Jul 2 00:48:13.826449 kubelet[1568]: I0702 00:48:13.826404 1568 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.69" Jul 2 00:48:13.828117 kubelet[1568]: E0702 00:48:13.828020 1568 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.69" Jul 2 00:48:13.828231 kubelet[1568]: E0702 00:48:13.828151 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614d964", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.69 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553260900, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 826353613, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec9614d964" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.832954 kubelet[1568]: E0702 00:48:13.832878 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614f3ed", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.69 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553267693, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 826367443, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec9614f3ed" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:13.834021 kubelet[1568]: E0702 00:48:13.833944 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec96150357", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.69 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553271639, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 826374399, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec96150357" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:14.124868 kubelet[1568]: E0702 00:48:14.124765 1568 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.69\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="800ms" Jul 2 00:48:14.229376 kubelet[1568]: I0702 00:48:14.229352 1568 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.69" Jul 2 00:48:14.230870 kubelet[1568]: E0702 00:48:14.230839 1568 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.69" Jul 2 00:48:14.230945 kubelet[1568]: E0702 00:48:14.230838 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614d964", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 10.0.0.69 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553260900, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 14, 229307119, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec9614d964" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:14.231981 kubelet[1568]: E0702 00:48:14.231917 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec9614f3ed", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 10.0.0.69 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553267693, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 14, 229318363, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec9614f3ed" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:14.232959 kubelet[1568]: E0702 00:48:14.232899 1568 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"10.0.0.69.17de3eec96150357", 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.69", UID:"10.0.0.69", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 10.0.0.69 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"10.0.0.69"}, FirstTimestamp:time.Date(2024, time.July, 2, 0, 48, 13, 553271639, time.Local), LastTimestamp:time.Date(2024, time.July, 2, 0, 48, 14, 229321529, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"10.0.0.69"}': 'events "10.0.0.69.17de3eec96150357" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Jul 2 00:48:14.436960 kubelet[1568]: I0702 00:48:14.436912 1568 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 2 00:48:14.499159 kubelet[1568]: E0702 00:48:14.499122 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:14.871001 kubelet[1568]: E0702 00:48:14.870899 1568 csi_plugin.go:295] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.69" not found Jul 2 00:48:14.933255 kubelet[1568]: E0702 00:48:14.933214 1568 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.69\" not found" node="10.0.0.69" Jul 2 00:48:15.032707 kubelet[1568]: I0702 00:48:15.032673 1568 kubelet_node_status.go:70] "Attempting to register node" node="10.0.0.69" Jul 2 00:48:15.040218 kubelet[1568]: I0702 00:48:15.040179 1568 kubelet_node_status.go:73] "Successfully registered node" node="10.0.0.69" Jul 2 00:48:15.076116 sudo[1440]: pam_unix(sudo:session): session closed for user root Jul 2 00:48:15.078271 sshd[1434]: pam_unix(sshd:session): session closed for user core Jul 2 00:48:15.081055 systemd[1]: sshd@4-10.0.0.69:22-10.0.0.1:40062.service: Deactivated successfully. Jul 2 00:48:15.082398 systemd[1]: session-5.scope: Deactivated successfully. Jul 2 00:48:15.082419 systemd-logind[1304]: Session 5 logged out. Waiting for processes to exit. Jul 2 00:48:15.083482 systemd-logind[1304]: Removed session 5. Jul 2 00:48:15.152326 kubelet[1568]: I0702 00:48:15.152292 1568 kuberuntime_manager.go:1528] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 2 00:48:15.152658 env[1316]: time="2024-07-02T00:48:15.152608567Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 2 00:48:15.152955 kubelet[1568]: I0702 00:48:15.152848 1568 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 2 00:48:15.500247 kubelet[1568]: I0702 00:48:15.500015 1568 apiserver.go:52] "Watching apiserver" Jul 2 00:48:15.500466 kubelet[1568]: E0702 00:48:15.500059 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:15.507976 kubelet[1568]: I0702 00:48:15.507938 1568 topology_manager.go:215] "Topology Admit Handler" podUID="19d374a3-0193-4eca-adaf-678b8ae8f209" podNamespace="kube-system" podName="kube-proxy-9fhb2" Jul 2 00:48:15.508068 kubelet[1568]: I0702 00:48:15.508041 1568 topology_manager.go:215] "Topology Admit Handler" podUID="1ab478fe-0449-408f-81a4-f67707b6665e" podNamespace="kube-flannel" podName="kube-flannel-ds-5tc86" Jul 2 00:48:15.519873 kubelet[1568]: I0702 00:48:15.519841 1568 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Jul 2 00:48:15.531619 kubelet[1568]: I0702 00:48:15.531590 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/1ab478fe-0449-408f-81a4-f67707b6665e-cni\") pod \"kube-flannel-ds-5tc86\" (UID: \"1ab478fe-0449-408f-81a4-f67707b6665e\") " pod="kube-flannel/kube-flannel-ds-5tc86" Jul 2 00:48:15.531826 kubelet[1568]: I0702 00:48:15.531809 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/1ab478fe-0449-408f-81a4-f67707b6665e-flannel-cfg\") pod \"kube-flannel-ds-5tc86\" (UID: \"1ab478fe-0449-408f-81a4-f67707b6665e\") " pod="kube-flannel/kube-flannel-ds-5tc86" Jul 2 00:48:15.531912 kubelet[1568]: I0702 00:48:15.531902 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/19d374a3-0193-4eca-adaf-678b8ae8f209-lib-modules\") pod \"kube-proxy-9fhb2\" (UID: \"19d374a3-0193-4eca-adaf-678b8ae8f209\") " pod="kube-system/kube-proxy-9fhb2" Jul 2 00:48:15.531989 kubelet[1568]: I0702 00:48:15.531978 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nwplx\" (UniqueName: \"kubernetes.io/projected/19d374a3-0193-4eca-adaf-678b8ae8f209-kube-api-access-nwplx\") pod \"kube-proxy-9fhb2\" (UID: \"19d374a3-0193-4eca-adaf-678b8ae8f209\") " pod="kube-system/kube-proxy-9fhb2" Jul 2 00:48:15.532097 kubelet[1568]: I0702 00:48:15.532085 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/1ab478fe-0449-408f-81a4-f67707b6665e-run\") pod \"kube-flannel-ds-5tc86\" (UID: \"1ab478fe-0449-408f-81a4-f67707b6665e\") " pod="kube-flannel/kube-flannel-ds-5tc86" Jul 2 00:48:15.532180 kubelet[1568]: I0702 00:48:15.532169 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/1ab478fe-0449-408f-81a4-f67707b6665e-cni-plugin\") pod \"kube-flannel-ds-5tc86\" (UID: \"1ab478fe-0449-408f-81a4-f67707b6665e\") " pod="kube-flannel/kube-flannel-ds-5tc86" Jul 2 00:48:15.532288 kubelet[1568]: I0702 00:48:15.532276 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/1ab478fe-0449-408f-81a4-f67707b6665e-xtables-lock\") pod \"kube-flannel-ds-5tc86\" (UID: \"1ab478fe-0449-408f-81a4-f67707b6665e\") " pod="kube-flannel/kube-flannel-ds-5tc86" Jul 2 00:48:15.532388 kubelet[1568]: I0702 00:48:15.532377 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bdskp\" (UniqueName: \"kubernetes.io/projected/1ab478fe-0449-408f-81a4-f67707b6665e-kube-api-access-bdskp\") pod \"kube-flannel-ds-5tc86\" (UID: \"1ab478fe-0449-408f-81a4-f67707b6665e\") " pod="kube-flannel/kube-flannel-ds-5tc86" Jul 2 00:48:15.532469 kubelet[1568]: I0702 00:48:15.532458 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/19d374a3-0193-4eca-adaf-678b8ae8f209-kube-proxy\") pod \"kube-proxy-9fhb2\" (UID: \"19d374a3-0193-4eca-adaf-678b8ae8f209\") " pod="kube-system/kube-proxy-9fhb2" Jul 2 00:48:15.532558 kubelet[1568]: I0702 00:48:15.532547 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/19d374a3-0193-4eca-adaf-678b8ae8f209-xtables-lock\") pod \"kube-proxy-9fhb2\" (UID: \"19d374a3-0193-4eca-adaf-678b8ae8f209\") " pod="kube-system/kube-proxy-9fhb2" Jul 2 00:48:15.819257 kubelet[1568]: E0702 00:48:15.818769 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:15.819257 kubelet[1568]: E0702 00:48:15.818804 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:15.819692 env[1316]: time="2024-07-02T00:48:15.819631759Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9fhb2,Uid:19d374a3-0193-4eca-adaf-678b8ae8f209,Namespace:kube-system,Attempt:0,}" Jul 2 00:48:15.819751 env[1316]: time="2024-07-02T00:48:15.819702385Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5tc86,Uid:1ab478fe-0449-408f-81a4-f67707b6665e,Namespace:kube-flannel,Attempt:0,}" Jul 2 00:48:16.328827 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount235178006.mount: Deactivated successfully. Jul 2 00:48:16.335089 env[1316]: time="2024-07-02T00:48:16.335042249Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.337452 env[1316]: time="2024-07-02T00:48:16.337413531Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.339825 env[1316]: time="2024-07-02T00:48:16.339788778Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.342125 env[1316]: time="2024-07-02T00:48:16.342086796Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.343022 env[1316]: time="2024-07-02T00:48:16.342991359Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.345487 env[1316]: time="2024-07-02T00:48:16.345444561Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.346253 env[1316]: time="2024-07-02T00:48:16.346219589Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.348681 env[1316]: time="2024-07-02T00:48:16.348653009Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:16.382928 env[1316]: time="2024-07-02T00:48:16.382784118Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:48:16.382928 env[1316]: time="2024-07-02T00:48:16.382823278Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:48:16.382928 env[1316]: time="2024-07-02T00:48:16.382834201Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:48:16.383216 env[1316]: time="2024-07-02T00:48:16.383164471Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/bc2ba5a6ac9375dfe09b217d1c44714c9e40478e33c34f0f6d6ada55a175bfd4 pid=1628 runtime=io.containerd.runc.v2 Jul 2 00:48:16.383741 env[1316]: time="2024-07-02T00:48:16.383661779Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:48:16.383741 env[1316]: time="2024-07-02T00:48:16.383716878Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:48:16.383741 env[1316]: time="2024-07-02T00:48:16.383727881Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:48:16.383888 env[1316]: time="2024-07-02T00:48:16.383859318Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79 pid=1629 runtime=io.containerd.runc.v2 Jul 2 00:48:16.466339 env[1316]: time="2024-07-02T00:48:16.466289031Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9fhb2,Uid:19d374a3-0193-4eca-adaf-678b8ae8f209,Namespace:kube-system,Attempt:0,} returns sandbox id \"bc2ba5a6ac9375dfe09b217d1c44714c9e40478e33c34f0f6d6ada55a175bfd4\"" Jul 2 00:48:16.467053 env[1316]: time="2024-07-02T00:48:16.467020530Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5tc86,Uid:1ab478fe-0449-408f-81a4-f67707b6665e,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79\"" Jul 2 00:48:16.467899 kubelet[1568]: E0702 00:48:16.467421 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:16.468904 env[1316]: time="2024-07-02T00:48:16.468775568Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.11\"" Jul 2 00:48:16.469242 kubelet[1568]: E0702 00:48:16.469205 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:16.500836 kubelet[1568]: E0702 00:48:16.500784 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:17.452405 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount737516193.mount: Deactivated successfully. Jul 2 00:48:17.501877 kubelet[1568]: E0702 00:48:17.501802 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:17.864614 env[1316]: time="2024-07-02T00:48:17.864365614Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.28.11,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:17.865669 env[1316]: time="2024-07-02T00:48:17.865643434Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:e195d3cf134bc9d64104f5e82e95fce811d55b1cdc9cb26fb8f52c8d107d1661,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:17.867060 env[1316]: time="2024-07-02T00:48:17.867023743Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.28.11,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:17.868377 env[1316]: time="2024-07-02T00:48:17.868350847Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:ae4b671d4cfc23dd75030bb4490207cd939b3b11a799bcb4119698cd712eb5b4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:17.868772 env[1316]: time="2024-07-02T00:48:17.868745210Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.11\" returns image reference \"sha256:e195d3cf134bc9d64104f5e82e95fce811d55b1cdc9cb26fb8f52c8d107d1661\"" Jul 2 00:48:17.870956 env[1316]: time="2024-07-02T00:48:17.870903538Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 2 00:48:17.871056 env[1316]: time="2024-07-02T00:48:17.870920828Z" level=info msg="CreateContainer within sandbox \"bc2ba5a6ac9375dfe09b217d1c44714c9e40478e33c34f0f6d6ada55a175bfd4\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 2 00:48:17.879469 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1804102803.mount: Deactivated successfully. Jul 2 00:48:17.881925 env[1316]: time="2024-07-02T00:48:17.881885450Z" level=info msg="CreateContainer within sandbox \"bc2ba5a6ac9375dfe09b217d1c44714c9e40478e33c34f0f6d6ada55a175bfd4\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"33ca06f9de8957744162e4f1daae6d5a86bc3822f698c7443bc0dfe5a52715fe\"" Jul 2 00:48:17.882465 env[1316]: time="2024-07-02T00:48:17.882440353Z" level=info msg="StartContainer for \"33ca06f9de8957744162e4f1daae6d5a86bc3822f698c7443bc0dfe5a52715fe\"" Jul 2 00:48:17.934497 env[1316]: time="2024-07-02T00:48:17.934454371Z" level=info msg="StartContainer for \"33ca06f9de8957744162e4f1daae6d5a86bc3822f698c7443bc0dfe5a52715fe\" returns successfully" Jul 2 00:48:18.503020 kubelet[1568]: E0702 00:48:18.502965 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:18.713105 kubelet[1568]: E0702 00:48:18.713058 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:18.724155 kubelet[1568]: I0702 00:48:18.724109 1568 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-9fhb2" podStartSLOduration=2.323020987 podCreationTimestamp="2024-07-02 00:48:15 +0000 UTC" firstStartedPulling="2024-07-02 00:48:16.468326401 +0000 UTC m=+3.903303954" lastFinishedPulling="2024-07-02 00:48:17.869367415 +0000 UTC m=+5.304344968" observedRunningTime="2024-07-02 00:48:18.723741477 +0000 UTC m=+6.158719029" watchObservedRunningTime="2024-07-02 00:48:18.724062001 +0000 UTC m=+6.159039553" Jul 2 00:48:19.051414 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3599339864.mount: Deactivated successfully. Jul 2 00:48:19.090834 env[1316]: time="2024-07-02T00:48:19.090776485Z" 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:[],}" Jul 2 00:48:19.092759 env[1316]: time="2024-07-02T00:48:19.092729036Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:19.094338 env[1316]: time="2024-07-02T00:48:19.094308771Z" 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:[],}" Jul 2 00:48:19.095838 env[1316]: time="2024-07-02T00:48:19.095811241Z" 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:[],}" Jul 2 00:48:19.096331 env[1316]: time="2024-07-02T00:48:19.096307551Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 2 00:48:19.098021 env[1316]: time="2024-07-02T00:48:19.097986757Z" level=info msg="CreateContainer within sandbox \"7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 2 00:48:19.107289 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount141404875.mount: Deactivated successfully. Jul 2 00:48:19.111115 env[1316]: time="2024-07-02T00:48:19.111068788Z" level=info msg="CreateContainer within sandbox \"7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"128f96a865edef7e6b652b29e23edb0a5b10564b6fc1449d7b8652138c86f891\"" Jul 2 00:48:19.111823 env[1316]: time="2024-07-02T00:48:19.111793019Z" level=info msg="StartContainer for \"128f96a865edef7e6b652b29e23edb0a5b10564b6fc1449d7b8652138c86f891\"" Jul 2 00:48:19.162017 env[1316]: time="2024-07-02T00:48:19.161962999Z" level=info msg="StartContainer for \"128f96a865edef7e6b652b29e23edb0a5b10564b6fc1449d7b8652138c86f891\" returns successfully" Jul 2 00:48:19.332768 env[1316]: time="2024-07-02T00:48:19.332407699Z" level=info msg="shim disconnected" id=128f96a865edef7e6b652b29e23edb0a5b10564b6fc1449d7b8652138c86f891 Jul 2 00:48:19.332768 env[1316]: time="2024-07-02T00:48:19.332457233Z" level=warning msg="cleaning up after shim disconnected" id=128f96a865edef7e6b652b29e23edb0a5b10564b6fc1449d7b8652138c86f891 namespace=k8s.io Jul 2 00:48:19.332768 env[1316]: time="2024-07-02T00:48:19.332466624Z" level=info msg="cleaning up dead shim" Jul 2 00:48:19.344514 env[1316]: time="2024-07-02T00:48:19.344064644Z" level=warning msg="cleanup warnings time=\"2024-07-02T00:48:19Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1897 runtime=io.containerd.runc.v2\n" Jul 2 00:48:19.503197 kubelet[1568]: E0702 00:48:19.503114 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:19.717112 kubelet[1568]: E0702 00:48:19.716186 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:19.717112 kubelet[1568]: E0702 00:48:19.716727 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:19.718128 env[1316]: time="2024-07-02T00:48:19.717907035Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 2 00:48:20.503748 kubelet[1568]: E0702 00:48:20.503673 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:20.832265 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2520253636.mount: Deactivated successfully. Jul 2 00:48:21.504492 kubelet[1568]: E0702 00:48:21.504446 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:21.728605 env[1316]: time="2024-07-02T00:48:21.728375460Z" 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:[],}" Jul 2 00:48:21.732021 env[1316]: time="2024-07-02T00:48:21.731969525Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:21.733814 env[1316]: time="2024-07-02T00:48:21.733729644Z" 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:[],}" Jul 2 00:48:21.735324 env[1316]: time="2024-07-02T00:48:21.735284936Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:21.736064 env[1316]: time="2024-07-02T00:48:21.736017782Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 2 00:48:21.737850 env[1316]: time="2024-07-02T00:48:21.737689348Z" level=info msg="CreateContainer within sandbox \"7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 2 00:48:21.746993 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3504039361.mount: Deactivated successfully. Jul 2 00:48:21.752193 env[1316]: time="2024-07-02T00:48:21.752146839Z" level=info msg="CreateContainer within sandbox \"7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a6098f79962f85d67430e9086ae40bdc4074c9781c56603a52b16dffc21a3486\"" Jul 2 00:48:21.753288 env[1316]: time="2024-07-02T00:48:21.753260734Z" level=info msg="StartContainer for \"a6098f79962f85d67430e9086ae40bdc4074c9781c56603a52b16dffc21a3486\"" Jul 2 00:48:21.815564 env[1316]: time="2024-07-02T00:48:21.815468466Z" level=info msg="StartContainer for \"a6098f79962f85d67430e9086ae40bdc4074c9781c56603a52b16dffc21a3486\" returns successfully" Jul 2 00:48:21.903448 kubelet[1568]: I0702 00:48:21.903397 1568 kubelet_node_status.go:493] "Fast updating node status as it just became ready" Jul 2 00:48:21.943800 env[1316]: time="2024-07-02T00:48:21.943682662Z" level=info msg="shim disconnected" id=a6098f79962f85d67430e9086ae40bdc4074c9781c56603a52b16dffc21a3486 Jul 2 00:48:21.944003 env[1316]: time="2024-07-02T00:48:21.943981232Z" level=warning msg="cleaning up after shim disconnected" id=a6098f79962f85d67430e9086ae40bdc4074c9781c56603a52b16dffc21a3486 namespace=k8s.io Jul 2 00:48:21.944063 env[1316]: time="2024-07-02T00:48:21.944050674Z" level=info msg="cleaning up dead shim" Jul 2 00:48:21.950301 env[1316]: time="2024-07-02T00:48:21.950263514Z" level=warning msg="cleanup warnings time=\"2024-07-02T00:48:21Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1964 runtime=io.containerd.runc.v2\n" Jul 2 00:48:22.505644 kubelet[1568]: E0702 00:48:22.505594 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:22.723045 kubelet[1568]: E0702 00:48:22.723000 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:22.724943 env[1316]: time="2024-07-02T00:48:22.724901423Z" level=info msg="CreateContainer within sandbox \"7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 2 00:48:22.740663 env[1316]: time="2024-07-02T00:48:22.740583429Z" level=info msg="CreateContainer within sandbox \"7417ee6240dbf264c0cd020dcda810d99f06adfc4ef1c45dded4eae26150aa79\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"99a3758ac3c2112d147b3aed1220c6c0142b7b6270de8a735d8a66678aea1d25\"" Jul 2 00:48:22.742152 env[1316]: time="2024-07-02T00:48:22.741263077Z" level=info msg="StartContainer for \"99a3758ac3c2112d147b3aed1220c6c0142b7b6270de8a735d8a66678aea1d25\"" Jul 2 00:48:22.745047 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a6098f79962f85d67430e9086ae40bdc4074c9781c56603a52b16dffc21a3486-rootfs.mount: Deactivated successfully. Jul 2 00:48:22.803855 env[1316]: time="2024-07-02T00:48:22.803605063Z" level=info msg="StartContainer for \"99a3758ac3c2112d147b3aed1220c6c0142b7b6270de8a735d8a66678aea1d25\" returns successfully" Jul 2 00:48:23.506306 kubelet[1568]: E0702 00:48:23.506250 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:23.727886 kubelet[1568]: E0702 00:48:23.727859 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:23.744598 kubelet[1568]: I0702 00:48:23.744529 1568 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-5tc86" podStartSLOduration=3.478027264 podCreationTimestamp="2024-07-02 00:48:15 +0000 UTC" firstStartedPulling="2024-07-02 00:48:16.469767634 +0000 UTC m=+3.904745146" lastFinishedPulling="2024-07-02 00:48:21.736218948 +0000 UTC m=+9.171196500" observedRunningTime="2024-07-02 00:48:23.743719807 +0000 UTC m=+11.178697359" watchObservedRunningTime="2024-07-02 00:48:23.744478618 +0000 UTC m=+11.179456170" Jul 2 00:48:23.883786 systemd-networkd[1103]: flannel.1: Link UP Jul 2 00:48:23.883790 systemd-networkd[1103]: flannel.1: Gained carrier Jul 2 00:48:24.506945 kubelet[1568]: E0702 00:48:24.506889 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:24.729368 kubelet[1568]: E0702 00:48:24.729335 1568 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Jul 2 00:48:25.507103 kubelet[1568]: E0702 00:48:25.507070 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:25.884797 systemd-networkd[1103]: flannel.1: Gained IPv6LL Jul 2 00:48:26.047343 kubelet[1568]: I0702 00:48:26.047270 1568 topology_manager.go:215] "Topology Admit Handler" podUID="5f76b457-a383-4767-b4ac-7128fb6b94fe" podNamespace="default" podName="nginx-deployment-6d5f899847-tpnsz" Jul 2 00:48:26.104366 kubelet[1568]: I0702 00:48:26.104301 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r79fd\" (UniqueName: \"kubernetes.io/projected/5f76b457-a383-4767-b4ac-7128fb6b94fe-kube-api-access-r79fd\") pod \"nginx-deployment-6d5f899847-tpnsz\" (UID: \"5f76b457-a383-4767-b4ac-7128fb6b94fe\") " pod="default/nginx-deployment-6d5f899847-tpnsz" Jul 2 00:48:26.350918 env[1316]: time="2024-07-02T00:48:26.350812451Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-tpnsz,Uid:5f76b457-a383-4767-b4ac-7128fb6b94fe,Namespace:default,Attempt:0,}" Jul 2 00:48:26.388462 systemd-networkd[1103]: cni0: Link UP Jul 2 00:48:26.398891 kernel: cni0: port 1(veth5c7b5ca8) entered blocking state Jul 2 00:48:26.398978 kernel: cni0: port 1(veth5c7b5ca8) entered disabled state Jul 2 00:48:26.399723 kernel: device veth5c7b5ca8 entered promiscuous mode Jul 2 00:48:26.400779 kernel: cni0: port 1(veth5c7b5ca8) entered blocking state Jul 2 00:48:26.400821 kernel: cni0: port 1(veth5c7b5ca8) entered forwarding state Jul 2 00:48:26.401772 kernel: cni0: port 1(veth5c7b5ca8) entered disabled state Jul 2 00:48:26.402149 systemd-networkd[1103]: veth5c7b5ca8: Link UP Jul 2 00:48:26.412967 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth5c7b5ca8: link becomes ready Jul 2 00:48:26.413068 kernel: cni0: port 1(veth5c7b5ca8) entered blocking state Jul 2 00:48:26.413088 kernel: cni0: port 1(veth5c7b5ca8) entered forwarding state Jul 2 00:48:26.413102 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): cni0: link becomes ready Jul 2 00:48:26.413196 systemd-networkd[1103]: veth5c7b5ca8: Gained carrier Jul 2 00:48:26.413790 systemd-networkd[1103]: cni0: Gained carrier Jul 2 00:48:26.415194 env[1316]: 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)(0x4000018928), "name":"cbr0", "type":"bridge"} Jul 2 00:48:26.415194 env[1316]: delegateAdd: netconf sent to delegate plugin: Jul 2 00:48:26.427747 env[1316]: {"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-07-02T00:48:26.427653587Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:48:26.427861 env[1316]: time="2024-07-02T00:48:26.427759543Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:48:26.427861 env[1316]: time="2024-07-02T00:48:26.427787185Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:48:26.428004 env[1316]: time="2024-07-02T00:48:26.427968164Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/4534c670e73d294f1fc7faad824b1ee1ffdede494e8e3b2417d406dea2741d9f pid=2138 runtime=io.containerd.runc.v2 Jul 2 00:48:26.461170 systemd-resolved[1241]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 2 00:48:26.479075 env[1316]: time="2024-07-02T00:48:26.479025093Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-tpnsz,Uid:5f76b457-a383-4767-b4ac-7128fb6b94fe,Namespace:default,Attempt:0,} returns sandbox id \"4534c670e73d294f1fc7faad824b1ee1ffdede494e8e3b2417d406dea2741d9f\"" Jul 2 00:48:26.480888 env[1316]: time="2024-07-02T00:48:26.480857956Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 2 00:48:26.507985 kubelet[1568]: E0702 00:48:26.507953 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:27.484818 systemd-networkd[1103]: cni0: Gained IPv6LL Jul 2 00:48:27.508276 kubelet[1568]: E0702 00:48:27.508233 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:27.932847 systemd-networkd[1103]: veth5c7b5ca8: Gained IPv6LL Jul 2 00:48:28.351046 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1847859434.mount: Deactivated successfully. Jul 2 00:48:28.509094 kubelet[1568]: E0702 00:48:28.509057 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:29.509656 kubelet[1568]: E0702 00:48:29.509619 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:29.555643 env[1316]: time="2024-07-02T00:48:29.555598900Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:29.557203 env[1316]: time="2024-07-02T00:48:29.557174730Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:29.559390 env[1316]: time="2024-07-02T00:48:29.559359977Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:29.560954 env[1316]: time="2024-07-02T00:48:29.560923382Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:bf28ef5d86aca0cd30a8ef19032ccadc1eada35dc9f14f42f3ccb73974f013de,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:29.562421 env[1316]: time="2024-07-02T00:48:29.562378971Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653\"" Jul 2 00:48:29.564532 env[1316]: time="2024-07-02T00:48:29.563817245Z" level=info msg="CreateContainer within sandbox \"4534c670e73d294f1fc7faad824b1ee1ffdede494e8e3b2417d406dea2741d9f\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 2 00:48:29.574283 env[1316]: time="2024-07-02T00:48:29.574238192Z" level=info msg="CreateContainer within sandbox \"4534c670e73d294f1fc7faad824b1ee1ffdede494e8e3b2417d406dea2741d9f\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"82cb9757add228e8cf842003be0c73b2a9d27231ecbcb93b64b20998ca7c7d1f\"" Jul 2 00:48:29.574722 env[1316]: time="2024-07-02T00:48:29.574645847Z" level=info msg="StartContainer for \"82cb9757add228e8cf842003be0c73b2a9d27231ecbcb93b64b20998ca7c7d1f\"" Jul 2 00:48:29.622237 env[1316]: time="2024-07-02T00:48:29.622193916Z" level=info msg="StartContainer for \"82cb9757add228e8cf842003be0c73b2a9d27231ecbcb93b64b20998ca7c7d1f\" returns successfully" Jul 2 00:48:29.749120 kubelet[1568]: I0702 00:48:29.748339 1568 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-tpnsz" podStartSLOduration=0.665971428 podCreationTimestamp="2024-07-02 00:48:26 +0000 UTC" firstStartedPulling="2024-07-02 00:48:26.480274256 +0000 UTC m=+13.915251808" lastFinishedPulling="2024-07-02 00:48:29.562609111 +0000 UTC m=+16.997586663" observedRunningTime="2024-07-02 00:48:29.748093778 +0000 UTC m=+17.183071330" watchObservedRunningTime="2024-07-02 00:48:29.748306283 +0000 UTC m=+17.183283835" Jul 2 00:48:30.510538 kubelet[1568]: E0702 00:48:30.510484 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:31.511046 kubelet[1568]: E0702 00:48:31.510998 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:32.427551 kubelet[1568]: I0702 00:48:32.427502 1568 topology_manager.go:215] "Topology Admit Handler" podUID="1e1cb890-7453-4847-9274-12e85a624306" podNamespace="default" podName="nfs-server-provisioner-0" Jul 2 00:48:32.448363 kubelet[1568]: I0702 00:48:32.448323 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-74bls\" (UniqueName: \"kubernetes.io/projected/1e1cb890-7453-4847-9274-12e85a624306-kube-api-access-74bls\") pod \"nfs-server-provisioner-0\" (UID: \"1e1cb890-7453-4847-9274-12e85a624306\") " pod="default/nfs-server-provisioner-0" Jul 2 00:48:32.448363 kubelet[1568]: I0702 00:48:32.448373 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1e1cb890-7453-4847-9274-12e85a624306-data\") pod \"nfs-server-provisioner-0\" (UID: \"1e1cb890-7453-4847-9274-12e85a624306\") " pod="default/nfs-server-provisioner-0" Jul 2 00:48:32.511779 kubelet[1568]: E0702 00:48:32.511744 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:32.733309 env[1316]: time="2024-07-02T00:48:32.733196076Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1e1cb890-7453-4847-9274-12e85a624306,Namespace:default,Attempt:0,}" Jul 2 00:48:32.750324 kernel: cni0: port 2(veth14b58658) entered blocking state Jul 2 00:48:32.750435 kernel: cni0: port 2(veth14b58658) entered disabled state Jul 2 00:48:32.750115 systemd-networkd[1103]: veth14b58658: Link UP Jul 2 00:48:32.752076 kernel: device veth14b58658 entered promiscuous mode Jul 2 00:48:32.758747 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 2 00:48:32.758815 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth14b58658: link becomes ready Jul 2 00:48:32.758835 kernel: cni0: port 2(veth14b58658) entered blocking state Jul 2 00:48:32.758853 kernel: cni0: port 2(veth14b58658) entered forwarding state Jul 2 00:48:32.758540 systemd-networkd[1103]: veth14b58658: Gained carrier Jul 2 00:48:32.763425 env[1316]: 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)(0x400001e928), "name":"cbr0", "type":"bridge"} Jul 2 00:48:32.763425 env[1316]: delegateAdd: netconf sent to delegate plugin: Jul 2 00:48:32.772792 env[1316]: {"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-07-02T00:48:32.772723073Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:48:32.772981 env[1316]: time="2024-07-02T00:48:32.772768374Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:48:32.772981 env[1316]: time="2024-07-02T00:48:32.772779068Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:48:32.773058 env[1316]: time="2024-07-02T00:48:32.773001006Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/71a7574b286888897c2f8dff5f55a01d85adbc6a06d71e0233c65067729a7aa2 pid=2300 runtime=io.containerd.runc.v2 Jul 2 00:48:32.810674 systemd-resolved[1241]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 2 00:48:32.827540 env[1316]: time="2024-07-02T00:48:32.827495056Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1e1cb890-7453-4847-9274-12e85a624306,Namespace:default,Attempt:0,} returns sandbox id \"71a7574b286888897c2f8dff5f55a01d85adbc6a06d71e0233c65067729a7aa2\"" Jul 2 00:48:32.829315 env[1316]: time="2024-07-02T00:48:32.829284854Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 2 00:48:33.499064 kubelet[1568]: E0702 00:48:33.499014 1568 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:33.512264 kubelet[1568]: E0702 00:48:33.512219 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:34.140811 systemd-networkd[1103]: veth14b58658: Gained IPv6LL Jul 2 00:48:34.512559 kubelet[1568]: E0702 00:48:34.512325 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:34.848647 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2474065822.mount: Deactivated successfully. Jul 2 00:48:35.513128 kubelet[1568]: E0702 00:48:35.513076 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:36.513585 kubelet[1568]: E0702 00:48:36.513535 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:36.651481 env[1316]: time="2024-07-02T00:48:36.651420727Z" 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:[],}" Jul 2 00:48:36.653209 env[1316]: time="2024-07-02T00:48:36.653167139Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:36.658320 env[1316]: time="2024-07-02T00:48:36.658266426Z" 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:[],}" Jul 2 00:48:36.660018 env[1316]: time="2024-07-02T00:48:36.659978091Z" 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:[],}" Jul 2 00:48:36.660879 env[1316]: time="2024-07-02T00:48:36.660848855Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 2 00:48:36.663442 env[1316]: time="2024-07-02T00:48:36.663392935Z" level=info msg="CreateContainer within sandbox \"71a7574b286888897c2f8dff5f55a01d85adbc6a06d71e0233c65067729a7aa2\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 2 00:48:36.677309 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3938083823.mount: Deactivated successfully. Jul 2 00:48:36.682562 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount580211746.mount: Deactivated successfully. Jul 2 00:48:36.685554 env[1316]: time="2024-07-02T00:48:36.685507672Z" level=info msg="CreateContainer within sandbox \"71a7574b286888897c2f8dff5f55a01d85adbc6a06d71e0233c65067729a7aa2\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"8d9586ff5c934716ef6ea403b9cd51b34629ea0f4fc956718923c7d861dff0ec\"" Jul 2 00:48:36.686275 env[1316]: time="2024-07-02T00:48:36.686200096Z" level=info msg="StartContainer for \"8d9586ff5c934716ef6ea403b9cd51b34629ea0f4fc956718923c7d861dff0ec\"" Jul 2 00:48:36.788084 env[1316]: time="2024-07-02T00:48:36.787980795Z" level=info msg="StartContainer for \"8d9586ff5c934716ef6ea403b9cd51b34629ea0f4fc956718923c7d861dff0ec\" returns successfully" Jul 2 00:48:37.513698 kubelet[1568]: E0702 00:48:37.513653 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:37.779504 kubelet[1568]: I0702 00:48:37.779398 1568 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.947248569 podCreationTimestamp="2024-07-02 00:48:32 +0000 UTC" firstStartedPulling="2024-07-02 00:48:32.829031835 +0000 UTC m=+20.264009387" lastFinishedPulling="2024-07-02 00:48:36.661135601 +0000 UTC m=+24.096113153" observedRunningTime="2024-07-02 00:48:37.779182658 +0000 UTC m=+25.214160211" watchObservedRunningTime="2024-07-02 00:48:37.779352335 +0000 UTC m=+25.214329887" Jul 2 00:48:38.514878 kubelet[1568]: E0702 00:48:38.514829 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:39.515305 kubelet[1568]: E0702 00:48:39.515254 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:40.516281 kubelet[1568]: E0702 00:48:40.516240 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:41.517229 kubelet[1568]: E0702 00:48:41.517195 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:42.518064 kubelet[1568]: E0702 00:48:42.518029 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:43.518866 kubelet[1568]: E0702 00:48:43.518822 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:44.519430 kubelet[1568]: E0702 00:48:44.519371 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:45.520382 kubelet[1568]: E0702 00:48:45.520343 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:46.521344 kubelet[1568]: E0702 00:48:46.521300 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:46.543626 kubelet[1568]: I0702 00:48:46.543593 1568 topology_manager.go:215] "Topology Admit Handler" podUID="d06cf555-d311-40b4-a4a8-c69168c35774" podNamespace="default" podName="test-pod-1" Jul 2 00:48:46.625424 kubelet[1568]: I0702 00:48:46.625364 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-b39c2360-c5e6-423f-9871-d25d4c9eabf8\" (UniqueName: \"kubernetes.io/nfs/d06cf555-d311-40b4-a4a8-c69168c35774-pvc-b39c2360-c5e6-423f-9871-d25d4c9eabf8\") pod \"test-pod-1\" (UID: \"d06cf555-d311-40b4-a4a8-c69168c35774\") " pod="default/test-pod-1" Jul 2 00:48:46.625424 kubelet[1568]: I0702 00:48:46.625414 1568 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rvxtn\" (UniqueName: \"kubernetes.io/projected/d06cf555-d311-40b4-a4a8-c69168c35774-kube-api-access-rvxtn\") pod \"test-pod-1\" (UID: \"d06cf555-d311-40b4-a4a8-c69168c35774\") " pod="default/test-pod-1" Jul 2 00:48:46.750719 kernel: FS-Cache: Loaded Jul 2 00:48:46.778015 kernel: RPC: Registered named UNIX socket transport module. Jul 2 00:48:46.778132 kernel: RPC: Registered udp transport module. Jul 2 00:48:46.778728 kernel: RPC: Registered tcp transport module. Jul 2 00:48:46.779701 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 2 00:48:46.818711 kernel: FS-Cache: Netfs 'nfs' registered for caching Jul 2 00:48:46.946011 kernel: NFS: Registering the id_resolver key type Jul 2 00:48:46.946126 kernel: Key type id_resolver registered Jul 2 00:48:46.946148 kernel: Key type id_legacy registered Jul 2 00:48:46.971342 nfsidmap[2480]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 2 00:48:46.974154 nfsidmap[2483]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 2 00:48:47.147576 env[1316]: time="2024-07-02T00:48:47.147211743Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d06cf555-d311-40b4-a4a8-c69168c35774,Namespace:default,Attempt:0,}" Jul 2 00:48:47.164423 systemd-networkd[1103]: veth2a0cbad9: Link UP Jul 2 00:48:47.166790 kernel: cni0: port 3(veth2a0cbad9) entered blocking state Jul 2 00:48:47.166856 kernel: cni0: port 3(veth2a0cbad9) entered disabled state Jul 2 00:48:47.167714 kernel: device veth2a0cbad9 entered promiscuous mode Jul 2 00:48:47.172860 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 2 00:48:47.172935 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth2a0cbad9: link becomes ready Jul 2 00:48:47.172959 kernel: cni0: port 3(veth2a0cbad9) entered blocking state Jul 2 00:48:47.173866 kernel: cni0: port 3(veth2a0cbad9) entered forwarding state Jul 2 00:48:47.173959 systemd-networkd[1103]: veth2a0cbad9: Gained carrier Jul 2 00:48:47.175504 env[1316]: 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)(0x40000b08e8), "name":"cbr0", "type":"bridge"} Jul 2 00:48:47.175504 env[1316]: delegateAdd: netconf sent to delegate plugin: Jul 2 00:48:47.184341 env[1316]: {"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-07-02T00:48:47.184266730Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:48:47.184341 env[1316]: time="2024-07-02T00:48:47.184315995Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:48:47.184341 env[1316]: time="2024-07-02T00:48:47.184327081Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:48:47.184500 env[1316]: time="2024-07-02T00:48:47.184462952Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/2faed2e11435ca5fb104ec2a3a04c857fe2572e415e24081c9981faa72a5522a pid=2530 runtime=io.containerd.runc.v2 Jul 2 00:48:47.215192 systemd-resolved[1241]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 2 00:48:47.232158 env[1316]: time="2024-07-02T00:48:47.232107507Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d06cf555-d311-40b4-a4a8-c69168c35774,Namespace:default,Attempt:0,} returns sandbox id \"2faed2e11435ca5fb104ec2a3a04c857fe2572e415e24081c9981faa72a5522a\"" Jul 2 00:48:47.233649 env[1316]: time="2024-07-02T00:48:47.233602087Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 2 00:48:47.450532 env[1316]: time="2024-07-02T00:48:47.449963809Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:47.451925 env[1316]: time="2024-07-02T00:48:47.451884892Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:47.453824 env[1316]: time="2024-07-02T00:48:47.453793288Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:47.455302 env[1316]: time="2024-07-02T00:48:47.455268899Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:bf28ef5d86aca0cd30a8ef19032ccadc1eada35dc9f14f42f3ccb73974f013de,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 2 00:48:47.456885 env[1316]: time="2024-07-02T00:48:47.456838958Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:2d3caadc252cc3b24921aae8c484cb83879b0b39cb20bb8d23a3a54872427653\"" Jul 2 00:48:47.458467 env[1316]: time="2024-07-02T00:48:47.458436792Z" level=info msg="CreateContainer within sandbox \"2faed2e11435ca5fb104ec2a3a04c857fe2572e415e24081c9981faa72a5522a\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 2 00:48:47.468505 env[1316]: time="2024-07-02T00:48:47.468473753Z" level=info msg="CreateContainer within sandbox \"2faed2e11435ca5fb104ec2a3a04c857fe2572e415e24081c9981faa72a5522a\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"7a3a852e3a222320906caa2dd2c6caf65010e66cd347fdd23a46ece14e298e98\"" Jul 2 00:48:47.469070 env[1316]: time="2024-07-02T00:48:47.469001508Z" level=info msg="StartContainer for \"7a3a852e3a222320906caa2dd2c6caf65010e66cd347fdd23a46ece14e298e98\"" Jul 2 00:48:47.522224 kubelet[1568]: E0702 00:48:47.521938 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:47.524986 env[1316]: time="2024-07-02T00:48:47.524942635Z" level=info msg="StartContainer for \"7a3a852e3a222320906caa2dd2c6caf65010e66cd347fdd23a46ece14e298e98\" returns successfully" Jul 2 00:48:47.781490 kubelet[1568]: I0702 00:48:47.781014 1568 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.557279798 podCreationTimestamp="2024-07-02 00:48:32 +0000 UTC" firstStartedPulling="2024-07-02 00:48:47.233369646 +0000 UTC m=+34.668347198" lastFinishedPulling="2024-07-02 00:48:47.457063716 +0000 UTC m=+34.892041268" observedRunningTime="2024-07-02 00:48:47.780954457 +0000 UTC m=+35.215931969" watchObservedRunningTime="2024-07-02 00:48:47.780973868 +0000 UTC m=+35.215951380" Jul 2 00:48:48.522204 kubelet[1568]: E0702 00:48:48.522165 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 2 00:48:48.924866 systemd-networkd[1103]: veth2a0cbad9: Gained IPv6LL Jul 2 00:48:49.522374 kubelet[1568]: E0702 00:48:49.522315 1568 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"