Aug 6 07:48:34.026954 kernel: Linux version 6.6.43-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT_DYNAMIC Mon Aug 5 20:36:27 -00 2024 Aug 6 07:48:34.026997 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=4a86c72568bc3f74d57effa5e252d5620941ef6d74241fc198859d020a6392c5 Aug 6 07:48:34.027020 kernel: BIOS-provided physical RAM map: Aug 6 07:48:34.027036 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Aug 6 07:48:34.027050 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Aug 6 07:48:34.027065 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Aug 6 07:48:34.027085 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffd7fff] usable Aug 6 07:48:34.027101 kernel: BIOS-e820: [mem 0x000000007ffd8000-0x000000007fffffff] reserved Aug 6 07:48:34.027117 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Aug 6 07:48:34.027136 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Aug 6 07:48:34.027152 kernel: NX (Execute Disable) protection: active Aug 6 07:48:34.027168 kernel: APIC: Static calls initialized Aug 6 07:48:34.027184 kernel: SMBIOS 2.8 present. Aug 6 07:48:34.027201 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 Aug 6 07:48:34.027222 kernel: Hypervisor detected: KVM Aug 6 07:48:34.027243 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Aug 6 07:48:34.027261 kernel: kvm-clock: using sched offset of 4328676454 cycles Aug 6 07:48:34.027280 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Aug 6 07:48:34.027315 kernel: tsc: Detected 2294.608 MHz processor Aug 6 07:48:34.027338 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 6 07:48:34.027362 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 6 07:48:34.027384 kernel: last_pfn = 0x7ffd8 max_arch_pfn = 0x400000000 Aug 6 07:48:34.027406 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Aug 6 07:48:34.027427 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 6 07:48:34.027461 kernel: ACPI: Early table checksum verification disabled Aug 6 07:48:34.027483 kernel: ACPI: RSDP 0x00000000000F5A50 000014 (v00 BOCHS ) Aug 6 07:48:34.028735 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 6 07:48:34.028761 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 6 07:48:34.028780 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 6 07:48:34.028799 kernel: ACPI: FACS 0x000000007FFE0000 000040 Aug 6 07:48:34.028818 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 6 07:48:34.028836 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 6 07:48:34.028926 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 6 07:48:34.028951 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 6 07:48:34.028969 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] Aug 6 07:48:34.028988 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] Aug 6 07:48:34.029010 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Aug 6 07:48:34.029031 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] Aug 6 07:48:34.029049 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] Aug 6 07:48:34.029069 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] Aug 6 07:48:34.029101 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] Aug 6 07:48:34.029120 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Aug 6 07:48:34.029140 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Aug 6 07:48:34.029160 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Aug 6 07:48:34.029179 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Aug 6 07:48:34.029199 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffd7fff] -> [mem 0x00000000-0x7ffd7fff] Aug 6 07:48:34.029219 kernel: NODE_DATA(0) allocated [mem 0x7ffd2000-0x7ffd7fff] Aug 6 07:48:34.029243 kernel: Zone ranges: Aug 6 07:48:34.029266 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 6 07:48:34.029286 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffd7fff] Aug 6 07:48:34.029306 kernel: Normal empty Aug 6 07:48:34.029326 kernel: Movable zone start for each node Aug 6 07:48:34.029346 kernel: Early memory node ranges Aug 6 07:48:34.029366 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Aug 6 07:48:34.029386 kernel: node 0: [mem 0x0000000000100000-0x000000007ffd7fff] Aug 6 07:48:34.029406 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffd7fff] Aug 6 07:48:34.029429 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 6 07:48:34.029449 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Aug 6 07:48:34.029469 kernel: On node 0, zone DMA32: 40 pages in unavailable ranges Aug 6 07:48:34.029489 kernel: ACPI: PM-Timer IO Port: 0x608 Aug 6 07:48:34.029532 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Aug 6 07:48:34.029552 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Aug 6 07:48:34.029572 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Aug 6 07:48:34.029595 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Aug 6 07:48:34.029619 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 6 07:48:34.029648 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Aug 6 07:48:34.029673 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Aug 6 07:48:34.029697 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 6 07:48:34.029721 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Aug 6 07:48:34.029746 kernel: TSC deadline timer available Aug 6 07:48:34.029768 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Aug 6 07:48:34.029788 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Aug 6 07:48:34.029808 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Aug 6 07:48:34.029828 kernel: Booting paravirtualized kernel on KVM Aug 6 07:48:34.029853 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 6 07:48:34.029873 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Aug 6 07:48:34.029893 kernel: percpu: Embedded 58 pages/cpu s196904 r8192 d32472 u1048576 Aug 6 07:48:34.029914 kernel: pcpu-alloc: s196904 r8192 d32472 u1048576 alloc=1*2097152 Aug 6 07:48:34.029933 kernel: pcpu-alloc: [0] 0 1 Aug 6 07:48:34.029952 kernel: kvm-guest: PV spinlocks disabled, no host support Aug 6 07:48:34.029974 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=4a86c72568bc3f74d57effa5e252d5620941ef6d74241fc198859d020a6392c5 Aug 6 07:48:34.029994 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 6 07:48:34.030017 kernel: random: crng init done Aug 6 07:48:34.030037 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 6 07:48:34.030056 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 6 07:48:34.030076 kernel: Fallback order for Node 0: 0 Aug 6 07:48:34.030096 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515800 Aug 6 07:48:34.030116 kernel: Policy zone: DMA32 Aug 6 07:48:34.030136 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 6 07:48:34.030156 kernel: Memory: 1965048K/2096600K available (12288K kernel code, 2302K rwdata, 22640K rodata, 49328K init, 2016K bss, 131292K reserved, 0K cma-reserved) Aug 6 07:48:34.030176 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Aug 6 07:48:34.030199 kernel: Kernel/User page tables isolation: enabled Aug 6 07:48:34.030219 kernel: ftrace: allocating 37659 entries in 148 pages Aug 6 07:48:34.030238 kernel: ftrace: allocated 148 pages with 3 groups Aug 6 07:48:34.030262 kernel: Dynamic Preempt: voluntary Aug 6 07:48:34.030282 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 6 07:48:34.030303 kernel: rcu: RCU event tracing is enabled. Aug 6 07:48:34.030323 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Aug 6 07:48:34.030343 kernel: Trampoline variant of Tasks RCU enabled. Aug 6 07:48:34.030363 kernel: Rude variant of Tasks RCU enabled. Aug 6 07:48:34.030386 kernel: Tracing variant of Tasks RCU enabled. Aug 6 07:48:34.030406 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 6 07:48:34.030425 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Aug 6 07:48:34.030445 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Aug 6 07:48:34.030464 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 6 07:48:34.030484 kernel: Console: colour VGA+ 80x25 Aug 6 07:48:34.030517 kernel: printk: console [tty0] enabled Aug 6 07:48:34.030537 kernel: printk: console [ttyS0] enabled Aug 6 07:48:34.030557 kernel: ACPI: Core revision 20230628 Aug 6 07:48:34.030580 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Aug 6 07:48:34.030600 kernel: APIC: Switch to symmetric I/O mode setup Aug 6 07:48:34.030619 kernel: x2apic enabled Aug 6 07:48:34.030639 kernel: APIC: Switched APIC routing to: physical x2apic Aug 6 07:48:34.030659 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Aug 6 07:48:34.030679 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns Aug 6 07:48:34.030699 kernel: Calibrating delay loop (skipped) preset value.. 4589.21 BogoMIPS (lpj=2294608) Aug 6 07:48:34.030718 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Aug 6 07:48:34.030739 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Aug 6 07:48:34.030774 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 6 07:48:34.030795 kernel: Spectre V2 : Mitigation: Retpolines Aug 6 07:48:34.030816 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Aug 6 07:48:34.030840 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Aug 6 07:48:34.030861 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Aug 6 07:48:34.030882 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Aug 6 07:48:34.030903 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Aug 6 07:48:34.030924 kernel: MDS: Mitigation: Clear CPU buffers Aug 6 07:48:34.030946 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Aug 6 07:48:34.030970 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 6 07:48:34.030991 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 6 07:48:34.031012 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 6 07:48:34.031033 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 6 07:48:34.031054 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 6 07:48:34.031075 kernel: Freeing SMP alternatives memory: 32K Aug 6 07:48:34.031096 kernel: pid_max: default: 32768 minimum: 301 Aug 6 07:48:34.031120 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Aug 6 07:48:34.031142 kernel: SELinux: Initializing. Aug 6 07:48:34.031165 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 6 07:48:34.031192 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 6 07:48:34.031218 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) Aug 6 07:48:34.031241 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 6 07:48:34.031263 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 6 07:48:34.031284 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 6 07:48:34.031323 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Aug 6 07:48:34.031356 kernel: signal: max sigframe size: 1776 Aug 6 07:48:34.031381 kernel: rcu: Hierarchical SRCU implementation. Aug 6 07:48:34.031406 kernel: rcu: Max phase no-delay instances is 400. Aug 6 07:48:34.031430 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 6 07:48:34.031440 kernel: smp: Bringing up secondary CPUs ... Aug 6 07:48:34.031449 kernel: smpboot: x86: Booting SMP configuration: Aug 6 07:48:34.031457 kernel: .... node #0, CPUs: #1 Aug 6 07:48:34.031466 kernel: smp: Brought up 1 node, 2 CPUs Aug 6 07:48:34.031476 kernel: smpboot: Max logical packages: 1 Aug 6 07:48:34.031485 kernel: smpboot: Total of 2 processors activated (9178.43 BogoMIPS) Aug 6 07:48:34.031519 kernel: devtmpfs: initialized Aug 6 07:48:34.031529 kernel: x86/mm: Memory block size: 128MB Aug 6 07:48:34.031538 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 6 07:48:34.031547 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Aug 6 07:48:34.031556 kernel: pinctrl core: initialized pinctrl subsystem Aug 6 07:48:34.031565 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 6 07:48:34.031574 kernel: audit: initializing netlink subsys (disabled) Aug 6 07:48:34.031584 kernel: audit: type=2000 audit(1722930512.157:1): state=initialized audit_enabled=0 res=1 Aug 6 07:48:34.031596 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 6 07:48:34.031605 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 6 07:48:34.031614 kernel: cpuidle: using governor menu Aug 6 07:48:34.031623 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 6 07:48:34.031632 kernel: dca service started, version 1.12.1 Aug 6 07:48:34.031641 kernel: PCI: Using configuration type 1 for base access Aug 6 07:48:34.031650 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 6 07:48:34.031659 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 6 07:48:34.031668 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 6 07:48:34.031680 kernel: ACPI: Added _OSI(Module Device) Aug 6 07:48:34.031689 kernel: ACPI: Added _OSI(Processor Device) Aug 6 07:48:34.031698 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 6 07:48:34.031707 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 6 07:48:34.031716 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 6 07:48:34.031725 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Aug 6 07:48:34.031733 kernel: ACPI: Interpreter enabled Aug 6 07:48:34.031742 kernel: ACPI: PM: (supports S0 S5) Aug 6 07:48:34.031751 kernel: ACPI: Using IOAPIC for interrupt routing Aug 6 07:48:34.031760 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 6 07:48:34.031772 kernel: PCI: Using E820 reservations for host bridge windows Aug 6 07:48:34.031781 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Aug 6 07:48:34.031790 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 6 07:48:34.032047 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Aug 6 07:48:34.032200 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Aug 6 07:48:34.032345 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Aug 6 07:48:34.032370 kernel: acpiphp: Slot [3] registered Aug 6 07:48:34.032397 kernel: acpiphp: Slot [4] registered Aug 6 07:48:34.032418 kernel: acpiphp: Slot [5] registered Aug 6 07:48:34.032439 kernel: acpiphp: Slot [6] registered Aug 6 07:48:34.032460 kernel: acpiphp: Slot [7] registered Aug 6 07:48:34.032481 kernel: acpiphp: Slot [8] registered Aug 6 07:48:34.032541 kernel: acpiphp: Slot [9] registered Aug 6 07:48:34.032562 kernel: acpiphp: Slot [10] registered Aug 6 07:48:34.032583 kernel: acpiphp: Slot [11] registered Aug 6 07:48:34.032604 kernel: acpiphp: Slot [12] registered Aug 6 07:48:34.032629 kernel: acpiphp: Slot [13] registered Aug 6 07:48:34.032650 kernel: acpiphp: Slot [14] registered Aug 6 07:48:34.032670 kernel: acpiphp: Slot [15] registered Aug 6 07:48:34.032691 kernel: acpiphp: Slot [16] registered Aug 6 07:48:34.032712 kernel: acpiphp: Slot [17] registered Aug 6 07:48:34.032733 kernel: acpiphp: Slot [18] registered Aug 6 07:48:34.032753 kernel: acpiphp: Slot [19] registered Aug 6 07:48:34.032774 kernel: acpiphp: Slot [20] registered Aug 6 07:48:34.032794 kernel: acpiphp: Slot [21] registered Aug 6 07:48:34.032815 kernel: acpiphp: Slot [22] registered Aug 6 07:48:34.032839 kernel: acpiphp: Slot [23] registered Aug 6 07:48:34.032860 kernel: acpiphp: Slot [24] registered Aug 6 07:48:34.032881 kernel: acpiphp: Slot [25] registered Aug 6 07:48:34.032902 kernel: acpiphp: Slot [26] registered Aug 6 07:48:34.032922 kernel: acpiphp: Slot [27] registered Aug 6 07:48:34.032943 kernel: acpiphp: Slot [28] registered Aug 6 07:48:34.032964 kernel: acpiphp: Slot [29] registered Aug 6 07:48:34.032984 kernel: acpiphp: Slot [30] registered Aug 6 07:48:34.033006 kernel: acpiphp: Slot [31] registered Aug 6 07:48:34.033030 kernel: PCI host bridge to bus 0000:00 Aug 6 07:48:34.033185 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Aug 6 07:48:34.033316 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Aug 6 07:48:34.033444 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Aug 6 07:48:34.034149 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Aug 6 07:48:34.034312 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Aug 6 07:48:34.034443 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 6 07:48:34.034649 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Aug 6 07:48:34.034816 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Aug 6 07:48:34.034988 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Aug 6 07:48:34.035162 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc1e0-0xc1ef] Aug 6 07:48:34.035338 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Aug 6 07:48:34.035941 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Aug 6 07:48:34.036145 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Aug 6 07:48:34.036325 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Aug 6 07:48:34.036797 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Aug 6 07:48:34.036960 kernel: pci 0000:00:01.2: reg 0x20: [io 0xc180-0xc19f] Aug 6 07:48:34.037128 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Aug 6 07:48:34.037275 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Aug 6 07:48:34.037419 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Aug 6 07:48:34.037616 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Aug 6 07:48:34.037766 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Aug 6 07:48:34.037911 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Aug 6 07:48:34.038057 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf0fff] Aug 6 07:48:34.039595 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] Aug 6 07:48:34.039788 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Aug 6 07:48:34.039959 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Aug 6 07:48:34.040122 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc1a0-0xc1bf] Aug 6 07:48:34.040274 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] Aug 6 07:48:34.040425 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Aug 6 07:48:34.041649 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Aug 6 07:48:34.041771 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc1c0-0xc1df] Aug 6 07:48:34.041874 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] Aug 6 07:48:34.041981 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Aug 6 07:48:34.042090 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 Aug 6 07:48:34.042191 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc100-0xc13f] Aug 6 07:48:34.042291 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] Aug 6 07:48:34.042388 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Aug 6 07:48:34.042495 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 Aug 6 07:48:34.043956 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc07f] Aug 6 07:48:34.044100 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] Aug 6 07:48:34.044206 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Aug 6 07:48:34.044319 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 Aug 6 07:48:34.044420 kernel: pci 0000:00:07.0: reg 0x10: [io 0xc080-0xc0ff] Aug 6 07:48:34.045655 kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] Aug 6 07:48:34.045822 kernel: pci 0000:00:07.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref] Aug 6 07:48:34.045936 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 Aug 6 07:48:34.046045 kernel: pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] Aug 6 07:48:34.046144 kernel: pci 0000:00:08.0: reg 0x20: [mem 0xfe818000-0xfe81bfff 64bit pref] Aug 6 07:48:34.046156 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Aug 6 07:48:34.046166 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Aug 6 07:48:34.046176 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Aug 6 07:48:34.046185 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Aug 6 07:48:34.046194 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Aug 6 07:48:34.046207 kernel: iommu: Default domain type: Translated Aug 6 07:48:34.046216 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 6 07:48:34.046225 kernel: PCI: Using ACPI for IRQ routing Aug 6 07:48:34.046234 kernel: PCI: pci_cache_line_size set to 64 bytes Aug 6 07:48:34.046258 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Aug 6 07:48:34.046268 kernel: e820: reserve RAM buffer [mem 0x7ffd8000-0x7fffffff] Aug 6 07:48:34.046383 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Aug 6 07:48:34.046484 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Aug 6 07:48:34.047699 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Aug 6 07:48:34.047747 kernel: vgaarb: loaded Aug 6 07:48:34.047761 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Aug 6 07:48:34.047775 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Aug 6 07:48:34.047794 kernel: clocksource: Switched to clocksource kvm-clock Aug 6 07:48:34.047810 kernel: VFS: Disk quotas dquot_6.6.0 Aug 6 07:48:34.047826 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 6 07:48:34.047842 kernel: pnp: PnP ACPI init Aug 6 07:48:34.047859 kernel: pnp: PnP ACPI: found 4 devices Aug 6 07:48:34.047875 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 6 07:48:34.047895 kernel: NET: Registered PF_INET protocol family Aug 6 07:48:34.047911 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 6 07:48:34.047927 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Aug 6 07:48:34.047943 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 6 07:48:34.047959 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Aug 6 07:48:34.047976 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Aug 6 07:48:34.047991 kernel: TCP: Hash tables configured (established 16384 bind 16384) Aug 6 07:48:34.048007 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 6 07:48:34.048023 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 6 07:48:34.048043 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 6 07:48:34.048058 kernel: NET: Registered PF_XDP protocol family Aug 6 07:48:34.048209 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Aug 6 07:48:34.048339 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Aug 6 07:48:34.048464 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Aug 6 07:48:34.049718 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Aug 6 07:48:34.049866 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Aug 6 07:48:34.050026 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Aug 6 07:48:34.050205 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Aug 6 07:48:34.050240 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Aug 6 07:48:34.050437 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x7b0 took 32034 usecs Aug 6 07:48:34.050464 kernel: PCI: CLS 0 bytes, default 64 Aug 6 07:48:34.050486 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Aug 6 07:48:34.050891 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns Aug 6 07:48:34.050921 kernel: Initialise system trusted keyrings Aug 6 07:48:34.050947 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Aug 6 07:48:34.050981 kernel: Key type asymmetric registered Aug 6 07:48:34.051003 kernel: Asymmetric key parser 'x509' registered Aug 6 07:48:34.051023 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Aug 6 07:48:34.051045 kernel: io scheduler mq-deadline registered Aug 6 07:48:34.051066 kernel: io scheduler kyber registered Aug 6 07:48:34.051088 kernel: io scheduler bfq registered Aug 6 07:48:34.051109 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 6 07:48:34.051131 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Aug 6 07:48:34.051153 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Aug 6 07:48:34.051178 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Aug 6 07:48:34.051199 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 6 07:48:34.051220 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 6 07:48:34.051241 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Aug 6 07:48:34.051262 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Aug 6 07:48:34.051284 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Aug 6 07:48:34.051322 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Aug 6 07:48:34.051526 kernel: rtc_cmos 00:03: RTC can wake from S4 Aug 6 07:48:34.051685 kernel: rtc_cmos 00:03: registered as rtc0 Aug 6 07:48:34.051856 kernel: rtc_cmos 00:03: setting system clock to 2024-08-06T07:48:33 UTC (1722930513) Aug 6 07:48:34.052010 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Aug 6 07:48:34.052036 kernel: intel_pstate: CPU model not supported Aug 6 07:48:34.052059 kernel: NET: Registered PF_INET6 protocol family Aug 6 07:48:34.052089 kernel: Segment Routing with IPv6 Aug 6 07:48:34.052103 kernel: In-situ OAM (IOAM) with IPv6 Aug 6 07:48:34.052117 kernel: NET: Registered PF_PACKET protocol family Aug 6 07:48:34.052131 kernel: Key type dns_resolver registered Aug 6 07:48:34.052151 kernel: IPI shorthand broadcast: enabled Aug 6 07:48:34.052163 kernel: sched_clock: Marking stable (1282003212, 187603948)->(1522183387, -52576227) Aug 6 07:48:34.052176 kernel: registered taskstats version 1 Aug 6 07:48:34.052206 kernel: Loading compiled-in X.509 certificates Aug 6 07:48:34.052230 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.43-flatcar: e31e857530e65c19b206dbf3ab8297cc37ac5d55' Aug 6 07:48:34.052252 kernel: Key type .fscrypt registered Aug 6 07:48:34.052272 kernel: Key type fscrypt-provisioning registered Aug 6 07:48:34.052293 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 6 07:48:34.052315 kernel: ima: Allocated hash algorithm: sha1 Aug 6 07:48:34.052340 kernel: ima: No architecture policies found Aug 6 07:48:34.052364 kernel: clk: Disabling unused clocks Aug 6 07:48:34.052389 kernel: Freeing unused kernel image (initmem) memory: 49328K Aug 6 07:48:34.052415 kernel: Write protecting the kernel read-only data: 36864k Aug 6 07:48:34.052442 kernel: Freeing unused kernel image (rodata/data gap) memory: 1936K Aug 6 07:48:34.054549 kernel: Run /init as init process Aug 6 07:48:34.054593 kernel: with arguments: Aug 6 07:48:34.054607 kernel: /init Aug 6 07:48:34.054620 kernel: with environment: Aug 6 07:48:34.054636 kernel: HOME=/ Aug 6 07:48:34.054652 kernel: TERM=linux Aug 6 07:48:34.054669 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 6 07:48:34.054687 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 6 07:48:34.054705 systemd[1]: Detected virtualization kvm. Aug 6 07:48:34.054722 systemd[1]: Detected architecture x86-64. Aug 6 07:48:34.054736 systemd[1]: Running in initrd. Aug 6 07:48:34.054754 systemd[1]: No hostname configured, using default hostname. Aug 6 07:48:34.054767 systemd[1]: Hostname set to . Aug 6 07:48:34.054783 systemd[1]: Initializing machine ID from VM UUID. Aug 6 07:48:34.054800 systemd[1]: Queued start job for default target initrd.target. Aug 6 07:48:34.054814 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 6 07:48:34.054828 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 6 07:48:34.054844 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 6 07:48:34.054863 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 6 07:48:34.054889 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 6 07:48:34.054905 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 6 07:48:34.054934 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 6 07:48:34.054958 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 6 07:48:34.054984 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 6 07:48:34.055008 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 6 07:48:34.055032 systemd[1]: Reached target paths.target - Path Units. Aug 6 07:48:34.055075 systemd[1]: Reached target slices.target - Slice Units. Aug 6 07:48:34.055103 systemd[1]: Reached target swap.target - Swaps. Aug 6 07:48:34.055127 systemd[1]: Reached target timers.target - Timer Units. Aug 6 07:48:34.055154 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 6 07:48:34.055178 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 6 07:48:34.055201 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 6 07:48:34.055228 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 6 07:48:34.055252 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 6 07:48:34.055275 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 6 07:48:34.055306 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 6 07:48:34.055316 systemd[1]: Reached target sockets.target - Socket Units. Aug 6 07:48:34.055326 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 6 07:48:34.055336 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 6 07:48:34.055346 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 6 07:48:34.055360 systemd[1]: Starting systemd-fsck-usr.service... Aug 6 07:48:34.055370 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 6 07:48:34.055380 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 6 07:48:34.055391 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 6 07:48:34.055460 systemd-journald[182]: Collecting audit messages is disabled. Aug 6 07:48:34.055554 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 6 07:48:34.055578 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 6 07:48:34.055602 systemd[1]: Finished systemd-fsck-usr.service. Aug 6 07:48:34.055627 systemd-journald[182]: Journal started Aug 6 07:48:34.055675 systemd-journald[182]: Runtime Journal (/run/log/journal/32a825204b9747b2bcd96d532ea402dc) is 4.9M, max 39.3M, 34.4M free. Aug 6 07:48:34.058702 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 6 07:48:34.039656 systemd-modules-load[183]: Inserted module 'overlay' Aug 6 07:48:34.093544 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 6 07:48:34.093635 kernel: Bridge firewalling registered Aug 6 07:48:34.093717 systemd-modules-load[183]: Inserted module 'br_netfilter' Aug 6 07:48:34.122922 systemd[1]: Started systemd-journald.service - Journal Service. Aug 6 07:48:34.130465 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 6 07:48:34.132823 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 6 07:48:34.138953 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 6 07:48:34.147776 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 6 07:48:34.154886 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 6 07:48:34.157042 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 6 07:48:34.166749 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 6 07:48:34.182074 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 6 07:48:34.190750 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 6 07:48:34.194642 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 6 07:48:34.196822 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 6 07:48:34.204864 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 6 07:48:34.207876 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 6 07:48:34.232811 dracut-cmdline[218]: dracut-dracut-053 Aug 6 07:48:34.240110 dracut-cmdline[218]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=4a86c72568bc3f74d57effa5e252d5620941ef6d74241fc198859d020a6392c5 Aug 6 07:48:34.269982 systemd-resolved[219]: Positive Trust Anchors: Aug 6 07:48:34.270003 systemd-resolved[219]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 6 07:48:34.270092 systemd-resolved[219]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 6 07:48:34.276184 systemd-resolved[219]: Defaulting to hostname 'linux'. Aug 6 07:48:34.277566 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 6 07:48:34.279039 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 6 07:48:34.375581 kernel: SCSI subsystem initialized Aug 6 07:48:34.389571 kernel: Loading iSCSI transport class v2.0-870. Aug 6 07:48:34.407548 kernel: iscsi: registered transport (tcp) Aug 6 07:48:34.443807 kernel: iscsi: registered transport (qla4xxx) Aug 6 07:48:34.443918 kernel: QLogic iSCSI HBA Driver Aug 6 07:48:34.520044 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 6 07:48:34.529848 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 6 07:48:34.593287 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 6 07:48:34.593413 kernel: device-mapper: uevent: version 1.0.3 Aug 6 07:48:34.595708 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 6 07:48:34.658618 kernel: raid6: avx2x4 gen() 15099 MB/s Aug 6 07:48:34.676598 kernel: raid6: avx2x2 gen() 15789 MB/s Aug 6 07:48:34.695547 kernel: raid6: avx2x1 gen() 12310 MB/s Aug 6 07:48:34.695685 kernel: raid6: using algorithm avx2x2 gen() 15789 MB/s Aug 6 07:48:34.713604 kernel: raid6: .... xor() 13045 MB/s, rmw enabled Aug 6 07:48:34.713723 kernel: raid6: using avx2x2 recovery algorithm Aug 6 07:48:34.750564 kernel: xor: automatically using best checksumming function avx Aug 6 07:48:34.993557 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 6 07:48:35.016794 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 6 07:48:35.024959 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 6 07:48:35.063522 systemd-udevd[402]: Using default interface naming scheme 'v255'. Aug 6 07:48:35.071871 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 6 07:48:35.080737 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 6 07:48:35.104458 dracut-pre-trigger[410]: rd.md=0: removing MD RAID activation Aug 6 07:48:35.150405 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 6 07:48:35.158778 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 6 07:48:35.226864 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 6 07:48:35.236784 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 6 07:48:35.273084 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 6 07:48:35.277308 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 6 07:48:35.281545 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 6 07:48:35.282310 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 6 07:48:35.295576 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 6 07:48:35.308341 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 6 07:48:35.348544 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues Aug 6 07:48:35.448677 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Aug 6 07:48:35.448834 kernel: scsi host0: Virtio SCSI HBA Aug 6 07:48:35.448964 kernel: cryptd: max_cpu_qlen set to 1000 Aug 6 07:48:35.448978 kernel: libata version 3.00 loaded. Aug 6 07:48:35.448991 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 6 07:48:35.449003 kernel: GPT:9289727 != 125829119 Aug 6 07:48:35.449014 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 6 07:48:35.449025 kernel: GPT:9289727 != 125829119 Aug 6 07:48:35.449042 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 6 07:48:35.449053 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 6 07:48:35.449065 kernel: ata_piix 0000:00:01.1: version 2.13 Aug 6 07:48:35.449204 kernel: scsi host1: ata_piix Aug 6 07:48:35.449332 kernel: scsi host2: ata_piix Aug 6 07:48:35.449455 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 Aug 6 07:48:35.449468 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 Aug 6 07:48:35.449484 kernel: AVX2 version of gcm_enc/dec engaged. Aug 6 07:48:35.449508 kernel: AES CTR mode by8 optimization enabled Aug 6 07:48:35.427844 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 6 07:48:35.428060 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 6 07:48:35.429571 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 6 07:48:35.430285 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 6 07:48:35.463634 kernel: ACPI: bus type USB registered Aug 6 07:48:35.463673 kernel: usbcore: registered new interface driver usbfs Aug 6 07:48:35.463700 kernel: usbcore: registered new interface driver hub Aug 6 07:48:35.463726 kernel: usbcore: registered new device driver usb Aug 6 07:48:35.463771 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues Aug 6 07:48:35.465879 kernel: virtio_blk virtio5: [vdb] 920 512-byte logical blocks (471 kB/460 KiB) Aug 6 07:48:35.430546 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 6 07:48:35.431660 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 6 07:48:35.441305 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 6 07:48:35.536008 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 6 07:48:35.547834 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 6 07:48:35.566976 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 6 07:48:35.623635 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 6 07:48:35.653530 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (449) Aug 6 07:48:35.658187 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 6 07:48:35.665401 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Aug 6 07:48:35.679256 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Aug 6 07:48:35.679431 kernel: BTRFS: device fsid d3844c60-0a2c-449a-9ee9-2a875f8d8e12 devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (453) Aug 6 07:48:35.679446 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Aug 6 07:48:35.679609 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 Aug 6 07:48:35.679733 kernel: hub 1-0:1.0: USB hub found Aug 6 07:48:35.679873 kernel: hub 1-0:1.0: 2 ports detected Aug 6 07:48:35.683794 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 6 07:48:35.686545 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 6 07:48:35.694062 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 6 07:48:35.704807 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 6 07:48:35.713568 disk-uuid[549]: Primary Header is updated. Aug 6 07:48:35.713568 disk-uuid[549]: Secondary Entries is updated. Aug 6 07:48:35.713568 disk-uuid[549]: Secondary Header is updated. Aug 6 07:48:35.723531 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 6 07:48:35.734538 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 6 07:48:35.749551 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 6 07:48:36.745538 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 6 07:48:36.746112 disk-uuid[550]: The operation has completed successfully. Aug 6 07:48:36.818344 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 6 07:48:36.819424 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 6 07:48:36.832882 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 6 07:48:36.849947 sh[564]: Success Aug 6 07:48:36.870718 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Aug 6 07:48:36.969217 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 6 07:48:36.976742 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 6 07:48:36.978348 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 6 07:48:37.012794 kernel: BTRFS info (device dm-0): first mount of filesystem d3844c60-0a2c-449a-9ee9-2a875f8d8e12 Aug 6 07:48:37.012899 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 6 07:48:37.015317 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 6 07:48:37.017631 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 6 07:48:37.020657 kernel: BTRFS info (device dm-0): using free space tree Aug 6 07:48:37.035165 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 6 07:48:37.036933 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 6 07:48:37.042736 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 6 07:48:37.045669 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 6 07:48:37.067787 kernel: BTRFS info (device vda6): first mount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 6 07:48:37.067868 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 6 07:48:37.070234 kernel: BTRFS info (device vda6): using free space tree Aug 6 07:48:37.076565 kernel: BTRFS info (device vda6): auto enabling async discard Aug 6 07:48:37.092163 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 6 07:48:37.093534 kernel: BTRFS info (device vda6): last unmount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 6 07:48:37.105085 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 6 07:48:37.113833 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 6 07:48:37.216564 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 6 07:48:37.225914 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 6 07:48:37.273333 systemd-networkd[751]: lo: Link UP Aug 6 07:48:37.274247 systemd-networkd[751]: lo: Gained carrier Aug 6 07:48:37.277296 systemd-networkd[751]: Enumeration completed Aug 6 07:48:37.278008 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 6 07:48:37.279121 systemd-networkd[751]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Aug 6 07:48:37.279126 systemd-networkd[751]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. Aug 6 07:48:37.280214 systemd[1]: Reached target network.target - Network. Aug 6 07:48:37.282438 systemd-networkd[751]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 6 07:48:37.282444 systemd-networkd[751]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 6 07:48:37.283572 systemd-networkd[751]: eth0: Link UP Aug 6 07:48:37.283581 systemd-networkd[751]: eth0: Gained carrier Aug 6 07:48:37.284986 systemd-networkd[751]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Aug 6 07:48:37.289375 systemd-networkd[751]: eth1: Link UP Aug 6 07:48:37.289382 systemd-networkd[751]: eth1: Gained carrier Aug 6 07:48:37.293814 ignition[667]: Ignition 2.18.0 Aug 6 07:48:37.289399 systemd-networkd[751]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 6 07:48:37.293823 ignition[667]: Stage: fetch-offline Aug 6 07:48:37.296514 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 6 07:48:37.293881 ignition[667]: no configs at "/usr/lib/ignition/base.d" Aug 6 07:48:37.293893 ignition[667]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Aug 6 07:48:37.294094 ignition[667]: parsed url from cmdline: "" Aug 6 07:48:37.294099 ignition[667]: no config URL provided Aug 6 07:48:37.294106 ignition[667]: reading system config file "/usr/lib/ignition/user.ign" Aug 6 07:48:37.294118 ignition[667]: no config at "/usr/lib/ignition/user.ign" Aug 6 07:48:37.303624 systemd-networkd[751]: eth1: DHCPv4 address 10.124.0.15/20 acquired from 169.254.169.253 Aug 6 07:48:37.294125 ignition[667]: failed to fetch config: resource requires networking Aug 6 07:48:37.303854 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 6 07:48:37.294343 ignition[667]: Ignition finished successfully Aug 6 07:48:37.306796 systemd-networkd[751]: eth0: DHCPv4 address 64.23.172.146/20, gateway 64.23.160.1 acquired from 169.254.169.253 Aug 6 07:48:37.328903 ignition[760]: Ignition 2.18.0 Aug 6 07:48:37.328915 ignition[760]: Stage: fetch Aug 6 07:48:37.329180 ignition[760]: no configs at "/usr/lib/ignition/base.d" Aug 6 07:48:37.329195 ignition[760]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Aug 6 07:48:37.329310 ignition[760]: parsed url from cmdline: "" Aug 6 07:48:37.329314 ignition[760]: no config URL provided Aug 6 07:48:37.329321 ignition[760]: reading system config file "/usr/lib/ignition/user.ign" Aug 6 07:48:37.329333 ignition[760]: no config at "/usr/lib/ignition/user.ign" Aug 6 07:48:37.329355 ignition[760]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 Aug 6 07:48:37.358135 ignition[760]: GET result: OK Aug 6 07:48:37.358233 ignition[760]: parsing config with SHA512: ab826f9790416107a71394bc377faec48b0fd76e805ab4699a3bc447c96491e1c349f082d481c9dbad63f68eda5b91e53a7ba0ca8d07d1218590a44c851d1798 Aug 6 07:48:37.362539 unknown[760]: fetched base config from "system" Aug 6 07:48:37.362552 unknown[760]: fetched base config from "system" Aug 6 07:48:37.362925 ignition[760]: fetch: fetch complete Aug 6 07:48:37.362562 unknown[760]: fetched user config from "digitalocean" Aug 6 07:48:37.362932 ignition[760]: fetch: fetch passed Aug 6 07:48:37.364945 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 6 07:48:37.362986 ignition[760]: Ignition finished successfully Aug 6 07:48:37.373874 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 6 07:48:37.401417 ignition[768]: Ignition 2.18.0 Aug 6 07:48:37.401436 ignition[768]: Stage: kargs Aug 6 07:48:37.401757 ignition[768]: no configs at "/usr/lib/ignition/base.d" Aug 6 07:48:37.401775 ignition[768]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Aug 6 07:48:37.402961 ignition[768]: kargs: kargs passed Aug 6 07:48:37.404678 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 6 07:48:37.403050 ignition[768]: Ignition finished successfully Aug 6 07:48:37.411911 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 6 07:48:37.448755 ignition[775]: Ignition 2.18.0 Aug 6 07:48:37.448776 ignition[775]: Stage: disks Aug 6 07:48:37.449081 ignition[775]: no configs at "/usr/lib/ignition/base.d" Aug 6 07:48:37.449100 ignition[775]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Aug 6 07:48:37.452488 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 6 07:48:37.450478 ignition[775]: disks: disks passed Aug 6 07:48:37.450583 ignition[775]: Ignition finished successfully Aug 6 07:48:37.454879 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 6 07:48:37.462802 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 6 07:48:37.464248 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 6 07:48:37.465734 systemd[1]: Reached target sysinit.target - System Initialization. Aug 6 07:48:37.467580 systemd[1]: Reached target basic.target - Basic System. Aug 6 07:48:37.473805 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 6 07:48:37.502200 systemd-fsck[785]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 6 07:48:37.510096 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 6 07:48:37.516761 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 6 07:48:37.670837 kernel: EXT4-fs (vda9): mounted filesystem e865ac73-053b-4efa-9a0f-50dec3f650d9 r/w with ordered data mode. Quota mode: none. Aug 6 07:48:37.671922 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 6 07:48:37.673429 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 6 07:48:37.679711 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 6 07:48:37.693849 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 6 07:48:37.697780 systemd[1]: Starting flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent... Aug 6 07:48:37.704691 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Aug 6 07:48:37.707093 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 6 07:48:37.708520 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 6 07:48:37.715247 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 6 07:48:37.720523 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (793) Aug 6 07:48:37.728967 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 6 07:48:37.741122 kernel: BTRFS info (device vda6): first mount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 6 07:48:37.745456 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 6 07:48:37.745557 kernel: BTRFS info (device vda6): using free space tree Aug 6 07:48:37.755541 kernel: BTRFS info (device vda6): auto enabling async discard Aug 6 07:48:37.762235 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 6 07:48:37.838416 coreos-metadata[796]: Aug 06 07:48:37.838 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Aug 6 07:48:37.842640 initrd-setup-root[823]: cut: /sysroot/etc/passwd: No such file or directory Aug 6 07:48:37.857779 initrd-setup-root[830]: cut: /sysroot/etc/group: No such file or directory Aug 6 07:48:37.860688 coreos-metadata[796]: Aug 06 07:48:37.856 INFO Fetch successful Aug 6 07:48:37.862011 coreos-metadata[795]: Aug 06 07:48:37.860 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Aug 6 07:48:37.864406 coreos-metadata[796]: Aug 06 07:48:37.864 INFO wrote hostname ci-3975.2.0-3-5d52add573 to /sysroot/etc/hostname Aug 6 07:48:37.865985 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 6 07:48:37.869808 initrd-setup-root[837]: cut: /sysroot/etc/shadow: No such file or directory Aug 6 07:48:37.873885 coreos-metadata[795]: Aug 06 07:48:37.873 INFO Fetch successful Aug 6 07:48:37.880782 initrd-setup-root[845]: cut: /sysroot/etc/gshadow: No such file or directory Aug 6 07:48:37.889734 systemd[1]: flatcar-digitalocean-network.service: Deactivated successfully. Aug 6 07:48:37.891316 systemd[1]: Finished flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent. Aug 6 07:48:38.052113 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 6 07:48:38.056706 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 6 07:48:38.060765 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 6 07:48:38.078653 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 6 07:48:38.083029 kernel: BTRFS info (device vda6): last unmount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 6 07:48:38.118347 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 6 07:48:38.135627 ignition[914]: INFO : Ignition 2.18.0 Aug 6 07:48:38.136754 ignition[914]: INFO : Stage: mount Aug 6 07:48:38.137478 ignition[914]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 6 07:48:38.137478 ignition[914]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Aug 6 07:48:38.139550 ignition[914]: INFO : mount: mount passed Aug 6 07:48:38.139550 ignition[914]: INFO : Ignition finished successfully Aug 6 07:48:38.139819 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 6 07:48:38.147885 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 6 07:48:38.181843 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 6 07:48:38.201114 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (927) Aug 6 07:48:38.205049 kernel: BTRFS info (device vda6): first mount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 6 07:48:38.205130 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 6 07:48:38.206842 kernel: BTRFS info (device vda6): using free space tree Aug 6 07:48:38.214561 kernel: BTRFS info (device vda6): auto enabling async discard Aug 6 07:48:38.218882 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 6 07:48:38.254411 ignition[944]: INFO : Ignition 2.18.0 Aug 6 07:48:38.254411 ignition[944]: INFO : Stage: files Aug 6 07:48:38.256861 ignition[944]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 6 07:48:38.256861 ignition[944]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Aug 6 07:48:38.256861 ignition[944]: DEBUG : files: compiled without relabeling support, skipping Aug 6 07:48:38.260825 ignition[944]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 6 07:48:38.260825 ignition[944]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 6 07:48:38.265044 ignition[944]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 6 07:48:38.266680 ignition[944]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 6 07:48:38.268229 unknown[944]: wrote ssh authorized keys file for user: core Aug 6 07:48:38.269597 ignition[944]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 6 07:48:38.270593 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Aug 6 07:48:38.272984 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Aug 6 07:48:38.272984 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 6 07:48:38.272984 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 6 07:48:38.272984 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw" Aug 6 07:48:38.272984 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw" Aug 6 07:48:38.272984 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw" Aug 6 07:48:38.272984 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-x86-64.raw: attempt #1 Aug 6 07:48:38.294347 systemd-networkd[751]: eth0: Gained IPv6LL Aug 6 07:48:38.422296 systemd-networkd[751]: eth1: Gained IPv6LL Aug 6 07:48:38.653683 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Aug 6 07:48:39.058903 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw" Aug 6 07:48:39.062316 ignition[944]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 6 07:48:39.062316 ignition[944]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 6 07:48:39.062316 ignition[944]: INFO : files: files passed Aug 6 07:48:39.062316 ignition[944]: INFO : Ignition finished successfully Aug 6 07:48:39.063465 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 6 07:48:39.075288 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 6 07:48:39.088015 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 6 07:48:39.104182 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 6 07:48:39.104404 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 6 07:48:39.115040 initrd-setup-root-after-ignition[973]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 6 07:48:39.115040 initrd-setup-root-after-ignition[973]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 6 07:48:39.120417 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 6 07:48:39.121521 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 6 07:48:39.124199 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 6 07:48:39.131332 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 6 07:48:39.216821 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 6 07:48:39.217011 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 6 07:48:39.219445 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 6 07:48:39.221382 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 6 07:48:39.223875 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 6 07:48:39.231790 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 6 07:48:39.255415 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 6 07:48:39.272855 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 6 07:48:39.291152 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 6 07:48:39.292275 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 6 07:48:39.294056 systemd[1]: Stopped target timers.target - Timer Units. Aug 6 07:48:39.295567 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 6 07:48:39.295807 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 6 07:48:39.297138 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 6 07:48:39.297999 systemd[1]: Stopped target basic.target - Basic System. Aug 6 07:48:39.298781 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 6 07:48:39.299707 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 6 07:48:39.300593 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 6 07:48:39.301432 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 6 07:48:39.302221 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 6 07:48:39.303621 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 6 07:48:39.305098 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 6 07:48:39.306563 systemd[1]: Stopped target swap.target - Swaps. Aug 6 07:48:39.308124 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 6 07:48:39.308430 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 6 07:48:39.310037 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 6 07:48:39.311088 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 6 07:48:39.313119 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 6 07:48:39.313332 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 6 07:48:39.315002 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 6 07:48:39.315446 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 6 07:48:39.317539 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 6 07:48:39.317868 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 6 07:48:39.320092 systemd[1]: ignition-files.service: Deactivated successfully. Aug 6 07:48:39.320362 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 6 07:48:39.321573 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Aug 6 07:48:39.321834 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 6 07:48:39.330981 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 6 07:48:39.339804 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 6 07:48:39.340579 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 6 07:48:39.340798 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 6 07:48:39.346997 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 6 07:48:39.347240 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 6 07:48:39.358596 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 6 07:48:39.358746 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 6 07:48:39.376257 ignition[997]: INFO : Ignition 2.18.0 Aug 6 07:48:39.376257 ignition[997]: INFO : Stage: umount Aug 6 07:48:39.379975 ignition[997]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 6 07:48:39.379975 ignition[997]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Aug 6 07:48:39.379975 ignition[997]: INFO : umount: umount passed Aug 6 07:48:39.379975 ignition[997]: INFO : Ignition finished successfully Aug 6 07:48:39.380021 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 6 07:48:39.380216 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 6 07:48:39.384000 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 6 07:48:39.384235 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 6 07:48:39.386138 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 6 07:48:39.386240 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 6 07:48:39.388050 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 6 07:48:39.388145 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 6 07:48:39.389598 systemd[1]: Stopped target network.target - Network. Aug 6 07:48:39.391978 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 6 07:48:39.392200 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 6 07:48:39.394096 systemd[1]: Stopped target paths.target - Path Units. Aug 6 07:48:39.396816 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 6 07:48:39.401350 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 6 07:48:39.403451 systemd[1]: Stopped target slices.target - Slice Units. Aug 6 07:48:39.407793 systemd[1]: Stopped target sockets.target - Socket Units. Aug 6 07:48:39.409942 systemd[1]: iscsid.socket: Deactivated successfully. Aug 6 07:48:39.410018 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 6 07:48:39.410796 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 6 07:48:39.410876 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 6 07:48:39.411832 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 6 07:48:39.411925 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 6 07:48:39.413815 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 6 07:48:39.413931 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 6 07:48:39.415887 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 6 07:48:39.418709 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 6 07:48:39.421571 systemd-networkd[751]: eth1: DHCPv6 lease lost Aug 6 07:48:39.422195 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 6 07:48:39.423320 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 6 07:48:39.423541 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 6 07:48:39.424644 systemd-networkd[751]: eth0: DHCPv6 lease lost Aug 6 07:48:39.426738 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 6 07:48:39.426931 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 6 07:48:39.439601 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 6 07:48:39.439820 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 6 07:48:39.452033 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 6 07:48:39.452142 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 6 07:48:39.453463 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 6 07:48:39.453598 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 6 07:48:39.461040 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 6 07:48:39.462451 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 6 07:48:39.462639 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 6 07:48:39.464467 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 6 07:48:39.464586 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 6 07:48:39.468270 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 6 07:48:39.468362 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 6 07:48:39.470679 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 6 07:48:39.470786 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 6 07:48:39.474723 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 6 07:48:39.502064 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 6 07:48:39.502296 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 6 07:48:39.505306 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 6 07:48:39.505434 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 6 07:48:39.508431 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 6 07:48:39.508629 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 6 07:48:39.510629 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 6 07:48:39.510690 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 6 07:48:39.512239 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 6 07:48:39.512337 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 6 07:48:39.514466 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 6 07:48:39.514584 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 6 07:48:39.516112 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 6 07:48:39.516219 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 6 07:48:39.526777 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 6 07:48:39.527756 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 6 07:48:39.527852 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 6 07:48:39.530539 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 6 07:48:39.530626 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 6 07:48:39.533082 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 6 07:48:39.533169 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 6 07:48:39.533975 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 6 07:48:39.534060 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 6 07:48:39.539987 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 6 07:48:39.540137 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 6 07:48:39.542421 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 6 07:48:39.549859 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 6 07:48:39.567777 systemd[1]: Switching root. Aug 6 07:48:39.610627 systemd-journald[182]: Received SIGTERM from PID 1 (systemd). Aug 6 07:48:39.610746 systemd-journald[182]: Journal stopped Aug 6 07:48:41.375683 kernel: SELinux: policy capability network_peer_controls=1 Aug 6 07:48:41.375809 kernel: SELinux: policy capability open_perms=1 Aug 6 07:48:41.375833 kernel: SELinux: policy capability extended_socket_class=1 Aug 6 07:48:41.375853 kernel: SELinux: policy capability always_check_network=0 Aug 6 07:48:41.375875 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 6 07:48:41.375897 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 6 07:48:41.375919 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 6 07:48:41.375955 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 6 07:48:41.375979 kernel: audit: type=1403 audit(1722930519.865:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 6 07:48:41.376002 systemd[1]: Successfully loaded SELinux policy in 51.230ms. Aug 6 07:48:41.376038 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 20.411ms. Aug 6 07:48:41.376062 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 6 07:48:41.376083 systemd[1]: Detected virtualization kvm. Aug 6 07:48:41.376104 systemd[1]: Detected architecture x86-64. Aug 6 07:48:41.376131 systemd[1]: Detected first boot. Aug 6 07:48:41.376157 systemd[1]: Hostname set to . Aug 6 07:48:41.376176 systemd[1]: Initializing machine ID from VM UUID. Aug 6 07:48:41.376195 zram_generator::config[1040]: No configuration found. Aug 6 07:48:41.376222 systemd[1]: Populated /etc with preset unit settings. Aug 6 07:48:41.376243 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 6 07:48:41.376265 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 6 07:48:41.376287 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 6 07:48:41.376310 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 6 07:48:41.376336 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 6 07:48:41.376361 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 6 07:48:41.376384 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 6 07:48:41.376407 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 6 07:48:41.376431 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 6 07:48:41.376454 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 6 07:48:41.376478 systemd[1]: Created slice user.slice - User and Session Slice. Aug 6 07:48:41.376517 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 6 07:48:41.376555 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 6 07:48:41.376583 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 6 07:48:41.376614 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 6 07:48:41.376640 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 6 07:48:41.376663 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 6 07:48:41.376687 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 6 07:48:41.376711 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 6 07:48:41.376735 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 6 07:48:41.376756 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 6 07:48:41.376783 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 6 07:48:41.376804 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 6 07:48:41.376825 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 6 07:48:41.376846 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 6 07:48:41.376867 systemd[1]: Reached target slices.target - Slice Units. Aug 6 07:48:41.376888 systemd[1]: Reached target swap.target - Swaps. Aug 6 07:48:41.376910 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 6 07:48:41.376936 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 6 07:48:41.376957 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 6 07:48:41.376981 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 6 07:48:41.377007 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 6 07:48:41.377030 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 6 07:48:41.377053 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 6 07:48:41.377077 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 6 07:48:41.377098 systemd[1]: Mounting media.mount - External Media Directory... Aug 6 07:48:41.377123 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:41.377150 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 6 07:48:41.377172 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 6 07:48:41.377198 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 6 07:48:41.377220 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Aug 6 07:48:41.377243 systemd[1]: Reached target machines.target - Containers. Aug 6 07:48:41.377266 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 6 07:48:41.377290 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 6 07:48:41.377314 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 6 07:48:41.377373 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 6 07:48:41.377402 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 6 07:48:41.377423 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 6 07:48:41.377445 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 6 07:48:41.377466 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 6 07:48:41.377490 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 6 07:48:41.377598 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 6 07:48:41.377622 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 6 07:48:41.377645 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 6 07:48:41.377671 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 6 07:48:41.377692 systemd[1]: Stopped systemd-fsck-usr.service. Aug 6 07:48:41.377713 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 6 07:48:41.377734 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 6 07:48:41.377756 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 6 07:48:41.377777 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 6 07:48:41.377799 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 6 07:48:41.377821 systemd[1]: verity-setup.service: Deactivated successfully. Aug 6 07:48:41.377841 systemd[1]: Stopped verity-setup.service. Aug 6 07:48:41.377868 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:41.377892 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 6 07:48:41.377915 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 6 07:48:41.377957 systemd[1]: Mounted media.mount - External Media Directory. Aug 6 07:48:41.377981 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 6 07:48:41.378008 kernel: fuse: init (API version 7.39) Aug 6 07:48:41.378030 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 6 07:48:41.378052 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 6 07:48:41.378073 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 6 07:48:41.378098 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 6 07:48:41.378122 kernel: loop: module loaded Aug 6 07:48:41.378142 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 6 07:48:41.378164 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 6 07:48:41.378185 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 6 07:48:41.378208 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 6 07:48:41.378230 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 6 07:48:41.378256 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 6 07:48:41.378280 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 6 07:48:41.378304 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 6 07:48:41.378333 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 6 07:48:41.378356 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 6 07:48:41.378379 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 6 07:48:41.378402 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 6 07:48:41.378424 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 6 07:48:41.378457 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 6 07:48:41.378479 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 6 07:48:41.378516 kernel: ACPI: bus type drm_connector registered Aug 6 07:48:41.378540 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 6 07:48:41.378628 systemd-journald[1108]: Collecting audit messages is disabled. Aug 6 07:48:41.378673 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 6 07:48:41.378698 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 6 07:48:41.378720 systemd-journald[1108]: Journal started Aug 6 07:48:41.378761 systemd-journald[1108]: Runtime Journal (/run/log/journal/32a825204b9747b2bcd96d532ea402dc) is 4.9M, max 39.3M, 34.4M free. Aug 6 07:48:40.820029 systemd[1]: Queued start job for default target multi-user.target. Aug 6 07:48:40.846833 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 6 07:48:40.847707 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 6 07:48:41.380670 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 6 07:48:41.386543 systemd[1]: Started systemd-journald.service - Journal Service. Aug 6 07:48:41.394461 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 6 07:48:41.397768 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 6 07:48:41.400846 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 6 07:48:41.436705 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 6 07:48:41.440378 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 6 07:48:41.440459 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 6 07:48:41.448160 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 6 07:48:41.457750 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 6 07:48:41.465747 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 6 07:48:41.466594 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 6 07:48:41.476781 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 6 07:48:41.483307 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 6 07:48:41.485449 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 6 07:48:41.488752 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 6 07:48:41.499669 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 6 07:48:41.502236 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 6 07:48:41.513677 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 6 07:48:41.525715 systemd-tmpfiles[1133]: ACLs are not supported, ignoring. Aug 6 07:48:41.525767 systemd-tmpfiles[1133]: ACLs are not supported, ignoring. Aug 6 07:48:41.554557 kernel: loop0: detected capacity change from 0 to 211296 Aug 6 07:48:41.556158 systemd-journald[1108]: Time spent on flushing to /var/log/journal/32a825204b9747b2bcd96d532ea402dc is 74.751ms for 976 entries. Aug 6 07:48:41.556158 systemd-journald[1108]: System Journal (/var/log/journal/32a825204b9747b2bcd96d532ea402dc) is 8.0M, max 195.6M, 187.6M free. Aug 6 07:48:41.667809 systemd-journald[1108]: Received client request to flush runtime journal. Aug 6 07:48:41.667899 kernel: block loop0: the capability attribute has been deprecated. Aug 6 07:48:41.668039 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 6 07:48:41.558832 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 6 07:48:41.672713 kernel: loop1: detected capacity change from 0 to 8 Aug 6 07:48:41.568935 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 6 07:48:41.573044 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 6 07:48:41.576808 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 6 07:48:41.591865 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 6 07:48:41.603108 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 6 07:48:41.616916 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 6 07:48:41.670285 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 6 07:48:41.697005 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 6 07:48:41.699051 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 6 07:48:41.713559 kernel: loop2: detected capacity change from 0 to 139904 Aug 6 07:48:41.712848 udevadm[1173]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Aug 6 07:48:41.741048 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 6 07:48:41.752995 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 6 07:48:41.775555 kernel: loop3: detected capacity change from 0 to 80568 Aug 6 07:48:41.802974 systemd-tmpfiles[1183]: ACLs are not supported, ignoring. Aug 6 07:48:41.803038 systemd-tmpfiles[1183]: ACLs are not supported, ignoring. Aug 6 07:48:41.816088 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 6 07:48:41.845906 kernel: loop4: detected capacity change from 0 to 211296 Aug 6 07:48:41.889561 kernel: loop5: detected capacity change from 0 to 8 Aug 6 07:48:41.894643 kernel: loop6: detected capacity change from 0 to 139904 Aug 6 07:48:41.953046 kernel: loop7: detected capacity change from 0 to 80568 Aug 6 07:48:41.975151 (sd-merge)[1187]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. Aug 6 07:48:41.976604 (sd-merge)[1187]: Merged extensions into '/usr'. Aug 6 07:48:41.990936 systemd[1]: Reloading requested from client PID 1165 ('systemd-sysext') (unit systemd-sysext.service)... Aug 6 07:48:41.990978 systemd[1]: Reloading... Aug 6 07:48:42.166532 zram_generator::config[1208]: No configuration found. Aug 6 07:48:42.515173 ldconfig[1160]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 6 07:48:42.568089 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 6 07:48:42.695364 systemd[1]: Reloading finished in 703 ms. Aug 6 07:48:42.726546 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 6 07:48:42.729066 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 6 07:48:42.745859 systemd[1]: Starting ensure-sysext.service... Aug 6 07:48:42.751812 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 6 07:48:42.773702 systemd[1]: Reloading requested from client PID 1254 ('systemctl') (unit ensure-sysext.service)... Aug 6 07:48:42.773728 systemd[1]: Reloading... Aug 6 07:48:42.842994 systemd-tmpfiles[1255]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 6 07:48:42.847877 systemd-tmpfiles[1255]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 6 07:48:42.851864 systemd-tmpfiles[1255]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 6 07:48:42.852553 systemd-tmpfiles[1255]: ACLs are not supported, ignoring. Aug 6 07:48:42.852676 systemd-tmpfiles[1255]: ACLs are not supported, ignoring. Aug 6 07:48:42.865919 systemd-tmpfiles[1255]: Detected autofs mount point /boot during canonicalization of boot. Aug 6 07:48:42.865935 systemd-tmpfiles[1255]: Skipping /boot Aug 6 07:48:42.909118 systemd-tmpfiles[1255]: Detected autofs mount point /boot during canonicalization of boot. Aug 6 07:48:42.909142 systemd-tmpfiles[1255]: Skipping /boot Aug 6 07:48:42.937535 zram_generator::config[1280]: No configuration found. Aug 6 07:48:43.192912 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 6 07:48:43.312873 systemd[1]: Reloading finished in 538 ms. Aug 6 07:48:43.332179 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 6 07:48:43.345397 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 6 07:48:43.373050 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 6 07:48:43.377955 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 6 07:48:43.380961 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 6 07:48:43.387401 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 6 07:48:43.393955 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 6 07:48:43.399028 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 6 07:48:43.403782 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:43.404226 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 6 07:48:43.412137 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 6 07:48:43.420035 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 6 07:48:43.424918 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 6 07:48:43.425919 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 6 07:48:43.426198 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:43.432012 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:43.432393 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 6 07:48:43.432724 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 6 07:48:43.432924 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:43.442674 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:43.443233 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 6 07:48:43.454777 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 6 07:48:43.457802 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 6 07:48:43.468812 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 6 07:48:43.469662 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:43.470371 systemd[1]: Finished ensure-sysext.service. Aug 6 07:48:43.471897 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 6 07:48:43.473832 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 6 07:48:43.481360 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 6 07:48:43.492478 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 6 07:48:43.503582 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 6 07:48:43.505130 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 6 07:48:43.521472 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 6 07:48:43.522603 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 6 07:48:43.532812 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 6 07:48:43.534347 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 6 07:48:43.536751 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 6 07:48:43.540416 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 6 07:48:43.552980 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 6 07:48:43.553847 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Aug 6 07:48:43.554477 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 6 07:48:43.554741 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 6 07:48:43.563813 systemd-udevd[1334]: Using default interface naming scheme 'v255'. Aug 6 07:48:43.607211 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 6 07:48:43.609594 augenrules[1362]: No rules Aug 6 07:48:43.611970 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 6 07:48:43.618087 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 6 07:48:43.628902 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 6 07:48:43.640875 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 6 07:48:43.753663 systemd-networkd[1372]: lo: Link UP Aug 6 07:48:43.754225 systemd-networkd[1372]: lo: Gained carrier Aug 6 07:48:43.755702 systemd-networkd[1372]: Enumeration completed Aug 6 07:48:43.756054 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 6 07:48:43.766873 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 6 07:48:43.838494 systemd-resolved[1330]: Positive Trust Anchors: Aug 6 07:48:43.840560 systemd-resolved[1330]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 6 07:48:43.840687 systemd-resolved[1330]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 6 07:48:43.864321 systemd-resolved[1330]: Using system hostname 'ci-3975.2.0-3-5d52add573'. Aug 6 07:48:43.876184 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 6 07:48:43.878162 systemd[1]: Reached target network.target - Network. Aug 6 07:48:43.879541 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 6 07:48:43.881752 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 6 07:48:43.926542 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1370) Aug 6 07:48:43.931725 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 6 07:48:43.933748 systemd[1]: Reached target time-set.target - System Time Set. Aug 6 07:48:43.954582 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1381) Aug 6 07:48:44.004856 systemd-networkd[1372]: eth1: Configuring with /run/systemd/network/10-7a:b1:5c:46:b6:4e.network. Aug 6 07:48:44.006214 systemd-networkd[1372]: eth1: Link UP Aug 6 07:48:44.006583 systemd-networkd[1372]: eth1: Gained carrier Aug 6 07:48:44.012746 systemd-timesyncd[1346]: Network configuration changed, trying to establish connection. Aug 6 07:48:44.017730 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... Aug 6 07:48:44.018458 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:44.018775 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 6 07:48:44.025994 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 6 07:48:44.032821 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 6 07:48:44.046803 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 6 07:48:44.048757 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 6 07:48:44.048836 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Aug 6 07:48:44.048869 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 6 07:48:44.054830 kernel: ISO 9660 Extensions: RRIP_1991A Aug 6 07:48:44.059251 systemd-networkd[1372]: eth0: Configuring with /run/systemd/network/10-46:ac:3c:e2:0a:48.network. Aug 6 07:48:44.060108 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. Aug 6 07:48:44.060474 systemd-networkd[1372]: eth0: Link UP Aug 6 07:48:44.060658 systemd-networkd[1372]: eth0: Gained carrier Aug 6 07:48:44.096649 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Aug 6 07:48:44.099860 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 6 07:48:44.101795 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 6 07:48:44.110899 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 6 07:48:44.111880 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 6 07:48:44.122580 kernel: ACPI: button: Power Button [PWRF] Aug 6 07:48:44.123663 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 6 07:48:44.125557 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 6 07:48:44.125774 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 6 07:48:44.137796 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 6 07:48:44.139642 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 6 07:48:44.139717 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 6 07:48:44.149554 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Aug 6 07:48:44.177390 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Aug 6 07:48:44.176299 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 6 07:48:44.272547 kernel: mousedev: PS/2 mouse device common for all mice Aug 6 07:48:44.283999 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 6 07:48:44.315976 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Aug 6 07:48:44.316087 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Aug 6 07:48:44.333455 kernel: Console: switching to colour dummy device 80x25 Aug 6 07:48:44.333553 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Aug 6 07:48:44.333594 kernel: [drm] features: -context_init Aug 6 07:48:44.333609 kernel: [drm] number of scanouts: 1 Aug 6 07:48:44.333633 kernel: [drm] number of cap sets: 0 Aug 6 07:48:44.337556 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Aug 6 07:48:44.406737 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Aug 6 07:48:44.406839 kernel: Console: switching to colour frame buffer device 128x48 Aug 6 07:48:44.406873 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Aug 6 07:48:44.426124 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 6 07:48:44.428968 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 6 07:48:44.443063 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 6 07:48:44.451531 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 6 07:48:44.451979 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 6 07:48:44.470887 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 6 07:48:44.527720 kernel: EDAC MC: Ver: 3.0.0 Aug 6 07:48:44.554267 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 6 07:48:44.563678 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 6 07:48:44.588941 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 6 07:48:44.608674 lvm[1437]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 6 07:48:44.654241 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 6 07:48:44.656113 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 6 07:48:44.656306 systemd[1]: Reached target sysinit.target - System Initialization. Aug 6 07:48:44.656702 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 6 07:48:44.656877 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 6 07:48:44.657303 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 6 07:48:44.658481 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 6 07:48:44.660956 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 6 07:48:44.661493 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 6 07:48:44.661611 systemd[1]: Reached target paths.target - Path Units. Aug 6 07:48:44.661727 systemd[1]: Reached target timers.target - Timer Units. Aug 6 07:48:44.664705 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 6 07:48:44.669538 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 6 07:48:44.678786 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 6 07:48:44.683546 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 6 07:48:44.686261 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 6 07:48:44.689263 systemd[1]: Reached target sockets.target - Socket Units. Aug 6 07:48:44.689862 systemd[1]: Reached target basic.target - Basic System. Aug 6 07:48:44.690518 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 6 07:48:44.691604 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 6 07:48:44.696803 systemd[1]: Starting containerd.service - containerd container runtime... Aug 6 07:48:44.713640 lvm[1441]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 6 07:48:44.714880 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 6 07:48:44.726908 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 6 07:48:44.736740 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 6 07:48:44.746834 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 6 07:48:44.747686 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Aug 6 07:48:44.762298 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 6 07:48:44.768843 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 6 07:48:44.776841 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 6 07:48:44.791811 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 6 07:48:44.795334 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 6 07:48:44.796216 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 6 07:48:44.803832 systemd[1]: Starting update-engine.service - Update Engine... Aug 6 07:48:44.809999 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 6 07:48:44.818338 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 6 07:48:44.843704 jq[1445]: false Aug 6 07:48:44.844105 coreos-metadata[1443]: Aug 06 07:48:44.839 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Aug 6 07:48:44.834264 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 6 07:48:44.835944 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 6 07:48:44.845056 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 6 07:48:44.845362 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 6 07:48:44.865133 coreos-metadata[1443]: Aug 06 07:48:44.858 INFO Fetch successful Aug 6 07:48:44.878656 dbus-daemon[1444]: [system] SELinux support is enabled Aug 6 07:48:44.879258 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 6 07:48:44.893640 jq[1455]: true Aug 6 07:48:44.910218 systemd[1]: motdgen.service: Deactivated successfully. Aug 6 07:48:44.910650 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 6 07:48:44.919578 extend-filesystems[1448]: Found loop4 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found loop5 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found loop6 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found loop7 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found vda Aug 6 07:48:44.919578 extend-filesystems[1448]: Found vda1 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found vda2 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found vda3 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found usr Aug 6 07:48:44.919578 extend-filesystems[1448]: Found vda4 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found vda6 Aug 6 07:48:44.919578 extend-filesystems[1448]: Found vda7 Aug 6 07:48:44.982191 extend-filesystems[1448]: Found vda9 Aug 6 07:48:44.982191 extend-filesystems[1448]: Checking size of /dev/vda9 Aug 6 07:48:44.983311 update_engine[1454]: I0806 07:48:44.938020 1454 main.cc:92] Flatcar Update Engine starting Aug 6 07:48:44.983311 update_engine[1454]: I0806 07:48:44.955116 1454 update_check_scheduler.cc:74] Next update check in 10m45s Aug 6 07:48:44.925856 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Aug 6 07:48:44.983917 jq[1470]: true Aug 6 07:48:44.925924 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 6 07:48:44.945977 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Aug 6 07:48:44.946191 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean). Aug 6 07:48:44.946234 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 6 07:48:44.959292 systemd[1]: Started update-engine.service - Update Engine. Aug 6 07:48:44.976401 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 6 07:48:44.980260 (ntainerd)[1471]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 6 07:48:45.023565 extend-filesystems[1448]: Resized partition /dev/vda9 Aug 6 07:48:45.032262 extend-filesystems[1486]: resize2fs 1.47.0 (5-Feb-2023) Aug 6 07:48:45.045562 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1386) Aug 6 07:48:45.052650 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks Aug 6 07:48:45.078050 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 6 07:48:45.095057 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 6 07:48:45.265941 systemd-logind[1452]: New seat seat0. Aug 6 07:48:45.268114 systemd-logind[1452]: Watching system buttons on /dev/input/event1 (Power Button) Aug 6 07:48:45.268147 systemd-logind[1452]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 6 07:48:45.268570 systemd[1]: Started systemd-logind.service - User Login Management. Aug 6 07:48:45.300646 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Aug 6 07:48:45.329840 extend-filesystems[1486]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Aug 6 07:48:45.329840 extend-filesystems[1486]: old_desc_blocks = 1, new_desc_blocks = 8 Aug 6 07:48:45.329840 extend-filesystems[1486]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Aug 6 07:48:45.335188 extend-filesystems[1448]: Resized filesystem in /dev/vda9 Aug 6 07:48:45.335188 extend-filesystems[1448]: Found vdb Aug 6 07:48:45.330840 locksmithd[1479]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 6 07:48:45.340614 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 6 07:48:45.341013 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 6 07:48:45.345976 bash[1502]: Updated "/home/core/.ssh/authorized_keys" Aug 6 07:48:45.352214 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 6 07:48:45.370990 systemd[1]: Starting sshkeys.service... Aug 6 07:48:45.411400 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Aug 6 07:48:45.424140 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Aug 6 07:48:45.465979 coreos-metadata[1516]: Aug 06 07:48:45.465 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Aug 6 07:48:45.482658 coreos-metadata[1516]: Aug 06 07:48:45.482 INFO Fetch successful Aug 6 07:48:45.517053 unknown[1516]: wrote ssh authorized keys file for user: core Aug 6 07:48:45.528480 systemd-networkd[1372]: eth0: Gained IPv6LL Aug 6 07:48:45.542631 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 6 07:48:45.547260 systemd[1]: Reached target network-online.target - Network is Online. Aug 6 07:48:45.573122 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 6 07:48:45.584983 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 6 07:48:45.587044 update-ssh-keys[1521]: Updated "/home/core/.ssh/authorized_keys" Aug 6 07:48:45.591370 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Aug 6 07:48:45.601630 systemd[1]: Finished sshkeys.service. Aug 6 07:48:45.702762 containerd[1471]: time="2024-08-06T07:48:45.702563265Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Aug 6 07:48:45.718591 systemd-networkd[1372]: eth1: Gained IPv6LL Aug 6 07:48:45.718796 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 6 07:48:45.801915 containerd[1471]: time="2024-08-06T07:48:45.801839155Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 6 07:48:45.802450 containerd[1471]: time="2024-08-06T07:48:45.802215537Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 6 07:48:45.807715 containerd[1471]: time="2024-08-06T07:48:45.807178800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.43-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 6 07:48:45.807715 containerd[1471]: time="2024-08-06T07:48:45.807251714Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 6 07:48:45.807715 containerd[1471]: time="2024-08-06T07:48:45.807658471Z" 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 Aug 6 07:48:45.810133 containerd[1471]: time="2024-08-06T07:48:45.808879321Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 6 07:48:45.810133 containerd[1471]: time="2024-08-06T07:48:45.809089367Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 6 07:48:45.810133 containerd[1471]: time="2024-08-06T07:48:45.809158204Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 6 07:48:45.810133 containerd[1471]: time="2024-08-06T07:48:45.809176242Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 6 07:48:45.810133 containerd[1471]: time="2024-08-06T07:48:45.809247184Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 6 07:48:45.810472 containerd[1471]: time="2024-08-06T07:48:45.809493362Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 6 07:48:45.811636 containerd[1471]: time="2024-08-06T07:48:45.811587809Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Aug 6 07:48:45.811798 containerd[1471]: time="2024-08-06T07:48:45.811765890Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 6 07:48:45.812182 containerd[1471]: time="2024-08-06T07:48:45.812144828Z" 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 Aug 6 07:48:45.812329 containerd[1471]: time="2024-08-06T07:48:45.812304161Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 6 07:48:45.814469 containerd[1471]: time="2024-08-06T07:48:45.814216982Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Aug 6 07:48:45.814469 containerd[1471]: time="2024-08-06T07:48:45.814246043Z" level=info msg="metadata content store policy set" policy=shared Aug 6 07:48:45.833213 containerd[1471]: time="2024-08-06T07:48:45.833154339Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 6 07:48:45.833468 containerd[1471]: time="2024-08-06T07:48:45.833435635Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 6 07:48:45.833637 containerd[1471]: time="2024-08-06T07:48:45.833603970Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 6 07:48:45.834036 containerd[1471]: time="2024-08-06T07:48:45.833764665Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 6 07:48:45.834036 containerd[1471]: time="2024-08-06T07:48:45.833788901Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 6 07:48:45.834036 containerd[1471]: time="2024-08-06T07:48:45.833886807Z" level=info msg="NRI interface is disabled by configuration." Aug 6 07:48:45.834036 containerd[1471]: time="2024-08-06T07:48:45.833907282Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835060193Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835093111Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835124381Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835147713Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835169353Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835193696Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835214833Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835233958Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835254425Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835275111Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835294679Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835322064Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 6 07:48:45.836374 containerd[1471]: time="2024-08-06T07:48:45.835518598Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.835929887Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.835993509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836018058Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836065385Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836155708Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836178135Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836197593Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836215914Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836237516Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836256471Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836276716Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836297773Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836318811Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 6 07:48:45.836950 containerd[1471]: time="2024-08-06T07:48:45.836611349Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837321 containerd[1471]: time="2024-08-06T07:48:45.836648997Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837321 containerd[1471]: time="2024-08-06T07:48:45.836674615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837321 containerd[1471]: time="2024-08-06T07:48:45.836702658Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837321 containerd[1471]: time="2024-08-06T07:48:45.836735921Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837321 containerd[1471]: time="2024-08-06T07:48:45.836764146Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837321 containerd[1471]: time="2024-08-06T07:48:45.836801890Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837321 containerd[1471]: time="2024-08-06T07:48:45.836823698Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 6 07:48:45.837466 containerd[1471]: time="2024-08-06T07:48:45.837224583Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Aug 6 07:48:45.837466 containerd[1471]: time="2024-08-06T07:48:45.837334744Z" level=info msg="Connect containerd service" Aug 6 07:48:45.837466 containerd[1471]: time="2024-08-06T07:48:45.837381915Z" level=info msg="using legacy CRI server" Aug 6 07:48:45.837466 containerd[1471]: time="2024-08-06T07:48:45.837391811Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 6 07:48:45.840561 containerd[1471]: time="2024-08-06T07:48:45.837488739Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 6 07:48:45.841601 containerd[1471]: time="2024-08-06T07:48:45.841356241Z" 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" Aug 6 07:48:45.841601 containerd[1471]: time="2024-08-06T07:48:45.841433772Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 6 07:48:45.841601 containerd[1471]: time="2024-08-06T07:48:45.841460845Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Aug 6 07:48:45.841601 containerd[1471]: time="2024-08-06T07:48:45.841477353Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 6 07:48:45.841601 containerd[1471]: time="2024-08-06T07:48:45.841495052Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841691339Z" level=info msg="Start subscribing containerd event" Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841794998Z" level=info msg="Start recovering state" Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841865750Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841895671Z" level=info msg="Start event monitor" Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841913029Z" level=info msg="Start snapshots syncer" Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841925533Z" level=info msg="Start cni network conf syncer for default" Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841945501Z" level=info msg="Start streaming server" Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.841915905Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 6 07:48:45.845840 containerd[1471]: time="2024-08-06T07:48:45.842121165Z" level=info msg="containerd successfully booted in 0.140694s" Aug 6 07:48:45.843667 systemd[1]: Started containerd.service - containerd container runtime. Aug 6 07:48:45.861203 sshd_keygen[1477]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 6 07:48:45.906266 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 6 07:48:45.921087 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 6 07:48:45.933944 systemd[1]: issuegen.service: Deactivated successfully. Aug 6 07:48:45.934136 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 6 07:48:45.945576 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 6 07:48:45.968692 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 6 07:48:45.978064 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 6 07:48:45.988163 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 6 07:48:45.989064 systemd[1]: Reached target getty.target - Login Prompts. Aug 6 07:48:47.044883 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 6 07:48:47.047298 (kubelet)[1559]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 6 07:48:47.048430 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 6 07:48:47.051649 systemd[1]: Startup finished in 1.443s (kernel) + 6.122s (initrd) + 7.235s (userspace) = 14.801s. Aug 6 07:48:48.063305 kubelet[1559]: E0806 07:48:48.063163 1559 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" Aug 6 07:48:48.066633 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 6 07:48:48.067077 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 6 07:48:48.067532 systemd[1]: kubelet.service: Consumed 1.509s CPU time. Aug 6 07:48:50.206265 systemd-timesyncd[1346]: Contacted time server 162.159.200.123:123 (1.flatcar.pool.ntp.org). Aug 6 07:48:50.206349 systemd-timesyncd[1346]: Initial clock synchronization to Tue 2024-08-06 07:48:50.208449 UTC. Aug 6 07:48:54.261951 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 6 07:48:54.270070 systemd[1]: Started sshd@0-64.23.172.146:22-147.75.109.163:47638.service - OpenSSH per-connection server daemon (147.75.109.163:47638). Aug 6 07:48:54.360152 sshd[1572]: Accepted publickey for core from 147.75.109.163 port 47638 ssh2: RSA SHA256:SPvEXBcgWiyzsQCUAYKdH6mATUwSf1+92OLxtqkDC6k Aug 6 07:48:54.364239 sshd[1572]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 6 07:48:54.378976 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 6 07:48:54.385070 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 6 07:48:54.391237 systemd-logind[1452]: New session 1 of user core. Aug 6 07:48:54.411073 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 6 07:48:54.419249 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 6 07:48:54.427676 (systemd)[1576]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 6 07:48:54.593172 systemd[1576]: Queued start job for default target default.target. Aug 6 07:48:54.605082 systemd[1576]: Created slice app.slice - User Application Slice. Aug 6 07:48:54.605140 systemd[1576]: Reached target paths.target - Paths. Aug 6 07:48:54.605168 systemd[1576]: Reached target timers.target - Timers. Aug 6 07:48:54.607365 systemd[1576]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 6 07:48:54.630453 systemd[1576]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 6 07:48:54.630637 systemd[1576]: Reached target sockets.target - Sockets. Aug 6 07:48:54.630666 systemd[1576]: Reached target basic.target - Basic System. Aug 6 07:48:54.630741 systemd[1576]: Reached target default.target - Main User Target. Aug 6 07:48:54.630783 systemd[1576]: Startup finished in 192ms. Aug 6 07:48:54.630955 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 6 07:48:54.638907 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 6 07:48:54.714920 systemd[1]: Started sshd@1-64.23.172.146:22-147.75.109.163:47654.service - OpenSSH per-connection server daemon (147.75.109.163:47654). Aug 6 07:48:54.781549 sshd[1587]: Accepted publickey for core from 147.75.109.163 port 47654 ssh2: RSA SHA256:SPvEXBcgWiyzsQCUAYKdH6mATUwSf1+92OLxtqkDC6k Aug 6 07:48:54.783939 sshd[1587]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 6 07:48:54.793242 systemd-logind[1452]: New session 2 of user core. Aug 6 07:48:54.800892 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 6 07:48:54.868906 sshd[1587]: pam_unix(sshd:session): session closed for user core Aug 6 07:48:54.886114 systemd[1]: sshd@1-64.23.172.146:22-147.75.109.163:47654.service: Deactivated successfully. Aug 6 07:48:54.889250 systemd[1]: session-2.scope: Deactivated successfully. Aug 6 07:48:54.891694 systemd-logind[1452]: Session 2 logged out. Waiting for processes to exit. Aug 6 07:48:54.896063 systemd[1]: Started sshd@2-64.23.172.146:22-147.75.109.163:47664.service - OpenSSH per-connection server daemon (147.75.109.163:47664). Aug 6 07:48:54.898160 systemd-logind[1452]: Removed session 2. Aug 6 07:48:54.958377 sshd[1594]: Accepted publickey for core from 147.75.109.163 port 47664 ssh2: RSA SHA256:SPvEXBcgWiyzsQCUAYKdH6mATUwSf1+92OLxtqkDC6k Aug 6 07:48:54.960533 sshd[1594]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 6 07:48:54.967266 systemd-logind[1452]: New session 3 of user core. Aug 6 07:48:54.981865 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 6 07:48:55.041379 sshd[1594]: pam_unix(sshd:session): session closed for user core Aug 6 07:48:55.055964 systemd[1]: sshd@2-64.23.172.146:22-147.75.109.163:47664.service: Deactivated successfully. Aug 6 07:48:55.059286 systemd[1]: session-3.scope: Deactivated successfully. Aug 6 07:48:55.061981 systemd-logind[1452]: Session 3 logged out. Waiting for processes to exit. Aug 6 07:48:55.070090 systemd[1]: Started sshd@3-64.23.172.146:22-147.75.109.163:47680.service - OpenSSH per-connection server daemon (147.75.109.163:47680). Aug 6 07:48:55.073416 systemd-logind[1452]: Removed session 3. Aug 6 07:48:55.121302 sshd[1601]: Accepted publickey for core from 147.75.109.163 port 47680 ssh2: RSA SHA256:SPvEXBcgWiyzsQCUAYKdH6mATUwSf1+92OLxtqkDC6k Aug 6 07:48:55.123601 sshd[1601]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 6 07:48:55.130928 systemd-logind[1452]: New session 4 of user core. Aug 6 07:48:55.140849 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 6 07:48:55.208749 sshd[1601]: pam_unix(sshd:session): session closed for user core Aug 6 07:48:55.222091 systemd[1]: sshd@3-64.23.172.146:22-147.75.109.163:47680.service: Deactivated successfully. Aug 6 07:48:55.224930 systemd[1]: session-4.scope: Deactivated successfully. Aug 6 07:48:55.226309 systemd-logind[1452]: Session 4 logged out. Waiting for processes to exit. Aug 6 07:48:55.233084 systemd[1]: Started sshd@4-64.23.172.146:22-147.75.109.163:47692.service - OpenSSH per-connection server daemon (147.75.109.163:47692). Aug 6 07:48:55.235897 systemd-logind[1452]: Removed session 4. Aug 6 07:48:55.296593 sshd[1608]: Accepted publickey for core from 147.75.109.163 port 47692 ssh2: RSA SHA256:SPvEXBcgWiyzsQCUAYKdH6mATUwSf1+92OLxtqkDC6k Aug 6 07:48:55.299836 sshd[1608]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 6 07:48:55.308784 systemd-logind[1452]: New session 5 of user core. Aug 6 07:48:55.319878 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 6 07:48:55.399779 sudo[1611]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 6 07:48:55.400246 sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 6 07:48:56.376836 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 6 07:48:56.377875 systemd[1]: kubelet.service: Consumed 1.509s CPU time. Aug 6 07:48:56.389072 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 6 07:48:56.438068 systemd[1]: Reloading requested from client PID 1650 ('systemctl') (unit session-5.scope)... Aug 6 07:48:56.438090 systemd[1]: Reloading... Aug 6 07:48:56.588536 zram_generator::config[1686]: No configuration found. Aug 6 07:48:56.782330 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 6 07:48:56.916110 systemd[1]: Reloading finished in 477 ms. Aug 6 07:48:56.985368 systemd[1]: kubelet.service: Deactivated successfully. Aug 6 07:48:56.986038 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 6 07:48:56.999900 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 6 07:48:57.156831 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 6 07:48:57.167182 (kubelet)[1741]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 6 07:48:57.248869 kubelet[1741]: 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. Aug 6 07:48:57.248869 kubelet[1741]: 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. Aug 6 07:48:57.248869 kubelet[1741]: 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. Aug 6 07:48:57.248869 kubelet[1741]: I0806 07:48:57.248711 1741 server.go:204] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 6 07:48:57.557289 kubelet[1741]: I0806 07:48:57.555656 1741 server.go:487] "Kubelet version" kubeletVersion="v1.29.2" Aug 6 07:48:57.557289 kubelet[1741]: I0806 07:48:57.555694 1741 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 6 07:48:57.557289 kubelet[1741]: I0806 07:48:57.555997 1741 server.go:919] "Client rotation is on, will bootstrap in background" Aug 6 07:48:57.584082 kubelet[1741]: I0806 07:48:57.584039 1741 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 6 07:48:57.605472 kubelet[1741]: I0806 07:48:57.605000 1741 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 6 07:48:57.607583 kubelet[1741]: I0806 07:48:57.606961 1741 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 6 07:48:57.607883 kubelet[1741]: I0806 07:48:57.607844 1741 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"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},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Aug 6 07:48:57.608397 kubelet[1741]: I0806 07:48:57.608352 1741 topology_manager.go:138] "Creating topology manager with none policy" Aug 6 07:48:57.608397 kubelet[1741]: I0806 07:48:57.608388 1741 container_manager_linux.go:301] "Creating device plugin manager" Aug 6 07:48:57.608569 kubelet[1741]: I0806 07:48:57.608552 1741 state_mem.go:36] "Initialized new in-memory state store" Aug 6 07:48:57.609539 kubelet[1741]: I0806 07:48:57.608991 1741 kubelet.go:396] "Attempting to sync node with API server" Aug 6 07:48:57.609539 kubelet[1741]: I0806 07:48:57.609037 1741 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 6 07:48:57.609539 kubelet[1741]: I0806 07:48:57.609076 1741 kubelet.go:312] "Adding apiserver pod source" Aug 6 07:48:57.609539 kubelet[1741]: I0806 07:48:57.609097 1741 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 6 07:48:57.610537 kubelet[1741]: E0806 07:48:57.610481 1741 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:48:57.610703 kubelet[1741]: E0806 07:48:57.610685 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:48:57.611901 kubelet[1741]: I0806 07:48:57.611882 1741 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="v1.7.17" apiVersion="v1" Aug 6 07:48:57.615885 kubelet[1741]: I0806 07:48:57.615844 1741 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Aug 6 07:48:57.616142 kubelet[1741]: W0806 07:48:57.616128 1741 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 6 07:48:57.618097 kubelet[1741]: I0806 07:48:57.618062 1741 server.go:1256] "Started kubelet" Aug 6 07:48:57.621381 kubelet[1741]: I0806 07:48:57.621334 1741 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Aug 6 07:48:57.623143 kubelet[1741]: I0806 07:48:57.622438 1741 server.go:461] "Adding debug handlers to kubelet server" Aug 6 07:48:57.625897 kubelet[1741]: I0806 07:48:57.624868 1741 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Aug 6 07:48:57.625897 kubelet[1741]: I0806 07:48:57.625254 1741 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 6 07:48:57.625897 kubelet[1741]: I0806 07:48:57.625774 1741 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 6 07:48:57.634531 kubelet[1741]: E0806 07:48:57.633924 1741 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.23.172.146\" not found" Aug 6 07:48:57.634531 kubelet[1741]: I0806 07:48:57.633981 1741 volume_manager.go:291] "Starting Kubelet Volume Manager" Aug 6 07:48:57.634531 kubelet[1741]: I0806 07:48:57.634097 1741 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Aug 6 07:48:57.634531 kubelet[1741]: I0806 07:48:57.634176 1741 reconciler_new.go:29] "Reconciler: start to sync state" Aug 6 07:48:57.635054 kubelet[1741]: E0806 07:48:57.635029 1741 kubelet.go:1462] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 6 07:48:57.636844 kubelet[1741]: I0806 07:48:57.636817 1741 factory.go:221] Registration of the systemd container factory successfully Aug 6 07:48:57.637184 kubelet[1741]: I0806 07:48:57.637156 1741 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Aug 6 07:48:57.639062 kubelet[1741]: I0806 07:48:57.639028 1741 factory.go:221] Registration of the containerd container factory successfully Aug 6 07:48:57.643593 kubelet[1741]: E0806 07:48:57.643554 1741 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"64.23.172.146\" not found" node="64.23.172.146" Aug 6 07:48:57.661945 kubelet[1741]: I0806 07:48:57.661875 1741 cpu_manager.go:214] "Starting CPU manager" policy="none" Aug 6 07:48:57.661945 kubelet[1741]: I0806 07:48:57.661902 1741 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Aug 6 07:48:57.662736 kubelet[1741]: I0806 07:48:57.662036 1741 state_mem.go:36] "Initialized new in-memory state store" Aug 6 07:48:57.667163 kubelet[1741]: I0806 07:48:57.666979 1741 policy_none.go:49] "None policy: Start" Aug 6 07:48:57.668929 kubelet[1741]: I0806 07:48:57.668903 1741 memory_manager.go:170] "Starting memorymanager" policy="None" Aug 6 07:48:57.669601 kubelet[1741]: I0806 07:48:57.669091 1741 state_mem.go:35] "Initializing new in-memory state store" Aug 6 07:48:57.683006 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Aug 6 07:48:57.710412 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Aug 6 07:48:57.716090 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Aug 6 07:48:57.722824 kubelet[1741]: I0806 07:48:57.722791 1741 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 6 07:48:57.725645 kubelet[1741]: I0806 07:48:57.725618 1741 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 6 07:48:57.730587 kubelet[1741]: E0806 07:48:57.729622 1741 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"64.23.172.146\" not found" Aug 6 07:48:57.735565 kubelet[1741]: I0806 07:48:57.735101 1741 kubelet_node_status.go:73] "Attempting to register node" node="64.23.172.146" Aug 6 07:48:57.739532 kubelet[1741]: I0806 07:48:57.739441 1741 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 6 07:48:57.741144 kubelet[1741]: I0806 07:48:57.740385 1741 kubelet_node_status.go:76] "Successfully registered node" node="64.23.172.146" Aug 6 07:48:57.741599 kubelet[1741]: I0806 07:48:57.741577 1741 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 6 07:48:57.741666 kubelet[1741]: I0806 07:48:57.741609 1741 status_manager.go:217] "Starting to sync pod status with apiserver" Aug 6 07:48:57.741666 kubelet[1741]: I0806 07:48:57.741627 1741 kubelet.go:2329] "Starting kubelet main sync loop" Aug 6 07:48:57.741731 kubelet[1741]: E0806 07:48:57.741720 1741 kubelet.go:2353] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Aug 6 07:48:57.759888 kubelet[1741]: I0806 07:48:57.759855 1741 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Aug 6 07:48:57.762536 containerd[1471]: time="2024-08-06T07:48:57.762475610Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Aug 6 07:48:57.763729 kubelet[1741]: I0806 07:48:57.763394 1741 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Aug 6 07:48:58.560611 kubelet[1741]: I0806 07:48:58.560111 1741 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Aug 6 07:48:58.561815 kubelet[1741]: W0806 07:48:58.560643 1741 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.RuntimeClass ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Aug 6 07:48:58.561815 kubelet[1741]: W0806 07:48:58.560689 1741 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.Service ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Aug 6 07:48:58.561815 kubelet[1741]: W0806 07:48:58.560715 1741 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.CSIDriver ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Aug 6 07:48:58.611000 kubelet[1741]: E0806 07:48:58.610900 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:48:58.611000 kubelet[1741]: I0806 07:48:58.611016 1741 apiserver.go:52] "Watching apiserver" Aug 6 07:48:58.620893 kubelet[1741]: I0806 07:48:58.620833 1741 topology_manager.go:215] "Topology Admit Handler" podUID="bccf945c-9f44-47a4-86b6-7e7a8f1e3d60" podNamespace="kube-flannel" podName="kube-flannel-ds-b2txb" Aug 6 07:48:58.621622 kubelet[1741]: I0806 07:48:58.620988 1741 topology_manager.go:215] "Topology Admit Handler" podUID="44a17df8-a523-43c6-b96b-552d26824b34" podNamespace="kube-system" podName="kube-proxy-tgtzn" Aug 6 07:48:58.634729 kubelet[1741]: I0806 07:48:58.634687 1741 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Aug 6 07:48:58.635484 systemd[1]: Created slice kubepods-besteffort-pod44a17df8_a523_43c6_b96b_552d26824b34.slice - libcontainer container kubepods-besteffort-pod44a17df8_a523_43c6_b96b_552d26824b34.slice. Aug 6 07:48:58.638665 kubelet[1741]: I0806 07:48:58.637989 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/44a17df8-a523-43c6-b96b-552d26824b34-kube-proxy\") pod \"kube-proxy-tgtzn\" (UID: \"44a17df8-a523-43c6-b96b-552d26824b34\") " pod="kube-system/kube-proxy-tgtzn" Aug 6 07:48:58.638665 kubelet[1741]: I0806 07:48:58.638046 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/44a17df8-a523-43c6-b96b-552d26824b34-xtables-lock\") pod \"kube-proxy-tgtzn\" (UID: \"44a17df8-a523-43c6-b96b-552d26824b34\") " pod="kube-system/kube-proxy-tgtzn" Aug 6 07:48:58.638665 kubelet[1741]: I0806 07:48:58.638078 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/bccf945c-9f44-47a4-86b6-7e7a8f1e3d60-cni-plugin\") pod \"kube-flannel-ds-b2txb\" (UID: \"bccf945c-9f44-47a4-86b6-7e7a8f1e3d60\") " pod="kube-flannel/kube-flannel-ds-b2txb" Aug 6 07:48:58.638665 kubelet[1741]: I0806 07:48:58.638108 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/bccf945c-9f44-47a4-86b6-7e7a8f1e3d60-flannel-cfg\") pod \"kube-flannel-ds-b2txb\" (UID: \"bccf945c-9f44-47a4-86b6-7e7a8f1e3d60\") " pod="kube-flannel/kube-flannel-ds-b2txb" Aug 6 07:48:58.638665 kubelet[1741]: I0806 07:48:58.638142 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/bccf945c-9f44-47a4-86b6-7e7a8f1e3d60-xtables-lock\") pod \"kube-flannel-ds-b2txb\" (UID: \"bccf945c-9f44-47a4-86b6-7e7a8f1e3d60\") " pod="kube-flannel/kube-flannel-ds-b2txb" Aug 6 07:48:58.639055 kubelet[1741]: I0806 07:48:58.638177 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/44a17df8-a523-43c6-b96b-552d26824b34-lib-modules\") pod \"kube-proxy-tgtzn\" (UID: \"44a17df8-a523-43c6-b96b-552d26824b34\") " pod="kube-system/kube-proxy-tgtzn" Aug 6 07:48:58.639055 kubelet[1741]: I0806 07:48:58.638260 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-c9b6f\" (UniqueName: \"kubernetes.io/projected/44a17df8-a523-43c6-b96b-552d26824b34-kube-api-access-c9b6f\") pod \"kube-proxy-tgtzn\" (UID: \"44a17df8-a523-43c6-b96b-552d26824b34\") " pod="kube-system/kube-proxy-tgtzn" Aug 6 07:48:58.639055 kubelet[1741]: I0806 07:48:58.638294 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/bccf945c-9f44-47a4-86b6-7e7a8f1e3d60-run\") pod \"kube-flannel-ds-b2txb\" (UID: \"bccf945c-9f44-47a4-86b6-7e7a8f1e3d60\") " pod="kube-flannel/kube-flannel-ds-b2txb" Aug 6 07:48:58.639055 kubelet[1741]: I0806 07:48:58.638374 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/bccf945c-9f44-47a4-86b6-7e7a8f1e3d60-cni\") pod \"kube-flannel-ds-b2txb\" (UID: \"bccf945c-9f44-47a4-86b6-7e7a8f1e3d60\") " pod="kube-flannel/kube-flannel-ds-b2txb" Aug 6 07:48:58.639055 kubelet[1741]: I0806 07:48:58.638415 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fnrt9\" (UniqueName: \"kubernetes.io/projected/bccf945c-9f44-47a4-86b6-7e7a8f1e3d60-kube-api-access-fnrt9\") pod \"kube-flannel-ds-b2txb\" (UID: \"bccf945c-9f44-47a4-86b6-7e7a8f1e3d60\") " pod="kube-flannel/kube-flannel-ds-b2txb" Aug 6 07:48:58.642696 systemd[1]: Created slice kubepods-burstable-podbccf945c_9f44_47a4_86b6_7e7a8f1e3d60.slice - libcontainer container kubepods-burstable-podbccf945c_9f44_47a4_86b6_7e7a8f1e3d60.slice. Aug 6 07:48:58.683657 sudo[1611]: pam_unix(sudo:session): session closed for user root Aug 6 07:48:58.687845 sshd[1608]: pam_unix(sshd:session): session closed for user core Aug 6 07:48:58.693188 systemd[1]: sshd@4-64.23.172.146:22-147.75.109.163:47692.service: Deactivated successfully. Aug 6 07:48:58.696061 systemd[1]: session-5.scope: Deactivated successfully. Aug 6 07:48:58.697409 systemd-logind[1452]: Session 5 logged out. Waiting for processes to exit. Aug 6 07:48:58.699272 systemd-logind[1452]: Removed session 5. Aug 6 07:48:58.955657 kubelet[1741]: E0806 07:48:58.955163 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:48:58.956337 containerd[1471]: time="2024-08-06T07:48:58.956274988Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tgtzn,Uid:44a17df8-a523-43c6-b96b-552d26824b34,Namespace:kube-system,Attempt:0,}" Aug 6 07:48:58.963541 kubelet[1741]: E0806 07:48:58.963368 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:48:58.964388 containerd[1471]: time="2024-08-06T07:48:58.964348560Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-b2txb,Uid:bccf945c-9f44-47a4-86b6-7e7a8f1e3d60,Namespace:kube-flannel,Attempt:0,}" Aug 6 07:48:59.611341 kubelet[1741]: E0806 07:48:59.611270 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:48:59.733027 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3984728786.mount: Deactivated successfully. Aug 6 07:48:59.756882 containerd[1471]: time="2024-08-06T07:48:59.756778422Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 6 07:48:59.760140 containerd[1471]: time="2024-08-06T07:48:59.759896238Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 6 07:48:59.762115 containerd[1471]: time="2024-08-06T07:48:59.762033083Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" Aug 6 07:48:59.764272 containerd[1471]: time="2024-08-06T07:48:59.764182739Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 6 07:48:59.769795 containerd[1471]: time="2024-08-06T07:48:59.769685903Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 6 07:48:59.777819 containerd[1471]: time="2024-08-06T07:48:59.777703121Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 6 07:48:59.780446 containerd[1471]: time="2024-08-06T07:48:59.779722681Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 823.310094ms" Aug 6 07:48:59.787367 containerd[1471]: time="2024-08-06T07:48:59.787264620Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 822.638299ms" Aug 6 07:49:00.005704 containerd[1471]: time="2024-08-06T07:49:00.004403491Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 6 07:49:00.005704 containerd[1471]: time="2024-08-06T07:49:00.004748069Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:00.005704 containerd[1471]: time="2024-08-06T07:49:00.004791255Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 6 07:49:00.005704 containerd[1471]: time="2024-08-06T07:49:00.004850855Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:00.014844 containerd[1471]: time="2024-08-06T07:49:00.009987006Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 6 07:49:00.014844 containerd[1471]: time="2024-08-06T07:49:00.013402543Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:00.014844 containerd[1471]: time="2024-08-06T07:49:00.013437434Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 6 07:49:00.014844 containerd[1471]: time="2024-08-06T07:49:00.013461452Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:00.166956 systemd[1]: Started cri-containerd-791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29.scope - libcontainer container 791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29. Aug 6 07:49:00.177918 systemd[1]: Started cri-containerd-edc96ab36c2e298d592071573a39983a9384b92e51f60aed83a778a6e8941628.scope - libcontainer container edc96ab36c2e298d592071573a39983a9384b92e51f60aed83a778a6e8941628. Aug 6 07:49:00.257691 containerd[1471]: time="2024-08-06T07:49:00.257552158Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tgtzn,Uid:44a17df8-a523-43c6-b96b-552d26824b34,Namespace:kube-system,Attempt:0,} returns sandbox id \"edc96ab36c2e298d592071573a39983a9384b92e51f60aed83a778a6e8941628\"" Aug 6 07:49:00.260134 kubelet[1741]: E0806 07:49:00.260099 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:00.265829 containerd[1471]: time="2024-08-06T07:49:00.265695778Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.7\"" Aug 6 07:49:00.267476 containerd[1471]: time="2024-08-06T07:49:00.267422674Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-b2txb,Uid:bccf945c-9f44-47a4-86b6-7e7a8f1e3d60,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29\"" Aug 6 07:49:00.268948 kubelet[1741]: E0806 07:49:00.268915 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:00.612331 kubelet[1741]: E0806 07:49:00.612236 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:00.835898 systemd[1]: run-containerd-runc-k8s.io-791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29-runc.oFldoD.mount: Deactivated successfully. Aug 6 07:49:01.612842 kubelet[1741]: E0806 07:49:01.612770 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:02.186160 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3394007776.mount: Deactivated successfully. Aug 6 07:49:02.617079 kubelet[1741]: E0806 07:49:02.617020 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:03.034203 containerd[1471]: time="2024-08-06T07:49:03.033808077Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.29.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:03.040484 containerd[1471]: time="2024-08-06T07:49:03.040282626Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.29.7: active requests=0, bytes read=28600088" Aug 6 07:49:03.046960 containerd[1471]: time="2024-08-06T07:49:03.046831513Z" level=info msg="ImageCreate event name:\"sha256:cc8c46cf9d741d1e8a357e5899f298d2f4ac4d890a2d248026b57e130e91cd07\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:03.055154 containerd[1471]: time="2024-08-06T07:49:03.054415800Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:4d5e787d71c41243379cbb323d2b3a920fa50825cab19d20ef3344a808d18c4e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:03.056252 containerd[1471]: time="2024-08-06T07:49:03.056183229Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.29.7\" with image id \"sha256:cc8c46cf9d741d1e8a357e5899f298d2f4ac4d890a2d248026b57e130e91cd07\", repo tag \"registry.k8s.io/kube-proxy:v1.29.7\", repo digest \"registry.k8s.io/kube-proxy@sha256:4d5e787d71c41243379cbb323d2b3a920fa50825cab19d20ef3344a808d18c4e\", size \"28599107\" in 2.790111367s" Aug 6 07:49:03.056252 containerd[1471]: time="2024-08-06T07:49:03.056251240Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.7\" returns image reference \"sha256:cc8c46cf9d741d1e8a357e5899f298d2f4ac4d890a2d248026b57e130e91cd07\"" Aug 6 07:49:03.058519 containerd[1471]: time="2024-08-06T07:49:03.058443693Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Aug 6 07:49:03.061487 containerd[1471]: time="2024-08-06T07:49:03.061385784Z" level=info msg="CreateContainer within sandbox \"edc96ab36c2e298d592071573a39983a9384b92e51f60aed83a778a6e8941628\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Aug 6 07:49:03.129623 containerd[1471]: time="2024-08-06T07:49:03.129488808Z" level=info msg="CreateContainer within sandbox \"edc96ab36c2e298d592071573a39983a9384b92e51f60aed83a778a6e8941628\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"8bd0a1b7d2050d30e234c90f0f5d66b909182fa95a3de8bab9510f6c986870a7\"" Aug 6 07:49:03.131773 containerd[1471]: time="2024-08-06T07:49:03.130626906Z" level=info msg="StartContainer for \"8bd0a1b7d2050d30e234c90f0f5d66b909182fa95a3de8bab9510f6c986870a7\"" Aug 6 07:49:03.208041 systemd[1]: Started cri-containerd-8bd0a1b7d2050d30e234c90f0f5d66b909182fa95a3de8bab9510f6c986870a7.scope - libcontainer container 8bd0a1b7d2050d30e234c90f0f5d66b909182fa95a3de8bab9510f6c986870a7. Aug 6 07:49:03.274770 containerd[1471]: time="2024-08-06T07:49:03.273643887Z" level=info msg="StartContainer for \"8bd0a1b7d2050d30e234c90f0f5d66b909182fa95a3de8bab9510f6c986870a7\" returns successfully" Aug 6 07:49:03.618332 kubelet[1741]: E0806 07:49:03.618261 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:03.770935 kubelet[1741]: E0806 07:49:03.770551 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:04.618994 kubelet[1741]: E0806 07:49:04.618918 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:04.772960 kubelet[1741]: E0806 07:49:04.772926 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:05.082296 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2009845351.mount: Deactivated successfully. Aug 6 07:49:05.202966 containerd[1471]: time="2024-08-06T07:49:05.202846095Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:05.210091 containerd[1471]: time="2024-08-06T07:49:05.209564226Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852935" Aug 6 07:49:05.217538 containerd[1471]: time="2024-08-06T07:49:05.215906142Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:05.223028 containerd[1471]: time="2024-08-06T07:49:05.222952630Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:05.225625 containerd[1471]: time="2024-08-06T07:49:05.225563839Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 2.16684487s" Aug 6 07:49:05.225880 containerd[1471]: time="2024-08-06T07:49:05.225845395Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Aug 6 07:49:05.229336 containerd[1471]: time="2024-08-06T07:49:05.229273065Z" level=info msg="CreateContainer within sandbox \"791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Aug 6 07:49:05.287025 containerd[1471]: time="2024-08-06T07:49:05.286959432Z" level=info msg="CreateContainer within sandbox \"791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291\"" Aug 6 07:49:05.288710 containerd[1471]: time="2024-08-06T07:49:05.288658037Z" level=info msg="StartContainer for \"5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291\"" Aug 6 07:49:05.348952 systemd[1]: Started cri-containerd-5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291.scope - libcontainer container 5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291. Aug 6 07:49:05.391847 systemd[1]: cri-containerd-5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291.scope: Deactivated successfully. Aug 6 07:49:05.394442 containerd[1471]: time="2024-08-06T07:49:05.394343243Z" level=info msg="StartContainer for \"5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291\" returns successfully" Aug 6 07:49:05.519571 containerd[1471]: time="2024-08-06T07:49:05.519465666Z" level=info msg="shim disconnected" id=5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291 namespace=k8s.io Aug 6 07:49:05.519571 containerd[1471]: time="2024-08-06T07:49:05.519564674Z" level=warning msg="cleaning up after shim disconnected" id=5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291 namespace=k8s.io Aug 6 07:49:05.519571 containerd[1471]: time="2024-08-06T07:49:05.519577733Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 6 07:49:05.620117 kubelet[1741]: E0806 07:49:05.619894 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:05.776076 kubelet[1741]: E0806 07:49:05.775874 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:05.777161 containerd[1471]: time="2024-08-06T07:49:05.777080978Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Aug 6 07:49:05.788049 kubelet[1741]: I0806 07:49:05.787583 1741 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-tgtzn" podStartSLOduration=5.992491642 podStartE2EDuration="8.787526004s" podCreationTimestamp="2024-08-06 07:48:57 +0000 UTC" firstStartedPulling="2024-08-06 07:49:00.2619188 +0000 UTC m=+3.088708019" lastFinishedPulling="2024-08-06 07:49:03.056953147 +0000 UTC m=+5.883742381" observedRunningTime="2024-08-06 07:49:03.78230644 +0000 UTC m=+6.609095680" watchObservedRunningTime="2024-08-06 07:49:05.787526004 +0000 UTC m=+8.614315236" Aug 6 07:49:05.950554 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-5f39c5b4fa749cd9facb5606747632ec7638d99c1cf9a15b7682427cc3a6c291-rootfs.mount: Deactivated successfully. Aug 6 07:49:06.621206 kubelet[1741]: E0806 07:49:06.621085 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:07.622060 kubelet[1741]: E0806 07:49:07.621991 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:07.868353 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount508675099.mount: Deactivated successfully. Aug 6 07:49:08.622718 kubelet[1741]: E0806 07:49:08.622659 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:08.974007 containerd[1471]: time="2024-08-06T07:49:08.973826404Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:08.977367 containerd[1471]: time="2024-08-06T07:49:08.976960629Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Aug 6 07:49:08.980288 containerd[1471]: time="2024-08-06T07:49:08.980196010Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:08.988796 containerd[1471]: time="2024-08-06T07:49:08.987858829Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:08.989671 containerd[1471]: time="2024-08-06T07:49:08.989629205Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.212464983s" Aug 6 07:49:08.989671 containerd[1471]: time="2024-08-06T07:49:08.989675529Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Aug 6 07:49:08.993487 containerd[1471]: time="2024-08-06T07:49:08.993444596Z" level=info msg="CreateContainer within sandbox \"791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Aug 6 07:49:09.027437 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount450457178.mount: Deactivated successfully. Aug 6 07:49:09.036461 containerd[1471]: time="2024-08-06T07:49:09.036373724Z" level=info msg="CreateContainer within sandbox \"791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a\"" Aug 6 07:49:09.037425 containerd[1471]: time="2024-08-06T07:49:09.037384819Z" level=info msg="StartContainer for \"251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a\"" Aug 6 07:49:09.090023 systemd[1]: run-containerd-runc-k8s.io-251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a-runc.QVp9a9.mount: Deactivated successfully. Aug 6 07:49:09.101876 systemd[1]: Started cri-containerd-251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a.scope - libcontainer container 251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a. Aug 6 07:49:09.137666 systemd[1]: cri-containerd-251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a.scope: Deactivated successfully. Aug 6 07:49:09.146650 kubelet[1741]: I0806 07:49:09.146600 1741 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Aug 6 07:49:09.151268 containerd[1471]: time="2024-08-06T07:49:09.151096472Z" level=info msg="StartContainer for \"251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a\" returns successfully" Aug 6 07:49:09.259099 containerd[1471]: time="2024-08-06T07:49:09.258166262Z" level=info msg="shim disconnected" id=251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a namespace=k8s.io Aug 6 07:49:09.259099 containerd[1471]: time="2024-08-06T07:49:09.258263258Z" level=warning msg="cleaning up after shim disconnected" id=251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a namespace=k8s.io Aug 6 07:49:09.259099 containerd[1471]: time="2024-08-06T07:49:09.258279660Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 6 07:49:09.624001 kubelet[1741]: E0806 07:49:09.623930 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:09.787538 kubelet[1741]: I0806 07:49:09.786777 1741 topology_manager.go:215] "Topology Admit Handler" podUID="a937417e-f03e-4454-9432-4ba4b5b71f55" podNamespace="default" podName="nginx-deployment-6d5f899847-xk6hv" Aug 6 07:49:09.791245 kubelet[1741]: E0806 07:49:09.791184 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:09.795280 containerd[1471]: time="2024-08-06T07:49:09.795113589Z" level=info msg="CreateContainer within sandbox \"791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Aug 6 07:49:09.801465 systemd[1]: Created slice kubepods-besteffort-poda937417e_f03e_4454_9432_4ba4b5b71f55.slice - libcontainer container kubepods-besteffort-poda937417e_f03e_4454_9432_4ba4b5b71f55.slice. Aug 6 07:49:09.832345 containerd[1471]: time="2024-08-06T07:49:09.832257305Z" level=info msg="CreateContainer within sandbox \"791aeaf56ddd0818dc18e47bc5a23e23aa8157d68031350b91660609a4036c29\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"85a337c79e2c5f8451fc9569606d3d7a0aebf69fb7d7a3d77af0dd174a92f235\"" Aug 6 07:49:09.833221 containerd[1471]: time="2024-08-06T07:49:09.833186858Z" level=info msg="StartContainer for \"85a337c79e2c5f8451fc9569606d3d7a0aebf69fb7d7a3d77af0dd174a92f235\"" Aug 6 07:49:09.840752 kubelet[1741]: I0806 07:49:09.840621 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wfxdq\" (UniqueName: \"kubernetes.io/projected/a937417e-f03e-4454-9432-4ba4b5b71f55-kube-api-access-wfxdq\") pod \"nginx-deployment-6d5f899847-xk6hv\" (UID: \"a937417e-f03e-4454-9432-4ba4b5b71f55\") " pod="default/nginx-deployment-6d5f899847-xk6hv" Aug 6 07:49:09.876019 systemd[1]: Started cri-containerd-85a337c79e2c5f8451fc9569606d3d7a0aebf69fb7d7a3d77af0dd174a92f235.scope - libcontainer container 85a337c79e2c5f8451fc9569606d3d7a0aebf69fb7d7a3d77af0dd174a92f235. Aug 6 07:49:09.920811 containerd[1471]: time="2024-08-06T07:49:09.920643327Z" level=info msg="StartContainer for \"85a337c79e2c5f8451fc9569606d3d7a0aebf69fb7d7a3d77af0dd174a92f235\" returns successfully" Aug 6 07:49:10.024086 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-251d195ddd8e010b6d00d82131912956ad7cd57ca596109d43e1543358e55e7a-rootfs.mount: Deactivated successfully. Aug 6 07:49:10.107398 containerd[1471]: time="2024-08-06T07:49:10.107293934Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-xk6hv,Uid:a937417e-f03e-4454-9432-4ba4b5b71f55,Namespace:default,Attempt:0,}" Aug 6 07:49:10.185923 systemd[1]: run-netns-cni\x2d80de0f49\x2da2eb\x2d42cb\x2dfbdb\x2d277e034ab15d.mount: Deactivated successfully. Aug 6 07:49:10.191391 systemd[1]: run-containerd-io.containerd.grpc.v1.cri-sandboxes-976a33cf2c35668df86631457a1b97582e0bc4dc817ba74f76b61a65d85d0200-shm.mount: Deactivated successfully. Aug 6 07:49:10.195592 containerd[1471]: time="2024-08-06T07:49:10.195493787Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-xk6hv,Uid:a937417e-f03e-4454-9432-4ba4b5b71f55,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"976a33cf2c35668df86631457a1b97582e0bc4dc817ba74f76b61a65d85d0200\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Aug 6 07:49:10.196692 kubelet[1741]: E0806 07:49:10.196027 1741 remote_runtime.go:193] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"976a33cf2c35668df86631457a1b97582e0bc4dc817ba74f76b61a65d85d0200\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Aug 6 07:49:10.196692 kubelet[1741]: E0806 07:49:10.196099 1741 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"976a33cf2c35668df86631457a1b97582e0bc4dc817ba74f76b61a65d85d0200\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-xk6hv" Aug 6 07:49:10.196692 kubelet[1741]: E0806 07:49:10.196134 1741 kuberuntime_manager.go:1172] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"976a33cf2c35668df86631457a1b97582e0bc4dc817ba74f76b61a65d85d0200\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-xk6hv" Aug 6 07:49:10.196692 kubelet[1741]: E0806 07:49:10.196243 1741 pod_workers.go:1298] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-6d5f899847-xk6hv_default(a937417e-f03e-4454-9432-4ba4b5b71f55)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-6d5f899847-xk6hv_default(a937417e-f03e-4454-9432-4ba4b5b71f55)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"976a33cf2c35668df86631457a1b97582e0bc4dc817ba74f76b61a65d85d0200\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-6d5f899847-xk6hv" podUID="a937417e-f03e-4454-9432-4ba4b5b71f55" Aug 6 07:49:10.624437 kubelet[1741]: E0806 07:49:10.624363 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:10.795607 kubelet[1741]: E0806 07:49:10.795570 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:10.811481 kubelet[1741]: I0806 07:49:10.811412 1741 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-b2txb" podStartSLOduration=5.091115169 podStartE2EDuration="13.811363423s" podCreationTimestamp="2024-08-06 07:48:57 +0000 UTC" firstStartedPulling="2024-08-06 07:49:00.269659449 +0000 UTC m=+3.096448673" lastFinishedPulling="2024-08-06 07:49:08.98990771 +0000 UTC m=+11.816696927" observedRunningTime="2024-08-06 07:49:10.811238751 +0000 UTC m=+13.638027990" watchObservedRunningTime="2024-08-06 07:49:10.811363423 +0000 UTC m=+13.638152657" Aug 6 07:49:11.001508 systemd-networkd[1372]: flannel.1: Link UP Aug 6 07:49:11.002247 systemd-networkd[1372]: flannel.1: Gained carrier Aug 6 07:49:11.625690 kubelet[1741]: E0806 07:49:11.625620 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:11.798014 kubelet[1741]: E0806 07:49:11.797979 1741 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Aug 6 07:49:12.626771 kubelet[1741]: E0806 07:49:12.626699 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:12.982035 systemd-networkd[1372]: flannel.1: Gained IPv6LL Aug 6 07:49:13.627377 kubelet[1741]: E0806 07:49:13.627305 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:14.628221 kubelet[1741]: E0806 07:49:14.628168 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:15.628458 kubelet[1741]: E0806 07:49:15.628390 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:16.629414 kubelet[1741]: E0806 07:49:16.629305 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:17.610022 kubelet[1741]: E0806 07:49:17.609949 1741 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:17.630324 kubelet[1741]: E0806 07:49:17.630231 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:18.631467 kubelet[1741]: E0806 07:49:18.631392 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:19.632540 kubelet[1741]: E0806 07:49:19.632440 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:20.633550 kubelet[1741]: E0806 07:49:20.633415 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:21.634146 kubelet[1741]: E0806 07:49:21.634078 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:22.634809 kubelet[1741]: E0806 07:49:22.634746 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:23.635970 kubelet[1741]: E0806 07:49:23.635885 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:23.743767 containerd[1471]: time="2024-08-06T07:49:23.743065274Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-xk6hv,Uid:a937417e-f03e-4454-9432-4ba4b5b71f55,Namespace:default,Attempt:0,}" Aug 6 07:49:23.783197 systemd-networkd[1372]: cni0: Link UP Aug 6 07:49:23.783207 systemd-networkd[1372]: cni0: Gained carrier Aug 6 07:49:23.790160 systemd-networkd[1372]: cni0: Lost carrier Aug 6 07:49:23.797620 systemd-networkd[1372]: veth0a3ed580: Link UP Aug 6 07:49:23.799572 kernel: cni0: port 1(veth0a3ed580) entered blocking state Aug 6 07:49:23.799836 kernel: cni0: port 1(veth0a3ed580) entered disabled state Aug 6 07:49:23.801446 kernel: veth0a3ed580: entered allmulticast mode Aug 6 07:49:23.801622 kernel: veth0a3ed580: entered promiscuous mode Aug 6 07:49:23.804650 kernel: cni0: port 1(veth0a3ed580) entered blocking state Aug 6 07:49:23.804748 kernel: cni0: port 1(veth0a3ed580) entered forwarding state Aug 6 07:49:23.806909 kernel: cni0: port 1(veth0a3ed580) entered disabled state Aug 6 07:49:23.818352 kernel: cni0: port 1(veth0a3ed580) entered blocking state Aug 6 07:49:23.818548 kernel: cni0: port 1(veth0a3ed580) entered forwarding state Aug 6 07:49:23.818481 systemd-networkd[1372]: veth0a3ed580: Gained carrier Aug 6 07:49:23.820280 systemd-networkd[1372]: cni0: Gained carrier Aug 6 07:49:23.827154 containerd[1471]: 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)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Aug 6 07:49:23.827154 containerd[1471]: delegateAdd: netconf sent to delegate plugin: Aug 6 07:49:23.866316 containerd[1471]: {"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-08-06T07:49:23.865983696Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 6 07:49:23.866316 containerd[1471]: time="2024-08-06T07:49:23.866054810Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:23.866316 containerd[1471]: time="2024-08-06T07:49:23.866079352Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 6 07:49:23.866316 containerd[1471]: time="2024-08-06T07:49:23.866096183Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:23.907906 systemd[1]: Started cri-containerd-0ef50eda4ff720e06f75ce3becf286462399029523f36c920dddef4cb1215126.scope - libcontainer container 0ef50eda4ff720e06f75ce3becf286462399029523f36c920dddef4cb1215126. Aug 6 07:49:23.980986 containerd[1471]: time="2024-08-06T07:49:23.980855722Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-xk6hv,Uid:a937417e-f03e-4454-9432-4ba4b5b71f55,Namespace:default,Attempt:0,} returns sandbox id \"0ef50eda4ff720e06f75ce3becf286462399029523f36c920dddef4cb1215126\"" Aug 6 07:49:23.983281 containerd[1471]: time="2024-08-06T07:49:23.983246164Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 6 07:49:24.636774 kubelet[1741]: E0806 07:49:24.636688 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:25.462721 systemd-networkd[1372]: veth0a3ed580: Gained IPv6LL Aug 6 07:49:25.535394 systemd-networkd[1372]: cni0: Gained IPv6LL Aug 6 07:49:25.638009 kubelet[1741]: E0806 07:49:25.637783 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:26.638843 kubelet[1741]: E0806 07:49:26.638797 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:26.912375 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3031595751.mount: Deactivated successfully. Aug 6 07:49:27.640401 kubelet[1741]: E0806 07:49:27.640355 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:28.499240 containerd[1471]: time="2024-08-06T07:49:28.499119439Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:28.505257 containerd[1471]: time="2024-08-06T07:49:28.505174444Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70974994" Aug 6 07:49:28.510425 containerd[1471]: time="2024-08-06T07:49:28.510333942Z" level=info msg="ImageCreate event name:\"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:28.519897 containerd[1471]: time="2024-08-06T07:49:28.519833494Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:28.522280 containerd[1471]: time="2024-08-06T07:49:28.521205983Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"70974872\" in 4.53769866s" Aug 6 07:49:28.522280 containerd[1471]: time="2024-08-06T07:49:28.521270431Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\"" Aug 6 07:49:28.524237 containerd[1471]: time="2024-08-06T07:49:28.524190900Z" level=info msg="CreateContainer within sandbox \"0ef50eda4ff720e06f75ce3becf286462399029523f36c920dddef4cb1215126\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Aug 6 07:49:28.556536 containerd[1471]: time="2024-08-06T07:49:28.556441996Z" level=info msg="CreateContainer within sandbox \"0ef50eda4ff720e06f75ce3becf286462399029523f36c920dddef4cb1215126\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"dc06a18de9d234ed443a6fb563181de0286baea9e84c4aa8788c444446a40afb\"" Aug 6 07:49:28.557619 containerd[1471]: time="2024-08-06T07:49:28.557349058Z" level=info msg="StartContainer for \"dc06a18de9d234ed443a6fb563181de0286baea9e84c4aa8788c444446a40afb\"" Aug 6 07:49:28.613817 systemd[1]: Started cri-containerd-dc06a18de9d234ed443a6fb563181de0286baea9e84c4aa8788c444446a40afb.scope - libcontainer container dc06a18de9d234ed443a6fb563181de0286baea9e84c4aa8788c444446a40afb. Aug 6 07:49:28.641138 kubelet[1741]: E0806 07:49:28.640715 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:28.658791 containerd[1471]: time="2024-08-06T07:49:28.658633074Z" level=info msg="StartContainer for \"dc06a18de9d234ed443a6fb563181de0286baea9e84c4aa8788c444446a40afb\" returns successfully" Aug 6 07:49:28.846260 kubelet[1741]: I0806 07:49:28.846208 1741 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-xk6hv" podStartSLOduration=15.307354193 podStartE2EDuration="19.84616213s" podCreationTimestamp="2024-08-06 07:49:09 +0000 UTC" firstStartedPulling="2024-08-06 07:49:23.98274019 +0000 UTC m=+26.809529409" lastFinishedPulling="2024-08-06 07:49:28.521548127 +0000 UTC m=+31.348337346" observedRunningTime="2024-08-06 07:49:28.84612013 +0000 UTC m=+31.672909370" watchObservedRunningTime="2024-08-06 07:49:28.84616213 +0000 UTC m=+31.672951421" Aug 6 07:49:29.641193 kubelet[1741]: E0806 07:49:29.641121 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:30.252863 update_engine[1454]: I0806 07:49:30.252741 1454 update_attempter.cc:509] Updating boot flags... Aug 6 07:49:30.293854 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2555) Aug 6 07:49:30.367650 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2559) Aug 6 07:49:30.641462 kubelet[1741]: E0806 07:49:30.641351 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:31.642332 kubelet[1741]: E0806 07:49:31.642217 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:32.643010 kubelet[1741]: E0806 07:49:32.642937 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:32.910664 kubelet[1741]: I0806 07:49:32.910489 1741 topology_manager.go:215] "Topology Admit Handler" podUID="b04681d8-a5c7-4818-8126-a56f9c314000" podNamespace="default" podName="nfs-server-provisioner-0" Aug 6 07:49:32.922601 systemd[1]: Created slice kubepods-besteffort-podb04681d8_a5c7_4818_8126_a56f9c314000.slice - libcontainer container kubepods-besteffort-podb04681d8_a5c7_4818_8126_a56f9c314000.slice. Aug 6 07:49:33.001196 kubelet[1741]: I0806 07:49:33.001074 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g4zqm\" (UniqueName: \"kubernetes.io/projected/b04681d8-a5c7-4818-8126-a56f9c314000-kube-api-access-g4zqm\") pod \"nfs-server-provisioner-0\" (UID: \"b04681d8-a5c7-4818-8126-a56f9c314000\") " pod="default/nfs-server-provisioner-0" Aug 6 07:49:33.001196 kubelet[1741]: I0806 07:49:33.001152 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/b04681d8-a5c7-4818-8126-a56f9c314000-data\") pod \"nfs-server-provisioner-0\" (UID: \"b04681d8-a5c7-4818-8126-a56f9c314000\") " pod="default/nfs-server-provisioner-0" Aug 6 07:49:33.227348 containerd[1471]: time="2024-08-06T07:49:33.227209630Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b04681d8-a5c7-4818-8126-a56f9c314000,Namespace:default,Attempt:0,}" Aug 6 07:49:33.283319 systemd-networkd[1372]: veth8a39e26e: Link UP Aug 6 07:49:33.286005 kernel: cni0: port 2(veth8a39e26e) entered blocking state Aug 6 07:49:33.286106 kernel: cni0: port 2(veth8a39e26e) entered disabled state Aug 6 07:49:33.288571 kernel: veth8a39e26e: entered allmulticast mode Aug 6 07:49:33.290653 kernel: veth8a39e26e: entered promiscuous mode Aug 6 07:49:33.301009 kernel: cni0: port 2(veth8a39e26e) entered blocking state Aug 6 07:49:33.301136 kernel: cni0: port 2(veth8a39e26e) entered forwarding state Aug 6 07:49:33.300165 systemd-networkd[1372]: veth8a39e26e: Gained carrier Aug 6 07:49:33.308016 containerd[1471]: 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)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Aug 6 07:49:33.308016 containerd[1471]: delegateAdd: netconf sent to delegate plugin: Aug 6 07:49:33.337951 containerd[1471]: {"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-08-06T07:49:33.337813253Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 6 07:49:33.337951 containerd[1471]: time="2024-08-06T07:49:33.337880116Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:33.337951 containerd[1471]: time="2024-08-06T07:49:33.337905208Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 6 07:49:33.337951 containerd[1471]: time="2024-08-06T07:49:33.337919561Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:49:33.374899 systemd[1]: Started cri-containerd-9f4d1073e9a68a1b8f5a24165f80d294a21e45601c41d724fd75239160bd7905.scope - libcontainer container 9f4d1073e9a68a1b8f5a24165f80d294a21e45601c41d724fd75239160bd7905. Aug 6 07:49:33.433483 containerd[1471]: time="2024-08-06T07:49:33.433431788Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b04681d8-a5c7-4818-8126-a56f9c314000,Namespace:default,Attempt:0,} returns sandbox id \"9f4d1073e9a68a1b8f5a24165f80d294a21e45601c41d724fd75239160bd7905\"" Aug 6 07:49:33.436049 containerd[1471]: time="2024-08-06T07:49:33.436005869Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Aug 6 07:49:33.643622 kubelet[1741]: E0806 07:49:33.643563 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:34.119067 systemd[1]: run-containerd-runc-k8s.io-9f4d1073e9a68a1b8f5a24165f80d294a21e45601c41d724fd75239160bd7905-runc.MEjjQQ.mount: Deactivated successfully. Aug 6 07:49:34.422592 systemd-networkd[1372]: veth8a39e26e: Gained IPv6LL Aug 6 07:49:34.644420 kubelet[1741]: E0806 07:49:34.644069 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:35.645252 kubelet[1741]: E0806 07:49:35.645208 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:35.818493 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount521862344.mount: Deactivated successfully. Aug 6 07:49:36.645722 kubelet[1741]: E0806 07:49:36.645676 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:37.610170 kubelet[1741]: E0806 07:49:37.610120 1741 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:37.646988 kubelet[1741]: E0806 07:49:37.646799 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:38.439888 containerd[1471]: time="2024-08-06T07:49:38.439773195Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:38.444765 containerd[1471]: time="2024-08-06T07:49:38.444681985Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Aug 6 07:49:38.451535 containerd[1471]: time="2024-08-06T07:49:38.451380113Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:38.458612 containerd[1471]: time="2024-08-06T07:49:38.458551214Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:49:38.461549 containerd[1471]: time="2024-08-06T07:49:38.461079151Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 5.024817628s" Aug 6 07:49:38.461549 containerd[1471]: time="2024-08-06T07:49:38.461141832Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Aug 6 07:49:38.465485 containerd[1471]: time="2024-08-06T07:49:38.465251241Z" level=info msg="CreateContainer within sandbox \"9f4d1073e9a68a1b8f5a24165f80d294a21e45601c41d724fd75239160bd7905\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Aug 6 07:49:38.502494 containerd[1471]: time="2024-08-06T07:49:38.502306742Z" level=info msg="CreateContainer within sandbox \"9f4d1073e9a68a1b8f5a24165f80d294a21e45601c41d724fd75239160bd7905\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ab21eff05ee31f4f0395e585cd9b621d753482c09773ced9f7b8a1f60d3ecc00\"" Aug 6 07:49:38.503599 containerd[1471]: time="2024-08-06T07:49:38.503489544Z" level=info msg="StartContainer for \"ab21eff05ee31f4f0395e585cd9b621d753482c09773ced9f7b8a1f60d3ecc00\"" Aug 6 07:49:38.557401 systemd[1]: run-containerd-runc-k8s.io-ab21eff05ee31f4f0395e585cd9b621d753482c09773ced9f7b8a1f60d3ecc00-runc.ZlVo8J.mount: Deactivated successfully. Aug 6 07:49:38.574724 systemd[1]: Started cri-containerd-ab21eff05ee31f4f0395e585cd9b621d753482c09773ced9f7b8a1f60d3ecc00.scope - libcontainer container ab21eff05ee31f4f0395e585cd9b621d753482c09773ced9f7b8a1f60d3ecc00. Aug 6 07:49:38.628176 containerd[1471]: time="2024-08-06T07:49:38.628107111Z" level=info msg="StartContainer for \"ab21eff05ee31f4f0395e585cd9b621d753482c09773ced9f7b8a1f60d3ecc00\" returns successfully" Aug 6 07:49:38.647911 kubelet[1741]: E0806 07:49:38.647843 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:38.878545 kubelet[1741]: I0806 07:49:38.878444 1741 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.851507536 podStartE2EDuration="6.878395365s" podCreationTimestamp="2024-08-06 07:49:32 +0000 UTC" firstStartedPulling="2024-08-06 07:49:33.435480491 +0000 UTC m=+36.262269717" lastFinishedPulling="2024-08-06 07:49:38.462368307 +0000 UTC m=+41.289157546" observedRunningTime="2024-08-06 07:49:38.87831686 +0000 UTC m=+41.705106101" watchObservedRunningTime="2024-08-06 07:49:38.878395365 +0000 UTC m=+41.705184604" Aug 6 07:49:39.648735 kubelet[1741]: E0806 07:49:39.648668 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:40.649241 kubelet[1741]: E0806 07:49:40.649096 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:41.650010 kubelet[1741]: E0806 07:49:41.649938 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:42.650791 kubelet[1741]: E0806 07:49:42.650703 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:43.651697 kubelet[1741]: E0806 07:49:43.651477 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:44.652828 kubelet[1741]: E0806 07:49:44.652715 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:45.653566 kubelet[1741]: E0806 07:49:45.653458 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:46.654057 kubelet[1741]: E0806 07:49:46.653977 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:47.655059 kubelet[1741]: E0806 07:49:47.654875 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:47.987765 kubelet[1741]: I0806 07:49:47.987471 1741 topology_manager.go:215] "Topology Admit Handler" podUID="64336343-39ba-457e-8ad1-bd70b2d1ebe8" podNamespace="default" podName="test-pod-1" Aug 6 07:49:47.997418 systemd[1]: Created slice kubepods-besteffort-pod64336343_39ba_457e_8ad1_bd70b2d1ebe8.slice - libcontainer container kubepods-besteffort-pod64336343_39ba_457e_8ad1_bd70b2d1ebe8.slice. Aug 6 07:49:48.008273 kubelet[1741]: I0806 07:49:48.008181 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zf84t\" (UniqueName: \"kubernetes.io/projected/64336343-39ba-457e-8ad1-bd70b2d1ebe8-kube-api-access-zf84t\") pod \"test-pod-1\" (UID: \"64336343-39ba-457e-8ad1-bd70b2d1ebe8\") " pod="default/test-pod-1" Aug 6 07:49:48.008273 kubelet[1741]: I0806 07:49:48.008245 1741 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-3dcd6387-3943-40fe-88b8-e173be062a3a\" (UniqueName: \"kubernetes.io/nfs/64336343-39ba-457e-8ad1-bd70b2d1ebe8-pvc-3dcd6387-3943-40fe-88b8-e173be062a3a\") pod \"test-pod-1\" (UID: \"64336343-39ba-457e-8ad1-bd70b2d1ebe8\") " pod="default/test-pod-1" Aug 6 07:49:48.166850 kernel: FS-Cache: Loaded Aug 6 07:49:48.265945 kernel: RPC: Registered named UNIX socket transport module. Aug 6 07:49:48.266085 kernel: RPC: Registered udp transport module. Aug 6 07:49:48.266141 kernel: RPC: Registered tcp transport module. Aug 6 07:49:48.266848 kernel: RPC: Registered tcp-with-tls transport module. Aug 6 07:49:48.267741 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 6 07:49:48.602823 kernel: NFS: Registering the id_resolver key type Aug 6 07:49:48.603117 kernel: Key type id_resolver registered Aug 6 07:49:48.606652 kernel: Key type id_legacy registered Aug 6 07:49:48.655241 kubelet[1741]: E0806 07:49:48.655172 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:49.655533 kubelet[1741]: E0806 07:49:49.655411 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:50.656390 kubelet[1741]: E0806 07:49:50.656328 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:51.657312 kubelet[1741]: E0806 07:49:51.657236 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:52.657607 kubelet[1741]: E0806 07:49:52.657529 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:53.658364 kubelet[1741]: E0806 07:49:53.658288 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:54.659163 kubelet[1741]: E0806 07:49:54.658914 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:54.778143 nfsidmap[2836]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.0-3-5d52add573' Aug 6 07:49:54.782471 systemd-resolved[1330]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. Aug 6 07:49:55.660153 kubelet[1741]: E0806 07:49:55.660084 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:56.661066 kubelet[1741]: E0806 07:49:56.660990 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:57.609662 kubelet[1741]: E0806 07:49:57.609585 1741 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:57.661859 kubelet[1741]: E0806 07:49:57.661805 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:57.846745 systemd-resolved[1330]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.3. Aug 6 07:49:58.663140 kubelet[1741]: E0806 07:49:58.663069 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:49:59.663364 kubelet[1741]: E0806 07:49:59.663291 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:50:00.664126 kubelet[1741]: E0806 07:50:00.664041 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:50:00.920318 nfsidmap[2858]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.0-3-5d52add573' Aug 6 07:50:01.206934 containerd[1471]: time="2024-08-06T07:50:01.206280886Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:64336343-39ba-457e-8ad1-bd70b2d1ebe8,Namespace:default,Attempt:0,}" Aug 6 07:50:01.403190 systemd-networkd[1372]: veth8812bbeb: Link UP Aug 6 07:50:01.418229 kernel: cni0: port 3(veth8812bbeb) entered blocking state Aug 6 07:50:01.419574 kernel: cni0: port 3(veth8812bbeb) entered disabled state Aug 6 07:50:01.419653 kernel: veth8812bbeb: entered allmulticast mode Aug 6 07:50:01.419682 kernel: veth8812bbeb: entered promiscuous mode Aug 6 07:50:01.448601 kernel: cni0: port 3(veth8812bbeb) entered blocking state Aug 6 07:50:01.448808 kernel: cni0: port 3(veth8812bbeb) entered forwarding state Aug 6 07:50:01.449128 systemd-networkd[1372]: veth8812bbeb: Gained carrier Aug 6 07:50:01.520448 containerd[1471]: 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)(0xc000018938), "name":"cbr0", "type":"bridge"} Aug 6 07:50:01.520448 containerd[1471]: delegateAdd: netconf sent to delegate plugin: Aug 6 07:50:01.669526 kubelet[1741]: E0806 07:50:01.665252 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:50:01.740555 containerd[1471]: {"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-08-06T07:50:01.738174169Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 6 07:50:01.740555 containerd[1471]: time="2024-08-06T07:50:01.738281765Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:50:01.740555 containerd[1471]: time="2024-08-06T07:50:01.738312184Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 6 07:50:01.740555 containerd[1471]: time="2024-08-06T07:50:01.738329910Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 6 07:50:01.884396 systemd[1]: Started cri-containerd-13b4a132e478981dcd36d25bfc16211f5311eef6dc5cce7184cb7b6fed5f32ec.scope - libcontainer container 13b4a132e478981dcd36d25bfc16211f5311eef6dc5cce7184cb7b6fed5f32ec. Aug 6 07:50:02.036679 containerd[1471]: time="2024-08-06T07:50:02.036481725Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:64336343-39ba-457e-8ad1-bd70b2d1ebe8,Namespace:default,Attempt:0,} returns sandbox id \"13b4a132e478981dcd36d25bfc16211f5311eef6dc5cce7184cb7b6fed5f32ec\"" Aug 6 07:50:02.040767 containerd[1471]: time="2024-08-06T07:50:02.040711932Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 6 07:50:02.597129 containerd[1471]: time="2024-08-06T07:50:02.595160776Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 6 07:50:02.610548 containerd[1471]: time="2024-08-06T07:50:02.610417540Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Aug 6 07:50:02.619558 containerd[1471]: time="2024-08-06T07:50:02.618801203Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"70974872\" in 578.015276ms" Aug 6 07:50:02.619558 containerd[1471]: time="2024-08-06T07:50:02.618877951Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\"" Aug 6 07:50:02.630150 containerd[1471]: time="2024-08-06T07:50:02.626936119Z" level=info msg="CreateContainer within sandbox \"13b4a132e478981dcd36d25bfc16211f5311eef6dc5cce7184cb7b6fed5f32ec\" for container &ContainerMetadata{Name:test,Attempt:0,}" Aug 6 07:50:02.673342 kubelet[1741]: E0806 07:50:02.673226 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:50:02.751597 containerd[1471]: time="2024-08-06T07:50:02.751390291Z" level=info msg="CreateContainer within sandbox \"13b4a132e478981dcd36d25bfc16211f5311eef6dc5cce7184cb7b6fed5f32ec\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"c608948be98fd730be100fdc42148952b97e11be23e992182dc59823365f2ec0\"" Aug 6 07:50:02.753173 containerd[1471]: time="2024-08-06T07:50:02.752702918Z" level=info msg="StartContainer for \"c608948be98fd730be100fdc42148952b97e11be23e992182dc59823365f2ec0\"" Aug 6 07:50:02.859957 systemd[1]: Started cri-containerd-c608948be98fd730be100fdc42148952b97e11be23e992182dc59823365f2ec0.scope - libcontainer container c608948be98fd730be100fdc42148952b97e11be23e992182dc59823365f2ec0. Aug 6 07:50:03.070914 containerd[1471]: time="2024-08-06T07:50:03.069539026Z" level=info msg="StartContainer for \"c608948be98fd730be100fdc42148952b97e11be23e992182dc59823365f2ec0\" returns successfully" Aug 6 07:50:03.222156 systemd-networkd[1372]: veth8812bbeb: Gained IPv6LL Aug 6 07:50:03.673855 kubelet[1741]: E0806 07:50:03.673782 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:50:04.674711 kubelet[1741]: E0806 07:50:04.674623 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:50:05.674991 kubelet[1741]: E0806 07:50:05.674901 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 6 07:50:06.676204 kubelet[1741]: E0806 07:50:06.675982 1741 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"