Oct 9 01:25:40.046618 kernel: Linux version 6.6.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Tue Oct 8 23:33:43 -00 2024 Oct 9 01:25:40.046645 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=openstack flatcar.autologin verity.usrhash=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:25:40.046658 kernel: BIOS-provided physical RAM map: Oct 9 01:25:40.046666 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Oct 9 01:25:40.046673 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Oct 9 01:25:40.046681 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Oct 9 01:25:40.046690 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdcfff] usable Oct 9 01:25:40.046698 kernel: BIOS-e820: [mem 0x000000007ffdd000-0x000000007fffffff] reserved Oct 9 01:25:40.046706 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Oct 9 01:25:40.046716 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Oct 9 01:25:40.046724 kernel: NX (Execute Disable) protection: active Oct 9 01:25:40.046732 kernel: APIC: Static calls initialized Oct 9 01:25:40.046740 kernel: SMBIOS 2.8 present. Oct 9 01:25:40.046748 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.15.0-1 04/01/2014 Oct 9 01:25:40.046757 kernel: Hypervisor detected: KVM Oct 9 01:25:40.046768 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Oct 9 01:25:40.046776 kernel: kvm-clock: using sched offset of 5873454048 cycles Oct 9 01:25:40.046785 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 9 01:25:40.046793 kernel: tsc: Detected 1996.249 MHz processor Oct 9 01:25:40.046802 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 9 01:25:40.046811 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 9 01:25:40.046820 kernel: last_pfn = 0x7ffdd max_arch_pfn = 0x400000000 Oct 9 01:25:40.046828 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Oct 9 01:25:40.046837 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 9 01:25:40.046848 kernel: ACPI: Early table checksum verification disabled Oct 9 01:25:40.046856 kernel: ACPI: RSDP 0x00000000000F5930 000014 (v00 BOCHS ) Oct 9 01:25:40.046865 kernel: ACPI: RSDT 0x000000007FFE1848 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:25:40.046873 kernel: ACPI: FACP 0x000000007FFE172C 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:25:40.046882 kernel: ACPI: DSDT 0x000000007FFE0040 0016EC (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:25:40.046890 kernel: ACPI: FACS 0x000000007FFE0000 000040 Oct 9 01:25:40.046899 kernel: ACPI: APIC 0x000000007FFE17A0 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:25:40.046907 kernel: ACPI: WAET 0x000000007FFE1820 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:25:40.046916 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe172c-0x7ffe179f] Oct 9 01:25:40.046926 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe172b] Oct 9 01:25:40.046935 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Oct 9 01:25:40.046943 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17a0-0x7ffe181f] Oct 9 01:25:40.046952 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe1820-0x7ffe1847] Oct 9 01:25:40.046960 kernel: No NUMA configuration found Oct 9 01:25:40.046968 kernel: Faking a node at [mem 0x0000000000000000-0x000000007ffdcfff] Oct 9 01:25:40.046977 kernel: NODE_DATA(0) allocated [mem 0x7ffd7000-0x7ffdcfff] Oct 9 01:25:40.046989 kernel: Zone ranges: Oct 9 01:25:40.047001 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 9 01:25:40.047009 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdcfff] Oct 9 01:25:40.047018 kernel: Normal empty Oct 9 01:25:40.047027 kernel: Movable zone start for each node Oct 9 01:25:40.047036 kernel: Early memory node ranges Oct 9 01:25:40.047045 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Oct 9 01:25:40.047054 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdcfff] Oct 9 01:25:40.047065 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdcfff] Oct 9 01:25:40.047074 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 9 01:25:40.047083 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Oct 9 01:25:40.047091 kernel: On node 0, zone DMA32: 35 pages in unavailable ranges Oct 9 01:25:40.047100 kernel: ACPI: PM-Timer IO Port: 0x608 Oct 9 01:25:40.047125 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Oct 9 01:25:40.047135 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Oct 9 01:25:40.049132 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Oct 9 01:25:40.049150 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Oct 9 01:25:40.049171 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 9 01:25:40.049180 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Oct 9 01:25:40.049189 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Oct 9 01:25:40.049197 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 9 01:25:40.049205 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Oct 9 01:25:40.049214 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Oct 9 01:25:40.049222 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Oct 9 01:25:40.049230 kernel: Booting paravirtualized kernel on KVM Oct 9 01:25:40.049239 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 9 01:25:40.049250 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Oct 9 01:25:40.049258 kernel: percpu: Embedded 58 pages/cpu s196904 r8192 d32472 u1048576 Oct 9 01:25:40.049267 kernel: pcpu-alloc: s196904 r8192 d32472 u1048576 alloc=1*2097152 Oct 9 01:25:40.049275 kernel: pcpu-alloc: [0] 0 1 Oct 9 01:25:40.049283 kernel: kvm-guest: PV spinlocks disabled, no host support Oct 9 01:25:40.049293 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=openstack flatcar.autologin verity.usrhash=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:25:40.049302 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 9 01:25:40.049310 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 9 01:25:40.049321 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 9 01:25:40.049329 kernel: Fallback order for Node 0: 0 Oct 9 01:25:40.049337 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515805 Oct 9 01:25:40.049345 kernel: Policy zone: DMA32 Oct 9 01:25:40.049354 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 9 01:25:40.049362 kernel: Memory: 1971212K/2096620K available (12288K kernel code, 2305K rwdata, 22728K rodata, 42872K init, 2316K bss, 125148K reserved, 0K cma-reserved) Oct 9 01:25:40.049371 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 9 01:25:40.049379 kernel: ftrace: allocating 37786 entries in 148 pages Oct 9 01:25:40.049389 kernel: ftrace: allocated 148 pages with 3 groups Oct 9 01:25:40.049397 kernel: Dynamic Preempt: voluntary Oct 9 01:25:40.049406 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 9 01:25:40.049418 kernel: rcu: RCU event tracing is enabled. Oct 9 01:25:40.049426 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 9 01:25:40.049435 kernel: Trampoline variant of Tasks RCU enabled. Oct 9 01:25:40.049443 kernel: Rude variant of Tasks RCU enabled. Oct 9 01:25:40.049451 kernel: Tracing variant of Tasks RCU enabled. Oct 9 01:25:40.049460 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 9 01:25:40.049468 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 9 01:25:40.049478 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Oct 9 01:25:40.049486 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 9 01:25:40.049495 kernel: Console: colour VGA+ 80x25 Oct 9 01:25:40.049503 kernel: printk: console [tty0] enabled Oct 9 01:25:40.049511 kernel: printk: console [ttyS0] enabled Oct 9 01:25:40.049520 kernel: ACPI: Core revision 20230628 Oct 9 01:25:40.049528 kernel: APIC: Switch to symmetric I/O mode setup Oct 9 01:25:40.049536 kernel: x2apic enabled Oct 9 01:25:40.049544 kernel: APIC: Switched APIC routing to: physical x2apic Oct 9 01:25:40.049554 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Oct 9 01:25:40.049563 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Oct 9 01:25:40.049571 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) Oct 9 01:25:40.049580 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Oct 9 01:25:40.049588 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Oct 9 01:25:40.049596 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 9 01:25:40.049605 kernel: Spectre V2 : Mitigation: Retpolines Oct 9 01:25:40.049613 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Oct 9 01:25:40.049621 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Oct 9 01:25:40.049631 kernel: Speculative Store Bypass: Vulnerable Oct 9 01:25:40.049639 kernel: x86/fpu: x87 FPU will use FXSAVE Oct 9 01:25:40.049648 kernel: Freeing SMP alternatives memory: 32K Oct 9 01:25:40.049656 kernel: pid_max: default: 32768 minimum: 301 Oct 9 01:25:40.049664 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 9 01:25:40.049672 kernel: landlock: Up and running. Oct 9 01:25:40.049681 kernel: SELinux: Initializing. Oct 9 01:25:40.049689 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 9 01:25:40.049707 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 9 01:25:40.049716 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) Oct 9 01:25:40.049725 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:25:40.049733 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:25:40.049744 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:25:40.049753 kernel: Performance Events: AMD PMU driver. Oct 9 01:25:40.049762 kernel: ... version: 0 Oct 9 01:25:40.049770 kernel: ... bit width: 48 Oct 9 01:25:40.049779 kernel: ... generic registers: 4 Oct 9 01:25:40.049790 kernel: ... value mask: 0000ffffffffffff Oct 9 01:25:40.049799 kernel: ... max period: 00007fffffffffff Oct 9 01:25:40.049807 kernel: ... fixed-purpose events: 0 Oct 9 01:25:40.049816 kernel: ... event mask: 000000000000000f Oct 9 01:25:40.049825 kernel: signal: max sigframe size: 1440 Oct 9 01:25:40.049834 kernel: rcu: Hierarchical SRCU implementation. Oct 9 01:25:40.049842 kernel: rcu: Max phase no-delay instances is 400. Oct 9 01:25:40.049851 kernel: smp: Bringing up secondary CPUs ... Oct 9 01:25:40.049860 kernel: smpboot: x86: Booting SMP configuration: Oct 9 01:25:40.049870 kernel: .... node #0, CPUs: #1 Oct 9 01:25:40.049879 kernel: smp: Brought up 1 node, 2 CPUs Oct 9 01:25:40.049888 kernel: smpboot: Max logical packages: 2 Oct 9 01:25:40.049897 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) Oct 9 01:25:40.049905 kernel: devtmpfs: initialized Oct 9 01:25:40.049914 kernel: x86/mm: Memory block size: 128MB Oct 9 01:25:40.049922 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 9 01:25:40.049931 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 9 01:25:40.049940 kernel: pinctrl core: initialized pinctrl subsystem Oct 9 01:25:40.049951 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 9 01:25:40.049959 kernel: audit: initializing netlink subsys (disabled) Oct 9 01:25:40.049968 kernel: audit: type=2000 audit(1728437139.556:1): state=initialized audit_enabled=0 res=1 Oct 9 01:25:40.049977 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 9 01:25:40.049986 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 9 01:25:40.049994 kernel: cpuidle: using governor menu Oct 9 01:25:40.050003 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 9 01:25:40.050012 kernel: dca service started, version 1.12.1 Oct 9 01:25:40.050020 kernel: PCI: Using configuration type 1 for base access Oct 9 01:25:40.050031 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 9 01:25:40.050040 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 9 01:25:40.050049 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 9 01:25:40.050058 kernel: ACPI: Added _OSI(Module Device) Oct 9 01:25:40.050066 kernel: ACPI: Added _OSI(Processor Device) Oct 9 01:25:40.050075 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 9 01:25:40.050084 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 9 01:25:40.050092 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 9 01:25:40.050101 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 9 01:25:40.050131 kernel: ACPI: Interpreter enabled Oct 9 01:25:40.050140 kernel: ACPI: PM: (supports S0 S3 S5) Oct 9 01:25:40.050149 kernel: ACPI: Using IOAPIC for interrupt routing Oct 9 01:25:40.050158 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 9 01:25:40.050167 kernel: PCI: Using E820 reservations for host bridge windows Oct 9 01:25:40.050175 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Oct 9 01:25:40.050184 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 9 01:25:40.050331 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Oct 9 01:25:40.050436 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Oct 9 01:25:40.050527 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Oct 9 01:25:40.050541 kernel: acpiphp: Slot [3] registered Oct 9 01:25:40.050550 kernel: acpiphp: Slot [4] registered Oct 9 01:25:40.050559 kernel: acpiphp: Slot [5] registered Oct 9 01:25:40.050568 kernel: acpiphp: Slot [6] registered Oct 9 01:25:40.050576 kernel: acpiphp: Slot [7] registered Oct 9 01:25:40.050585 kernel: acpiphp: Slot [8] registered Oct 9 01:25:40.050596 kernel: acpiphp: Slot [9] registered Oct 9 01:25:40.050605 kernel: acpiphp: Slot [10] registered Oct 9 01:25:40.050614 kernel: acpiphp: Slot [11] registered Oct 9 01:25:40.050622 kernel: acpiphp: Slot [12] registered Oct 9 01:25:40.050631 kernel: acpiphp: Slot [13] registered Oct 9 01:25:40.050640 kernel: acpiphp: Slot [14] registered Oct 9 01:25:40.050648 kernel: acpiphp: Slot [15] registered Oct 9 01:25:40.050657 kernel: acpiphp: Slot [16] registered Oct 9 01:25:40.050666 kernel: acpiphp: Slot [17] registered Oct 9 01:25:40.050674 kernel: acpiphp: Slot [18] registered Oct 9 01:25:40.050685 kernel: acpiphp: Slot [19] registered Oct 9 01:25:40.050693 kernel: acpiphp: Slot [20] registered Oct 9 01:25:40.050702 kernel: acpiphp: Slot [21] registered Oct 9 01:25:40.050710 kernel: acpiphp: Slot [22] registered Oct 9 01:25:40.050719 kernel: acpiphp: Slot [23] registered Oct 9 01:25:40.050727 kernel: acpiphp: Slot [24] registered Oct 9 01:25:40.050736 kernel: acpiphp: Slot [25] registered Oct 9 01:25:40.050744 kernel: acpiphp: Slot [26] registered Oct 9 01:25:40.050753 kernel: acpiphp: Slot [27] registered Oct 9 01:25:40.050763 kernel: acpiphp: Slot [28] registered Oct 9 01:25:40.050772 kernel: acpiphp: Slot [29] registered Oct 9 01:25:40.050780 kernel: acpiphp: Slot [30] registered Oct 9 01:25:40.050789 kernel: acpiphp: Slot [31] registered Oct 9 01:25:40.050797 kernel: PCI host bridge to bus 0000:00 Oct 9 01:25:40.050895 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 9 01:25:40.050980 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 9 01:25:40.051061 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Oct 9 01:25:40.051173 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Oct 9 01:25:40.051253 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Oct 9 01:25:40.051332 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 9 01:25:40.051445 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Oct 9 01:25:40.051548 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Oct 9 01:25:40.051648 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Oct 9 01:25:40.051745 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] Oct 9 01:25:40.051838 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Oct 9 01:25:40.051930 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Oct 9 01:25:40.052029 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Oct 9 01:25:40.052146 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Oct 9 01:25:40.052259 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Oct 9 01:25:40.052358 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Oct 9 01:25:40.052460 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Oct 9 01:25:40.052567 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Oct 9 01:25:40.052668 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Oct 9 01:25:40.052767 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Oct 9 01:25:40.052875 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff] Oct 9 01:25:40.052972 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref] Oct 9 01:25:40.053083 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 9 01:25:40.053223 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Oct 9 01:25:40.053323 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc0bf] Oct 9 01:25:40.053421 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff] Oct 9 01:25:40.053518 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Oct 9 01:25:40.053615 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref] Oct 9 01:25:40.053723 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Oct 9 01:25:40.053822 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Oct 9 01:25:40.053925 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff] Oct 9 01:25:40.054022 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Oct 9 01:25:40.055808 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 Oct 9 01:25:40.055913 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0c0-0xc0ff] Oct 9 01:25:40.056005 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Oct 9 01:25:40.056107 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 Oct 9 01:25:40.056222 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc100-0xc11f] Oct 9 01:25:40.056320 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Oct 9 01:25:40.056334 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Oct 9 01:25:40.056343 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 9 01:25:40.056352 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Oct 9 01:25:40.056361 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 9 01:25:40.056370 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Oct 9 01:25:40.056379 kernel: iommu: Default domain type: Translated Oct 9 01:25:40.056388 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 9 01:25:40.056401 kernel: PCI: Using ACPI for IRQ routing Oct 9 01:25:40.056409 kernel: PCI: pci_cache_line_size set to 64 bytes Oct 9 01:25:40.056418 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Oct 9 01:25:40.056427 kernel: e820: reserve RAM buffer [mem 0x7ffdd000-0x7fffffff] Oct 9 01:25:40.056519 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Oct 9 01:25:40.056611 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Oct 9 01:25:40.056703 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 9 01:25:40.056716 kernel: vgaarb: loaded Oct 9 01:25:40.056725 kernel: clocksource: Switched to clocksource kvm-clock Oct 9 01:25:40.056738 kernel: VFS: Disk quotas dquot_6.6.0 Oct 9 01:25:40.056747 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 9 01:25:40.056755 kernel: pnp: PnP ACPI init Oct 9 01:25:40.056848 kernel: pnp 00:03: [dma 2] Oct 9 01:25:40.056863 kernel: pnp: PnP ACPI: found 5 devices Oct 9 01:25:40.056872 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 9 01:25:40.056881 kernel: NET: Registered PF_INET protocol family Oct 9 01:25:40.056889 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 9 01:25:40.056902 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Oct 9 01:25:40.056911 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 9 01:25:40.056919 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 9 01:25:40.056928 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Oct 9 01:25:40.056937 kernel: TCP: Hash tables configured (established 16384 bind 16384) Oct 9 01:25:40.056946 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 9 01:25:40.056955 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 9 01:25:40.056963 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 9 01:25:40.056972 kernel: NET: Registered PF_XDP protocol family Oct 9 01:25:40.057067 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 9 01:25:40.057172 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 9 01:25:40.057254 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Oct 9 01:25:40.057334 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Oct 9 01:25:40.057414 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Oct 9 01:25:40.057507 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Oct 9 01:25:40.057602 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Oct 9 01:25:40.057615 kernel: PCI: CLS 0 bytes, default 64 Oct 9 01:25:40.057628 kernel: Initialise system trusted keyrings Oct 9 01:25:40.057637 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Oct 9 01:25:40.057646 kernel: Key type asymmetric registered Oct 9 01:25:40.057654 kernel: Asymmetric key parser 'x509' registered Oct 9 01:25:40.057663 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 9 01:25:40.057672 kernel: io scheduler mq-deadline registered Oct 9 01:25:40.057681 kernel: io scheduler kyber registered Oct 9 01:25:40.057690 kernel: io scheduler bfq registered Oct 9 01:25:40.057699 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 9 01:25:40.057711 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Oct 9 01:25:40.057720 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Oct 9 01:25:40.057729 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Oct 9 01:25:40.057737 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Oct 9 01:25:40.057746 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 9 01:25:40.057755 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 9 01:25:40.057764 kernel: random: crng init done Oct 9 01:25:40.057773 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Oct 9 01:25:40.057782 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Oct 9 01:25:40.057793 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Oct 9 01:25:40.057898 kernel: rtc_cmos 00:04: RTC can wake from S4 Oct 9 01:25:40.057913 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Oct 9 01:25:40.057997 kernel: rtc_cmos 00:04: registered as rtc0 Oct 9 01:25:40.058081 kernel: rtc_cmos 00:04: setting system clock to 2024-10-09T01:25:39 UTC (1728437139) Oct 9 01:25:40.058537 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Oct 9 01:25:40.058554 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Oct 9 01:25:40.058563 kernel: NET: Registered PF_INET6 protocol family Oct 9 01:25:40.058576 kernel: Segment Routing with IPv6 Oct 9 01:25:40.058584 kernel: In-situ OAM (IOAM) with IPv6 Oct 9 01:25:40.058593 kernel: NET: Registered PF_PACKET protocol family Oct 9 01:25:40.058602 kernel: Key type dns_resolver registered Oct 9 01:25:40.058611 kernel: IPI shorthand broadcast: enabled Oct 9 01:25:40.058619 kernel: sched_clock: Marking stable (1006006938, 127547543)->(1136636161, -3081680) Oct 9 01:25:40.058628 kernel: registered taskstats version 1 Oct 9 01:25:40.058637 kernel: Loading compiled-in X.509 certificates Oct 9 01:25:40.058646 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.54-flatcar: 03ae66f5ce294ce3ab718ee0d7c4a4a6e8c5aae6' Oct 9 01:25:40.058657 kernel: Key type .fscrypt registered Oct 9 01:25:40.058666 kernel: Key type fscrypt-provisioning registered Oct 9 01:25:40.058675 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 9 01:25:40.058684 kernel: ima: Allocated hash algorithm: sha1 Oct 9 01:25:40.058692 kernel: ima: No architecture policies found Oct 9 01:25:40.058701 kernel: clk: Disabling unused clocks Oct 9 01:25:40.058710 kernel: Freeing unused kernel image (initmem) memory: 42872K Oct 9 01:25:40.058719 kernel: Write protecting the kernel read-only data: 36864k Oct 9 01:25:40.058729 kernel: Freeing unused kernel image (rodata/data gap) memory: 1848K Oct 9 01:25:40.058738 kernel: Run /init as init process Oct 9 01:25:40.058746 kernel: with arguments: Oct 9 01:25:40.058755 kernel: /init Oct 9 01:25:40.058763 kernel: with environment: Oct 9 01:25:40.058772 kernel: HOME=/ Oct 9 01:25:40.058781 kernel: TERM=linux Oct 9 01:25:40.058789 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 9 01:25:40.058801 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) Oct 9 01:25:40.058815 systemd[1]: Detected virtualization kvm. Oct 9 01:25:40.058825 systemd[1]: Detected architecture x86-64. Oct 9 01:25:40.058834 systemd[1]: Running in initrd. Oct 9 01:25:40.058843 systemd[1]: No hostname configured, using default hostname. Oct 9 01:25:40.058852 systemd[1]: Hostname set to . Oct 9 01:25:40.058862 systemd[1]: Initializing machine ID from VM UUID. Oct 9 01:25:40.058871 systemd[1]: Queued start job for default target initrd.target. Oct 9 01:25:40.058882 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:25:40.058892 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:25:40.058902 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 9 01:25:40.058912 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 01:25:40.058921 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 9 01:25:40.058931 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 9 01:25:40.058942 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 9 01:25:40.058954 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 9 01:25:40.058964 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:25:40.058974 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:25:40.058985 systemd[1]: Reached target paths.target - Path Units. Oct 9 01:25:40.059006 systemd[1]: Reached target slices.target - Slice Units. Oct 9 01:25:40.059019 systemd[1]: Reached target swap.target - Swaps. Oct 9 01:25:40.059030 systemd[1]: Reached target timers.target - Timer Units. Oct 9 01:25:40.059040 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 01:25:40.059049 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 01:25:40.059059 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 9 01:25:40.059069 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 9 01:25:40.059079 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:25:40.059089 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 01:25:40.059099 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:25:40.059254 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 01:25:40.059272 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 9 01:25:40.059282 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 01:25:40.059291 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 9 01:25:40.059301 systemd[1]: Starting systemd-fsck-usr.service... Oct 9 01:25:40.059311 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 01:25:40.059321 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 01:25:40.059350 systemd-journald[184]: Collecting audit messages is disabled. Oct 9 01:25:40.059376 systemd-journald[184]: Journal started Oct 9 01:25:40.059398 systemd-journald[184]: Runtime Journal (/run/log/journal/74d701e3111143cfb89affb596787429) is 4.9M, max 39.3M, 34.4M free. Oct 9 01:25:40.059446 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:25:40.070141 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 01:25:40.077447 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 9 01:25:40.078014 systemd-modules-load[185]: Inserted module 'overlay' Oct 9 01:25:40.080250 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:25:40.082818 systemd[1]: Finished systemd-fsck-usr.service. Oct 9 01:25:40.102592 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 01:25:40.150081 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 9 01:25:40.150174 kernel: Bridge firewalling registered Oct 9 01:25:40.115372 systemd-modules-load[185]: Inserted module 'br_netfilter' Oct 9 01:25:40.153362 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 01:25:40.157476 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 01:25:40.158340 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:25:40.167457 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:25:40.172302 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 01:25:40.175299 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:25:40.180025 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:25:40.187525 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 01:25:40.201312 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:25:40.215656 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 01:25:40.232685 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:25:40.234162 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:25:40.242420 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 9 01:25:40.279455 dracut-cmdline[221]: dracut-dracut-053 Oct 9 01:25:40.283143 dracut-cmdline[221]: 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=openstack flatcar.autologin verity.usrhash=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:25:40.290992 systemd-resolved[217]: Positive Trust Anchors: Oct 9 01:25:40.291003 systemd-resolved[217]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 01:25:40.291044 systemd-resolved[217]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 9 01:25:40.295334 systemd-resolved[217]: Defaulting to hostname 'linux'. Oct 9 01:25:40.296281 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 01:25:40.299611 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:25:40.351176 kernel: SCSI subsystem initialized Oct 9 01:25:40.361155 kernel: Loading iSCSI transport class v2.0-870. Oct 9 01:25:40.373165 kernel: iscsi: registered transport (tcp) Oct 9 01:25:40.395375 kernel: iscsi: registered transport (qla4xxx) Oct 9 01:25:40.395438 kernel: QLogic iSCSI HBA Driver Oct 9 01:25:40.435202 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 9 01:25:40.443251 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 9 01:25:40.469519 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 9 01:25:40.469638 kernel: device-mapper: uevent: version 1.0.3 Oct 9 01:25:40.470548 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 9 01:25:40.535196 kernel: raid6: sse2x4 gen() 5203 MB/s Oct 9 01:25:40.550359 kernel: raid6: sse2x2 gen() 10133 MB/s Oct 9 01:25:40.567217 kernel: raid6: sse2x1 gen() 9912 MB/s Oct 9 01:25:40.567248 kernel: raid6: using algorithm sse2x2 gen() 10133 MB/s Oct 9 01:25:40.585315 kernel: raid6: .... xor() 9394 MB/s, rmw enabled Oct 9 01:25:40.585341 kernel: raid6: using ssse3x2 recovery algorithm Oct 9 01:25:40.608242 kernel: xor: measuring software checksum speed Oct 9 01:25:40.608305 kernel: prefetch64-sse : 18482 MB/sec Oct 9 01:25:40.609210 kernel: generic_sse : 16858 MB/sec Oct 9 01:25:40.610805 kernel: xor: using function: prefetch64-sse (18482 MB/sec) Oct 9 01:25:40.797256 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 9 01:25:40.815600 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 9 01:25:40.820347 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:25:40.845881 systemd-udevd[403]: Using default interface naming scheme 'v255'. Oct 9 01:25:40.850368 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:25:40.862773 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 9 01:25:40.885330 dracut-pre-trigger[418]: rd.md=0: removing MD RAID activation Oct 9 01:25:40.928474 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 01:25:40.938427 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 01:25:40.997358 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:25:41.003412 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 9 01:25:41.027573 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 9 01:25:41.030981 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 01:25:41.032041 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:25:41.034971 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 01:25:41.045246 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 9 01:25:41.070179 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 9 01:25:41.078145 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues Oct 9 01:25:41.086140 kernel: virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB) Oct 9 01:25:41.124139 kernel: libata version 3.00 loaded. Oct 9 01:25:41.127738 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 01:25:41.128547 kernel: ata_piix 0000:00:01.1: version 2.13 Oct 9 01:25:41.127922 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:25:41.136506 kernel: scsi host0: ata_piix Oct 9 01:25:41.136681 kernel: scsi host1: ata_piix Oct 9 01:25:41.136813 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 Oct 9 01:25:41.136835 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 Oct 9 01:25:41.136223 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:25:41.138976 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:25:41.139178 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:25:41.140322 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:25:41.152398 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:25:41.180191 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 9 01:25:41.180262 kernel: GPT:17805311 != 41943039 Oct 9 01:25:41.180276 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 9 01:25:41.180288 kernel: GPT:17805311 != 41943039 Oct 9 01:25:41.180299 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 9 01:25:41.180310 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:25:41.210479 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:25:41.218368 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:25:41.239771 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:25:41.341185 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (467) Oct 9 01:25:41.358202 kernel: BTRFS: device fsid 6ed52ce5-b2f8-4d16-8889-677a209bc377 devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (469) Oct 9 01:25:41.387591 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 9 01:25:41.394375 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 9 01:25:41.400367 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 01:25:41.405008 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 9 01:25:41.405630 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 9 01:25:41.412293 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 9 01:25:41.442574 disk-uuid[513]: Primary Header is updated. Oct 9 01:25:41.442574 disk-uuid[513]: Secondary Entries is updated. Oct 9 01:25:41.442574 disk-uuid[513]: Secondary Header is updated. Oct 9 01:25:41.453181 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:25:41.459319 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:25:42.477081 disk-uuid[514]: The operation has completed successfully. Oct 9 01:25:42.480046 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:25:42.544880 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 9 01:25:42.545178 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 9 01:25:42.582276 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 9 01:25:42.586161 sh[527]: Success Oct 9 01:25:42.607283 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3" Oct 9 01:25:42.694491 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 9 01:25:42.712378 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 9 01:25:42.716583 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 9 01:25:42.753792 kernel: BTRFS info (device dm-0): first mount of filesystem 6ed52ce5-b2f8-4d16-8889-677a209bc377 Oct 9 01:25:42.753888 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:25:42.753919 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 9 01:25:42.756425 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 9 01:25:42.758458 kernel: BTRFS info (device dm-0): using free space tree Oct 9 01:25:42.776352 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 9 01:25:42.778515 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 9 01:25:42.785392 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 9 01:25:42.794416 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 9 01:25:42.816910 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:25:42.817004 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:25:42.820652 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:25:42.835182 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:25:42.844057 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 9 01:25:42.850982 kernel: BTRFS info (device vda6): last unmount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:25:42.860134 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 9 01:25:42.869287 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 9 01:25:42.919422 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 01:25:42.926293 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 01:25:42.955802 systemd-networkd[709]: lo: Link UP Oct 9 01:25:42.955810 systemd-networkd[709]: lo: Gained carrier Oct 9 01:25:42.958479 systemd-networkd[709]: Enumeration completed Oct 9 01:25:42.959083 systemd-networkd[709]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:25:42.959087 systemd-networkd[709]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 9 01:25:42.960082 systemd-networkd[709]: eth0: Link UP Oct 9 01:25:42.960086 systemd-networkd[709]: eth0: Gained carrier Oct 9 01:25:42.960096 systemd-networkd[709]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:25:42.960824 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 01:25:42.963052 systemd[1]: Reached target network.target - Network. Oct 9 01:25:42.970206 systemd-networkd[709]: eth0: DHCPv4 address 172.24.4.107/24, gateway 172.24.4.1 acquired from 172.24.4.1 Oct 9 01:25:43.048829 ignition[626]: Ignition 2.19.0 Oct 9 01:25:43.048841 ignition[626]: Stage: fetch-offline Oct 9 01:25:43.050587 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 01:25:43.048879 ignition[626]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:25:43.048888 ignition[626]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:25:43.053085 systemd-resolved[217]: Detected conflict on linux IN A 172.24.4.107 Oct 9 01:25:43.049002 ignition[626]: parsed url from cmdline: "" Oct 9 01:25:43.053095 systemd-resolved[217]: Hostname conflict, changing published hostname from 'linux' to 'linux9'. Oct 9 01:25:43.049006 ignition[626]: no config URL provided Oct 9 01:25:43.049011 ignition[626]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 01:25:43.049020 ignition[626]: no config at "/usr/lib/ignition/user.ign" Oct 9 01:25:43.049024 ignition[626]: failed to fetch config: resource requires networking Oct 9 01:25:43.049307 ignition[626]: Ignition finished successfully Oct 9 01:25:43.058317 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 9 01:25:43.072974 ignition[717]: Ignition 2.19.0 Oct 9 01:25:43.073573 ignition[717]: Stage: fetch Oct 9 01:25:43.073883 ignition[717]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:25:43.073914 ignition[717]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:25:43.074031 ignition[717]: parsed url from cmdline: "" Oct 9 01:25:43.074035 ignition[717]: no config URL provided Oct 9 01:25:43.074042 ignition[717]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 01:25:43.074051 ignition[717]: no config at "/usr/lib/ignition/user.ign" Oct 9 01:25:43.074184 ignition[717]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Oct 9 01:25:43.074432 ignition[717]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Oct 9 01:25:43.074477 ignition[717]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Oct 9 01:25:43.300789 ignition[717]: GET result: OK Oct 9 01:25:43.300902 ignition[717]: parsing config with SHA512: ffe856c537a5cc387270d3433d7243a9305f81d1e74f94a58df2a49ed656c9abf211feb9da2670e66af37149146417973e4a3520f57071e173469cb5cc8fea34 Oct 9 01:25:43.324036 unknown[717]: fetched base config from "system" Oct 9 01:25:43.324075 unknown[717]: fetched base config from "system" Oct 9 01:25:43.324747 ignition[717]: fetch: fetch complete Oct 9 01:25:43.324096 unknown[717]: fetched user config from "openstack" Oct 9 01:25:43.324760 ignition[717]: fetch: fetch passed Oct 9 01:25:43.328026 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 9 01:25:43.324883 ignition[717]: Ignition finished successfully Oct 9 01:25:43.337541 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 9 01:25:43.371658 ignition[724]: Ignition 2.19.0 Oct 9 01:25:43.371685 ignition[724]: Stage: kargs Oct 9 01:25:43.372099 ignition[724]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:25:43.372173 ignition[724]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:25:43.375912 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 9 01:25:43.374019 ignition[724]: kargs: kargs passed Oct 9 01:25:43.374108 ignition[724]: Ignition finished successfully Oct 9 01:25:43.385416 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 9 01:25:43.403618 ignition[730]: Ignition 2.19.0 Oct 9 01:25:43.403630 ignition[730]: Stage: disks Oct 9 01:25:43.405491 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 9 01:25:43.403818 ignition[730]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:25:43.407351 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 9 01:25:43.403829 ignition[730]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:25:43.408615 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 9 01:25:43.404603 ignition[730]: disks: disks passed Oct 9 01:25:43.410415 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 01:25:43.404646 ignition[730]: Ignition finished successfully Oct 9 01:25:43.412385 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 01:25:43.414460 systemd[1]: Reached target basic.target - Basic System. Oct 9 01:25:43.423320 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 9 01:25:43.447046 systemd-fsck[739]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Oct 9 01:25:43.458989 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 9 01:25:43.466323 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 9 01:25:43.596133 kernel: EXT4-fs (vda9): mounted filesystem ba2945c1-be14-41c0-8c54-84d676c7a16b r/w with ordered data mode. Quota mode: none. Oct 9 01:25:43.597760 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 9 01:25:43.599724 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 9 01:25:43.617321 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 01:25:43.621286 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 9 01:25:43.622359 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 9 01:25:43.626424 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Oct 9 01:25:43.645203 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (747) Oct 9 01:25:43.645282 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:25:43.645325 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:25:43.645353 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:25:43.645380 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:25:43.627262 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 9 01:25:43.627291 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 01:25:43.632839 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 9 01:25:43.653304 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 9 01:25:43.654932 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 01:25:43.819063 initrd-setup-root[778]: cut: /sysroot/etc/passwd: No such file or directory Oct 9 01:25:43.829572 initrd-setup-root[785]: cut: /sysroot/etc/group: No such file or directory Oct 9 01:25:43.838576 initrd-setup-root[792]: cut: /sysroot/etc/shadow: No such file or directory Oct 9 01:25:43.844528 initrd-setup-root[799]: cut: /sysroot/etc/gshadow: No such file or directory Oct 9 01:25:44.398903 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 9 01:25:44.407312 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 9 01:25:44.417726 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 9 01:25:44.432563 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 9 01:25:44.436360 kernel: BTRFS info (device vda6): last unmount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:25:44.523862 ignition[866]: INFO : Ignition 2.19.0 Oct 9 01:25:44.523862 ignition[866]: INFO : Stage: mount Oct 9 01:25:44.527628 ignition[866]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:25:44.527628 ignition[866]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:25:44.527628 ignition[866]: INFO : mount: mount passed Oct 9 01:25:44.527628 ignition[866]: INFO : Ignition finished successfully Oct 9 01:25:44.531645 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 9 01:25:44.552510 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 9 01:25:44.861918 systemd-networkd[709]: eth0: Gained IPv6LL Oct 9 01:25:51.128470 coreos-metadata[749]: Oct 09 01:25:51.128 WARN failed to locate config-drive, using the metadata service API instead Oct 9 01:25:51.168886 coreos-metadata[749]: Oct 09 01:25:51.168 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Oct 9 01:25:51.185655 coreos-metadata[749]: Oct 09 01:25:51.185 INFO Fetch successful Oct 9 01:25:51.187621 coreos-metadata[749]: Oct 09 01:25:51.186 INFO wrote hostname ci-4116-0-0-4-3f8c4891e2.novalocal to /sysroot/etc/hostname Oct 9 01:25:51.191078 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Oct 9 01:25:51.191438 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Oct 9 01:25:51.204475 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 9 01:25:51.239552 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 01:25:51.272192 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (883) Oct 9 01:25:51.281975 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:25:51.282064 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:25:51.285691 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:25:51.296184 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:25:51.302950 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 01:25:51.345975 ignition[901]: INFO : Ignition 2.19.0 Oct 9 01:25:51.348733 ignition[901]: INFO : Stage: files Oct 9 01:25:51.348733 ignition[901]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:25:51.348733 ignition[901]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:25:51.353353 ignition[901]: DEBUG : files: compiled without relabeling support, skipping Oct 9 01:25:51.353353 ignition[901]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 9 01:25:51.353353 ignition[901]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 9 01:25:51.358743 ignition[901]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 9 01:25:51.358743 ignition[901]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 9 01:25:51.358743 ignition[901]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 9 01:25:51.358706 unknown[901]: wrote ssh authorized keys file for user: core Oct 9 01:25:51.365797 ignition[901]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 01:25:51.365797 ignition[901]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 01:25:51.365797 ignition[901]: INFO : files: op(4): [started] processing unit "docker.service" Oct 9 01:25:51.365797 ignition[901]: INFO : files: op(4): [finished] processing unit "docker.service" Oct 9 01:25:51.365797 ignition[901]: INFO : files: op(5): [started] processing unit "flanneld.service" Oct 9 01:25:51.365797 ignition[901]: INFO : files: op(5): op(6): [started] writing systemd drop-in "50-network-config.conf" at "/sysroot/etc/systemd/system/flanneld.service.d/50-network-config.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(5): op(6): [finished] writing systemd drop-in "50-network-config.conf" at "/sysroot/etc/systemd/system/flanneld.service.d/50-network-config.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(5): [finished] processing unit "flanneld.service" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(7): op(8): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(7): op(8): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(9): [started] processing unit "coreos-metadata-sshkeys@.service" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(9): op(a): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(9): op(a): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(9): [finished] processing unit "coreos-metadata-sshkeys@.service" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(b): [started] processing unit "etcd-member.service" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(b): op(c): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(b): op(c): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(b): [finished] processing unit "etcd-member.service" Oct 9 01:25:51.370750 ignition[901]: INFO : files: op(d): [started] processing unit "flannel-docker-opts.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(d): op(e): [started] writing systemd drop-in "retry.conf" at "/sysroot/etc/systemd/system/flannel-docker-opts.service.d/retry.conf" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(d): op(e): [finished] writing systemd drop-in "retry.conf" at "/sysroot/etc/systemd/system/flannel-docker-opts.service.d/retry.conf" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(d): [finished] processing unit "flannel-docker-opts.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(f): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(f): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(10): [started] setting preset to enabled for "docker.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(10): [finished] setting preset to enabled for "docker.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(11): [started] setting preset to enabled for "etcd-member.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(11): [finished] setting preset to enabled for "etcd-member.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(12): [started] setting preset to enabled for "flanneld.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: op(12): [finished] setting preset to enabled for "flanneld.service" Oct 9 01:25:51.385138 ignition[901]: INFO : files: createResultFile: createFiles: op(13): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 9 01:25:51.385138 ignition[901]: INFO : files: createResultFile: createFiles: op(13): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 9 01:25:51.385138 ignition[901]: INFO : files: files passed Oct 9 01:25:51.385138 ignition[901]: INFO : Ignition finished successfully Oct 9 01:25:51.382563 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 9 01:25:51.393343 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 9 01:25:51.398366 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 9 01:25:51.403667 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 9 01:25:51.403853 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 9 01:25:51.412067 initrd-setup-root-after-ignition[928]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:25:51.412067 initrd-setup-root-after-ignition[928]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:25:51.416203 initrd-setup-root-after-ignition[933]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:25:51.415521 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 01:25:51.416685 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 9 01:25:51.422266 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 9 01:25:51.441495 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 9 01:25:51.441603 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 9 01:25:51.443029 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 9 01:25:51.443924 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 9 01:25:51.445039 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 9 01:25:51.446389 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 9 01:25:51.460758 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 01:25:51.466230 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 9 01:25:51.476614 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:25:51.478010 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:25:51.478690 systemd[1]: Stopped target timers.target - Timer Units. Oct 9 01:25:51.479809 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 9 01:25:51.479931 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 01:25:51.481193 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 9 01:25:51.481955 systemd[1]: Stopped target basic.target - Basic System. Oct 9 01:25:51.483047 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 9 01:25:51.484007 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 01:25:51.484915 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 9 01:25:51.486084 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 9 01:25:51.487205 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 01:25:51.488368 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 9 01:25:51.489539 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 9 01:25:51.490738 systemd[1]: Stopped target swap.target - Swaps. Oct 9 01:25:51.491742 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 9 01:25:51.491857 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 9 01:25:51.493023 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:25:51.493823 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:25:51.494827 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 9 01:25:51.494942 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:25:51.495966 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 9 01:25:51.496085 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 9 01:25:51.497625 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 9 01:25:51.497758 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 01:25:51.498490 systemd[1]: ignition-files.service: Deactivated successfully. Oct 9 01:25:51.498596 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 9 01:25:51.510301 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 9 01:25:51.514290 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 9 01:25:51.514773 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 9 01:25:51.514894 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:25:51.515549 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 9 01:25:51.515670 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 01:25:51.525475 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 9 01:25:51.526136 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 9 01:25:51.530408 ignition[953]: INFO : Ignition 2.19.0 Oct 9 01:25:51.531222 ignition[953]: INFO : Stage: umount Oct 9 01:25:51.532165 ignition[953]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:25:51.532165 ignition[953]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:25:51.533896 ignition[953]: INFO : umount: umount passed Oct 9 01:25:51.534514 ignition[953]: INFO : Ignition finished successfully Oct 9 01:25:51.536186 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 9 01:25:51.536276 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 9 01:25:51.538075 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 9 01:25:51.538670 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 9 01:25:51.539780 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 9 01:25:51.539824 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 9 01:25:51.540831 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 9 01:25:51.540869 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 9 01:25:51.542267 systemd[1]: Stopped target network.target - Network. Oct 9 01:25:51.543381 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 9 01:25:51.543425 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 01:25:51.544386 systemd[1]: Stopped target paths.target - Path Units. Oct 9 01:25:51.545400 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 9 01:25:51.550754 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:25:51.551335 systemd[1]: Stopped target slices.target - Slice Units. Oct 9 01:25:51.552473 systemd[1]: Stopped target sockets.target - Socket Units. Oct 9 01:25:51.553531 systemd[1]: iscsid.socket: Deactivated successfully. Oct 9 01:25:51.553568 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 01:25:51.554434 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 9 01:25:51.554467 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 01:25:51.555348 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 9 01:25:51.555391 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 9 01:25:51.556286 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 9 01:25:51.556325 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 9 01:25:51.557355 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 9 01:25:51.558437 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 9 01:25:51.560250 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 9 01:25:51.560741 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 9 01:25:51.560824 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 9 01:25:51.561771 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 9 01:25:51.561836 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 9 01:25:51.562288 systemd-networkd[709]: eth0: DHCPv6 lease lost Oct 9 01:25:51.563689 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 9 01:25:51.563773 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 9 01:25:51.565290 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 9 01:25:51.565339 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:25:51.572291 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 9 01:25:51.573097 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 9 01:25:51.573171 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 01:25:51.573838 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:25:51.579527 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 9 01:25:51.579620 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 9 01:25:51.586365 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 9 01:25:51.586519 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:25:51.588692 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 9 01:25:51.588753 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 9 01:25:51.589988 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 9 01:25:51.590021 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:25:51.591150 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 9 01:25:51.591210 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 9 01:25:51.592799 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 9 01:25:51.592843 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 9 01:25:51.593970 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 01:25:51.594015 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:25:51.604335 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 9 01:25:51.604894 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 9 01:25:51.604956 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:25:51.605534 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 9 01:25:51.605578 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 9 01:25:51.608151 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 9 01:25:51.608201 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:25:51.608794 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 9 01:25:51.608837 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:25:51.610179 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 9 01:25:51.610222 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:25:51.611425 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 9 01:25:51.611467 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:25:51.612652 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:25:51.612693 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:25:51.614285 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 9 01:25:51.614384 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 9 01:25:51.615339 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 9 01:25:51.615409 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 9 01:25:51.616904 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 9 01:25:51.624485 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 9 01:25:51.631587 systemd[1]: Switching root. Oct 9 01:25:51.667240 systemd-journald[184]: Journal stopped Oct 9 01:25:52.913445 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). Oct 9 01:25:52.913506 kernel: SELinux: policy capability network_peer_controls=1 Oct 9 01:25:52.913525 kernel: SELinux: policy capability open_perms=1 Oct 9 01:25:52.913538 kernel: SELinux: policy capability extended_socket_class=1 Oct 9 01:25:52.913550 kernel: SELinux: policy capability always_check_network=0 Oct 9 01:25:52.913569 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 9 01:25:52.913581 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 9 01:25:52.913593 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 9 01:25:52.913604 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 9 01:25:52.913616 kernel: audit: type=1403 audit(1728437151.826:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 9 01:25:52.913628 systemd[1]: Successfully loaded SELinux policy in 70.145ms. Oct 9 01:25:52.913652 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 24.327ms. Oct 9 01:25:52.913667 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) Oct 9 01:25:52.913686 systemd[1]: Detected virtualization kvm. Oct 9 01:25:52.913699 systemd[1]: Detected architecture x86-64. Oct 9 01:25:52.913712 systemd[1]: Detected first boot. Oct 9 01:25:52.913729 systemd[1]: Hostname set to . Oct 9 01:25:52.913749 systemd[1]: Initializing machine ID from VM UUID. Oct 9 01:25:52.913762 zram_generator::config[996]: No configuration found. Oct 9 01:25:52.913777 systemd[1]: Populated /etc with preset unit settings. Oct 9 01:25:52.913793 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 9 01:25:52.913806 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 9 01:25:52.913819 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 9 01:25:52.913833 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 9 01:25:52.913846 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 9 01:25:52.913859 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Oct 9 01:25:52.913872 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 9 01:25:52.913884 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 9 01:25:52.913897 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 9 01:25:52.913912 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 9 01:25:52.913929 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 9 01:25:52.913941 systemd[1]: Created slice user.slice - User and Session Slice. Oct 9 01:25:52.913954 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:25:52.913967 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:25:52.913979 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 9 01:25:52.913992 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 9 01:25:52.914004 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 9 01:25:52.914020 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 01:25:52.914034 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 9 01:25:52.914047 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:25:52.914060 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 9 01:25:52.914073 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 9 01:25:52.914085 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 9 01:25:52.914098 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 9 01:25:52.916963 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:25:52.916988 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 01:25:52.917001 systemd[1]: Reached target slices.target - Slice Units. Oct 9 01:25:52.917025 systemd[1]: Reached target swap.target - Swaps. Oct 9 01:25:52.917038 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 9 01:25:52.917050 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 9 01:25:52.917062 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:25:52.917074 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 01:25:52.917086 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:25:52.917104 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 9 01:25:52.922951 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 9 01:25:52.922977 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 9 01:25:52.922990 systemd[1]: Mounting media.mount - External Media Directory... Oct 9 01:25:52.923004 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:52.923018 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 9 01:25:52.923031 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 9 01:25:52.923044 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 9 01:25:52.923057 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). Oct 9 01:25:52.923077 systemd[1]: Reached target machines.target - Containers. Oct 9 01:25:52.923090 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 9 01:25:52.923103 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:25:52.923140 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 01:25:52.923154 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 9 01:25:52.923167 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:25:52.923180 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 01:25:52.923193 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:25:52.923209 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 9 01:25:52.923222 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:25:52.923235 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 9 01:25:52.923247 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 9 01:25:52.923260 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 9 01:25:52.923273 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 9 01:25:52.923285 systemd[1]: Stopped systemd-fsck-usr.service. Oct 9 01:25:52.923299 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 01:25:52.923311 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 01:25:52.923326 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 9 01:25:52.923340 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 9 01:25:52.923353 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 01:25:52.923366 systemd[1]: verity-setup.service: Deactivated successfully. Oct 9 01:25:52.923378 systemd[1]: Stopped verity-setup.service. Oct 9 01:25:52.923391 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:52.923404 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 9 01:25:52.923417 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 9 01:25:52.923432 systemd[1]: Mounted media.mount - External Media Directory. Oct 9 01:25:52.923445 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 9 01:25:52.923458 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 9 01:25:52.923470 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 9 01:25:52.923483 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:25:52.923501 kernel: fuse: init (API version 7.39) Oct 9 01:25:52.923515 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 9 01:25:52.923528 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 9 01:25:52.923541 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:25:52.923556 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:25:52.923569 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:25:52.923584 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:25:52.923596 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 9 01:25:52.923609 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 9 01:25:52.923622 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 01:25:52.923635 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 9 01:25:52.923648 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 9 01:25:52.923662 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 9 01:25:52.923674 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 9 01:25:52.923690 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 9 01:25:52.923705 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 9 01:25:52.923718 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 01:25:52.923769 systemd-journald[1087]: Collecting audit messages is disabled. Oct 9 01:25:52.923795 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 9 01:25:52.923808 kernel: loop: module loaded Oct 9 01:25:52.923820 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 9 01:25:52.923835 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 9 01:25:52.923851 systemd-journald[1087]: Journal started Oct 9 01:25:52.923876 systemd-journald[1087]: Runtime Journal (/run/log/journal/74d701e3111143cfb89affb596787429) is 4.9M, max 39.3M, 34.4M free. Oct 9 01:25:52.539962 systemd[1]: Queued start job for default target multi-user.target. Oct 9 01:25:52.570477 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 9 01:25:52.570867 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 9 01:25:52.933564 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:25:52.944130 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 9 01:25:52.951268 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:25:52.955414 kernel: ACPI: bus type drm_connector registered Oct 9 01:25:52.955470 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 9 01:25:52.966149 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 01:25:52.966207 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 9 01:25:52.978172 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 01:25:52.986036 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 01:25:52.986094 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 9 01:25:52.986890 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 01:25:52.987042 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 01:25:52.988616 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:25:52.988774 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:25:52.990617 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:25:52.991330 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 9 01:25:52.992248 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 9 01:25:52.993416 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 9 01:25:52.995256 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 9 01:25:53.022660 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 9 01:25:53.031842 kernel: loop0: detected capacity change from 0 to 8 Oct 9 01:25:53.031299 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 9 01:25:53.044252 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 9 01:25:53.045683 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 01:25:53.052186 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 9 01:25:53.054097 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 9 01:25:53.055218 systemd-journald[1087]: Time spent on flushing to /var/log/journal/74d701e3111143cfb89affb596787429 is 40.382ms for 954 entries. Oct 9 01:25:53.055218 systemd-journald[1087]: System Journal (/var/log/journal/74d701e3111143cfb89affb596787429) is 8.0M, max 584.8M, 576.8M free. Oct 9 01:25:53.148469 systemd-journald[1087]: Received client request to flush runtime journal. Oct 9 01:25:53.148512 kernel: loop1: detected capacity change from 0 to 140992 Oct 9 01:25:53.059621 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:25:53.078762 systemd-tmpfiles[1112]: ACLs are not supported, ignoring. Oct 9 01:25:53.078776 systemd-tmpfiles[1112]: ACLs are not supported, ignoring. Oct 9 01:25:53.083925 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:25:53.095016 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 9 01:25:53.098262 udevadm[1142]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 9 01:25:53.150571 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 9 01:25:53.155590 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 9 01:25:53.156525 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 9 01:25:53.178101 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 9 01:25:53.188375 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 01:25:53.207266 systemd-tmpfiles[1152]: ACLs are not supported, ignoring. Oct 9 01:25:53.207286 systemd-tmpfiles[1152]: ACLs are not supported, ignoring. Oct 9 01:25:53.212306 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:25:53.214185 kernel: loop2: detected capacity change from 0 to 138192 Oct 9 01:25:53.276157 kernel: loop3: detected capacity change from 0 to 8 Oct 9 01:25:53.278167 kernel: loop4: detected capacity change from 0 to 140992 Oct 9 01:25:53.364150 kernel: loop5: detected capacity change from 0 to 138192 Oct 9 01:25:53.410957 (sd-merge)[1156]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-openstack'. Oct 9 01:25:53.411392 (sd-merge)[1156]: Merged extensions into '/usr'. Oct 9 01:25:53.419671 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 9 01:25:53.427334 systemd[1]: Starting ensure-sysext.service... Oct 9 01:25:53.432697 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 01:25:53.441976 systemd[1]: Reloading requested from client PID 1158 ('systemctl') (unit ensure-sysext.service)... Oct 9 01:25:53.441993 systemd[1]: Reloading... Oct 9 01:25:53.516516 systemd-tmpfiles[1159]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 9 01:25:53.519641 systemd-tmpfiles[1159]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 9 01:25:53.524365 systemd-tmpfiles[1159]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 9 01:25:53.531142 zram_generator::config[1182]: No configuration found. Oct 9 01:25:53.527541 systemd-tmpfiles[1159]: ACLs are not supported, ignoring. Oct 9 01:25:53.527607 systemd-tmpfiles[1159]: ACLs are not supported, ignoring. Oct 9 01:25:53.540013 systemd-tmpfiles[1159]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 01:25:53.540181 systemd-tmpfiles[1159]: Skipping /boot Oct 9 01:25:53.553807 systemd-tmpfiles[1159]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 01:25:53.553823 systemd-tmpfiles[1159]: Skipping /boot Oct 9 01:25:53.757297 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. Oct 9 01:25:53.814334 systemd[1]: Reloading finished in 371 ms. Oct 9 01:25:53.843015 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:25:53.852923 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 9 01:25:53.858326 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 9 01:25:53.863259 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 9 01:25:53.874891 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 01:25:53.880303 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 9 01:25:53.893436 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:53.893738 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:25:53.900548 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:25:53.912694 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:25:53.916313 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:25:53.917035 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:25:53.918206 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:53.919320 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:25:53.920256 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:25:53.928924 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:53.929188 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:25:53.935524 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:25:53.937358 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:25:53.948428 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 9 01:25:53.949038 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:53.960039 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:25:53.960835 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:25:53.962772 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:25:53.963937 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:25:53.966657 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:53.967051 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:25:53.982212 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 01:25:53.982929 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:25:53.983087 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:25:53.983256 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:25:53.984510 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 9 01:25:53.986313 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 9 01:25:53.988345 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:25:53.988480 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:25:53.995831 systemd[1]: Finished ensure-sysext.service. Oct 9 01:25:54.001319 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 01:25:54.008288 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 9 01:25:54.009312 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 01:25:54.011182 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 01:25:54.051485 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 9 01:25:54.091306 augenrules[1287]: No rules Oct 9 01:25:54.092232 systemd[1]: audit-rules.service: Deactivated successfully. Oct 9 01:25:54.092491 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 9 01:25:54.160766 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 9 01:25:54.161568 systemd[1]: Reached target time-set.target - System Time Set. Oct 9 01:25:54.168890 systemd-resolved[1246]: Positive Trust Anchors: Oct 9 01:25:54.168914 systemd-resolved[1246]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 01:25:54.168962 systemd-resolved[1246]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 9 01:25:54.176820 systemd-resolved[1246]: Using system hostname 'ci-4116-0-0-4-3f8c4891e2.novalocal'. Oct 9 01:25:54.178672 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 01:25:54.181933 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:25:54.211517 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 9 01:25:54.224340 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:25:54.226489 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 9 01:25:54.229318 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). Oct 9 01:25:54.246719 systemd-udevd[1296]: Using default interface naming scheme 'v255'. Oct 9 01:25:54.274892 ldconfig[1107]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 9 01:25:54.294483 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 9 01:25:54.302485 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 9 01:25:54.308476 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:25:54.318368 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 01:25:54.319874 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 9 01:25:54.371759 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 9 01:25:54.406392 systemd-networkd[1304]: lo: Link UP Oct 9 01:25:54.406402 systemd-networkd[1304]: lo: Gained carrier Oct 9 01:25:54.410210 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1310) Oct 9 01:25:54.410299 systemd-networkd[1304]: Enumeration completed Oct 9 01:25:54.410441 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 01:25:54.411687 systemd[1]: Reached target network.target - Network. Oct 9 01:25:54.420854 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 9 01:25:54.432240 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1305) Oct 9 01:25:54.459157 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1305) Oct 9 01:25:54.478712 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 01:25:54.485320 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 9 01:25:54.488154 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Oct 9 01:25:54.498305 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Oct 9 01:25:54.498606 kernel: ACPI: button: Power Button [PWRF] Oct 9 01:25:54.525227 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 9 01:25:54.531828 systemd-networkd[1304]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:25:54.531839 systemd-networkd[1304]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 9 01:25:54.534667 systemd-networkd[1304]: eth0: Link UP Oct 9 01:25:54.534675 systemd-networkd[1304]: eth0: Gained carrier Oct 9 01:25:54.534696 systemd-networkd[1304]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:25:54.544188 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Oct 9 01:25:54.545288 systemd-networkd[1304]: eth0: DHCPv4 address 172.24.4.107/24, gateway 172.24.4.1 acquired from 172.24.4.1 Oct 9 01:25:54.546695 systemd-timesyncd[1270]: Network configuration changed, trying to establish connection. Oct 9 01:25:54.575496 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:25:54.586152 kernel: mousedev: PS/2 mouse device common for all mice Oct 9 01:25:54.678193 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Oct 9 01:25:54.678358 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Oct 9 01:25:54.686157 kernel: Console: switching to colour dummy device 80x25 Oct 9 01:25:54.687213 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Oct 9 01:25:54.687282 kernel: [drm] features: -context_init Oct 9 01:25:54.691981 kernel: [drm] number of scanouts: 1 Oct 9 01:25:54.692057 kernel: [drm] number of cap sets: 0 Oct 9 01:25:54.695176 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Oct 9 01:25:54.714574 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Oct 9 01:25:54.714715 kernel: Console: switching to colour frame buffer device 128x48 Oct 9 01:25:54.720169 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Oct 9 01:25:54.737859 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:25:54.738356 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:25:54.744410 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:25:54.745688 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 9 01:25:54.763526 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 9 01:25:54.842902 lvm[1339]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 01:25:54.895026 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 9 01:25:54.897934 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:25:54.907511 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 9 01:25:54.928768 lvm[1343]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 01:25:54.982963 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 9 01:25:55.362483 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:25:55.363370 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 01:25:55.363771 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 9 01:25:55.364025 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 9 01:25:55.366302 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 9 01:25:55.366721 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 9 01:25:55.366912 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 9 01:25:55.367072 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 9 01:25:55.367953 systemd[1]: Reached target paths.target - Path Units. Oct 9 01:25:55.369044 systemd[1]: Reached target timers.target - Timer Units. Oct 9 01:25:55.372648 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 9 01:25:55.376550 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 9 01:25:55.384024 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 9 01:25:55.386720 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 9 01:25:55.393964 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 01:25:55.397305 systemd[1]: Reached target basic.target - Basic System. Oct 9 01:25:55.400194 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 9 01:25:55.400286 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 9 01:25:55.409353 systemd[1]: Starting containerd.service - containerd container runtime... Oct 9 01:25:55.417463 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Oct 9 01:25:55.426483 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 9 01:25:55.436409 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 9 01:25:55.451355 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 9 01:25:55.459335 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 9 01:25:55.461417 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). Oct 9 01:25:55.470353 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 9 01:25:55.477896 jq[1356]: false Oct 9 01:25:55.484397 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 9 01:25:55.490345 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 9 01:25:55.504705 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 9 01:25:55.507148 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 9 01:25:55.507749 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 9 01:25:55.511708 systemd[1]: Starting update-engine.service - Update Engine... Oct 9 01:25:55.517357 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 9 01:25:55.530805 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 9 01:25:55.531062 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 9 01:25:55.531394 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 9 01:25:55.532178 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 9 01:25:55.551750 jq[1365]: true Oct 9 01:25:55.564377 update_engine[1364]: I20241009 01:25:55.560405 1364 main.cc:92] Flatcar Update Engine starting Oct 9 01:25:55.565444 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 9 01:25:55.592185 coreos-metadata[1351]: Oct 09 01:25:55.592 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Oct 9 01:25:55.600143 coreos-metadata[1351]: Oct 09 01:25:55.595 INFO Fetch successful Oct 9 01:25:55.600143 coreos-metadata[1351]: Oct 09 01:25:55.595 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Oct 9 01:25:55.595973 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 9 01:25:55.595793 dbus-daemon[1353]: [system] SELinux support is enabled Oct 9 01:25:55.605709 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). Oct 9 01:25:55.613054 extend-filesystems[1357]: Found loop3 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found loop4 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found loop5 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda1 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda2 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda3 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found usr Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda4 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda6 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda7 Oct 9 01:25:55.613054 extend-filesystems[1357]: Found vda9 Oct 9 01:25:55.613054 extend-filesystems[1357]: Checking size of /dev/vda9 Oct 9 01:25:55.852868 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 4635643 blocks Oct 9 01:25:55.852926 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1314) Oct 9 01:25:55.852952 update_engine[1364]: I20241009 01:25:55.603080 1364 update_check_scheduler.cc:74] Next update check in 2m42s Oct 9 01:25:55.605757 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.672 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.692 INFO Fetch successful Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.692 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.708 INFO Fetch successful Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.708 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.726 INFO Fetch successful Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.726 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.741 INFO Fetch successful Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.742 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.760 INFO Fetch successful Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.760 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Oct 9 01:25:55.853069 coreos-metadata[1352]: Oct 09 01:25:55.783 INFO Fetch successful Oct 9 01:25:55.856133 coreos-metadata[1351]: Oct 09 01:25:55.613 INFO Fetch successful Oct 9 01:25:55.856279 jq[1369]: true Oct 9 01:25:55.856368 extend-filesystems[1357]: Resized partition /dev/vda9 Oct 9 01:25:55.607387 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). Oct 9 01:25:55.861465 extend-filesystems[1391]: resize2fs 1.47.1 (20-May-2024) Oct 9 01:25:55.607409 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 9 01:25:55.607970 systemd[1]: Started update-engine.service - Update Engine. Oct 9 01:25:55.613781 (ntainerd)[1375]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 9 01:25:55.630103 unknown[1351]: wrote ssh authorized keys file for user: core Oct 9 01:25:55.635101 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 9 01:25:55.643727 systemd-logind[1361]: New seat seat0. Oct 9 01:25:55.665140 systemd[1]: motdgen.service: Deactivated successfully. Oct 9 01:25:55.665370 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 9 01:25:55.687516 systemd-logind[1361]: Watching system buttons on /dev/input/event1 (Power Button) Oct 9 01:25:55.687536 systemd-logind[1361]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 9 01:25:55.694203 systemd[1]: Started systemd-logind.service - User Login Management. Oct 9 01:25:55.848806 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 9 01:25:55.851552 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 9 01:25:55.870546 kernel: EXT4-fs (vda9): resized filesystem to 4635643 Oct 9 01:25:55.895855 locksmithd[1385]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 9 01:25:56.008145 extend-filesystems[1391]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 9 01:25:56.008145 extend-filesystems[1391]: old_desc_blocks = 1, new_desc_blocks = 3 Oct 9 01:25:56.008145 extend-filesystems[1391]: The filesystem on /dev/vda9 is now 4635643 (4k) blocks long. Oct 9 01:25:56.019905 extend-filesystems[1357]: Resized filesystem in /dev/vda9 Oct 9 01:25:56.014088 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 9 01:25:56.014377 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 9 01:25:56.034251 update-ssh-keys[1390]: Updated "/home/core/.ssh/authorized_keys" Oct 9 01:25:56.031361 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Oct 9 01:25:56.052234 bash[1409]: Updated "/home/core/.ssh/authorized_keys" Oct 9 01:25:56.049189 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 9 01:25:56.064412 systemd[1]: Starting sshkeys.service... Oct 9 01:25:56.103600 systemd[1]: Finished sshkeys.service. Oct 9 01:25:56.171240 containerd[1375]: time="2024-10-09T01:25:56.171092069Z" level=info msg="starting containerd" revision=b2ce781edcbd6cb758f172ecab61c79d607cc41d version=v1.7.22 Oct 9 01:25:56.216873 containerd[1375]: time="2024-10-09T01:25:56.216802609Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:25:56.223788 containerd[1375]: time="2024-10-09T01:25:56.223739008Z" 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.54-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.223884581Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.223910860Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224079727Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224101347Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224190925Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224208709Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224391331Z" 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 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224409816Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224425205Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224437778Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224518911Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225043 containerd[1375]: time="2024-10-09T01:25:56.224726871Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:25:56.225349 containerd[1375]: time="2024-10-09T01:25:56.224841385Z" 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 Oct 9 01:25:56.225349 containerd[1375]: time="2024-10-09T01:25:56.224857596Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 9 01:25:56.225349 containerd[1375]: time="2024-10-09T01:25:56.224941353Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 9 01:25:56.225349 containerd[1375]: time="2024-10-09T01:25:56.225012967Z" level=info msg="metadata content store policy set" policy=shared Oct 9 01:25:56.236746 containerd[1375]: time="2024-10-09T01:25:56.236697221Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 9 01:25:56.236937 containerd[1375]: time="2024-10-09T01:25:56.236921301Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 9 01:25:56.237103 containerd[1375]: time="2024-10-09T01:25:56.237085299Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 9 01:25:56.237208 containerd[1375]: time="2024-10-09T01:25:56.237191779Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 9 01:25:56.237273 containerd[1375]: time="2024-10-09T01:25:56.237260067Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 9 01:25:56.237504 containerd[1375]: time="2024-10-09T01:25:56.237485550Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 9 01:25:56.237913 containerd[1375]: time="2024-10-09T01:25:56.237895158Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 9 01:25:56.238084 containerd[1375]: time="2024-10-09T01:25:56.238065988Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 9 01:25:56.238197 containerd[1375]: time="2024-10-09T01:25:56.238180523Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 9 01:25:56.238271 containerd[1375]: time="2024-10-09T01:25:56.238256315Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 9 01:25:56.238335 containerd[1375]: time="2024-10-09T01:25:56.238321177Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.238397 containerd[1375]: time="2024-10-09T01:25:56.238383634Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.238457 containerd[1375]: time="2024-10-09T01:25:56.238444017Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.238518 containerd[1375]: time="2024-10-09T01:25:56.238504420Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.238590 containerd[1375]: time="2024-10-09T01:25:56.238574662Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.238670 containerd[1375]: time="2024-10-09T01:25:56.238655364Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.238736 containerd[1375]: time="2024-10-09T01:25:56.238722469Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.238794 containerd[1375]: time="2024-10-09T01:25:56.238781069Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238852143Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238874304Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238890414Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238905553Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238919349Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238934497Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238947672Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238969012Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238983709Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.238999108Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.239011972Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.239025528Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.239039825Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.239057327Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 9 01:25:56.239127 containerd[1375]: time="2024-10-09T01:25:56.239080982Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239644 containerd[1375]: time="2024-10-09T01:25:56.239523792Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.239644 containerd[1375]: time="2024-10-09T01:25:56.239563968Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239799349Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239837130Z" 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 Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239854212Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239868359Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239879680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239903645Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239915838Z" level=info msg="NRI interface is disabled by configuration." Oct 9 01:25:56.240149 containerd[1375]: time="2024-10-09T01:25:56.239927049Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 9 01:25:56.240605 containerd[1375]: time="2024-10-09T01:25:56.240518989Z" 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}" Oct 9 01:25:56.240799 containerd[1375]: time="2024-10-09T01:25:56.240775730Z" level=info msg="Connect containerd service" Oct 9 01:25:56.241147 containerd[1375]: time="2024-10-09T01:25:56.240870568Z" level=info msg="using legacy CRI server" Oct 9 01:25:56.241147 containerd[1375]: time="2024-10-09T01:25:56.240884775Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 9 01:25:56.241147 containerd[1375]: time="2024-10-09T01:25:56.240985303Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 9 01:25:56.241956 containerd[1375]: time="2024-10-09T01:25:56.241930847Z" 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" Oct 9 01:25:56.242182 containerd[1375]: time="2024-10-09T01:25:56.242142965Z" level=info msg="Start subscribing containerd event" Oct 9 01:25:56.242264 containerd[1375]: time="2024-10-09T01:25:56.242250586Z" level=info msg="Start recovering state" Oct 9 01:25:56.242380 containerd[1375]: time="2024-10-09T01:25:56.242364851Z" level=info msg="Start event monitor" Oct 9 01:25:56.242605 containerd[1375]: time="2024-10-09T01:25:56.242440432Z" level=info msg="Start snapshots syncer" Oct 9 01:25:56.242605 containerd[1375]: time="2024-10-09T01:25:56.242456212Z" level=info msg="Start cni network conf syncer for default" Oct 9 01:25:56.242605 containerd[1375]: time="2024-10-09T01:25:56.242466471Z" level=info msg="Start streaming server" Oct 9 01:25:56.243098 containerd[1375]: time="2024-10-09T01:25:56.243072698Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 9 01:25:56.243416 containerd[1375]: time="2024-10-09T01:25:56.243349788Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 9 01:25:56.247385 systemd[1]: Started containerd.service - containerd container runtime. Oct 9 01:25:56.249833 containerd[1375]: time="2024-10-09T01:25:56.249803151Z" level=info msg="containerd successfully booted in 0.080742s" Oct 9 01:25:56.254361 systemd-networkd[1304]: eth0: Gained IPv6LL Oct 9 01:25:56.255188 systemd-timesyncd[1270]: Network configuration changed, trying to establish connection. Oct 9 01:25:56.260019 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 9 01:25:56.265148 sshd_keygen[1387]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 9 01:25:56.261987 systemd[1]: Reached target network-online.target - Network is Online. Oct 9 01:25:56.273615 (-wrapper)[1439]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Oct 9 01:25:56.273918 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Oct 9 01:25:56.279488 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 9 01:25:56.299721 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 9 01:25:56.310535 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 9 01:25:56.317458 systemd[1]: Started sshd@0-172.24.4.107:22-172.24.4.1:47358.service - OpenSSH per-connection server daemon (172.24.4.1:47358). Oct 9 01:25:56.324738 systemd[1]: issuegen.service: Deactivated successfully. Oct 9 01:25:56.325439 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 9 01:25:56.345947 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 9 01:25:56.370645 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 9 01:25:56.392467 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 9 01:25:56.401543 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 9 01:25:56.413589 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 9 01:25:56.414526 systemd[1]: Reached target getty.target - Login Prompts. Oct 9 01:25:56.566563 (dockerd)[1472]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Oct 9 01:25:56.567371 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 9 01:25:57.175724 dockerd[1472]: time="2024-10-09T01:25:57.175585676Z" level=info msg="Starting up" Oct 9 01:25:57.371856 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport3898786532-merged.mount: Deactivated successfully. Oct 9 01:25:57.474897 dockerd[1472]: time="2024-10-09T01:25:57.474646344Z" level=info msg="Loading containers: start." Oct 9 01:25:57.692209 sshd[1451]: Accepted publickey for core from 172.24.4.1 port 47358 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:25:57.694433 sshd[1451]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:25:57.708506 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 9 01:25:57.718481 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 9 01:25:57.723972 systemd-logind[1361]: New session 1 of user core. Oct 9 01:25:57.745578 kernel: Initializing XFRM netlink socket Oct 9 01:25:57.749696 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 9 01:25:57.759452 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 9 01:25:57.772091 (systemd)[1580]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 9 01:25:57.795702 systemd-timesyncd[1270]: Network configuration changed, trying to establish connection. Oct 9 01:25:57.797439 systemd-timesyncd[1270]: Network configuration changed, trying to establish connection. Oct 9 01:25:57.806312 systemd-timesyncd[1270]: Network configuration changed, trying to establish connection. Oct 9 01:25:57.874857 systemd-networkd[1304]: docker0: Link UP Oct 9 01:25:57.875245 systemd-timesyncd[1270]: Network configuration changed, trying to establish connection. Oct 9 01:25:57.907602 systemd[1580]: Queued start job for default target default.target. Oct 9 01:25:57.911473 dockerd[1472]: time="2024-10-09T01:25:57.911431835Z" level=info msg="Loading containers: done." Oct 9 01:25:57.913256 systemd[1580]: Created slice app.slice - User Application Slice. Oct 9 01:25:57.913400 systemd[1580]: Reached target paths.target - Paths. Oct 9 01:25:57.913493 systemd[1580]: Reached target timers.target - Timers. Oct 9 01:25:57.917383 systemd[1580]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 9 01:25:57.928065 systemd[1580]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 9 01:25:57.928969 systemd[1580]: Reached target sockets.target - Sockets. Oct 9 01:25:57.929016 systemd[1580]: Reached target basic.target - Basic System. Oct 9 01:25:57.929066 systemd[1580]: Reached target default.target - Main User Target. Oct 9 01:25:57.929096 systemd[1580]: Startup finished in 148ms. Oct 9 01:25:57.929303 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 9 01:25:57.935358 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 9 01:25:57.967039 dockerd[1472]: time="2024-10-09T01:25:57.966953486Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Oct 9 01:25:57.967298 dockerd[1472]: time="2024-10-09T01:25:57.967179410Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Oct 9 01:25:57.967509 dockerd[1472]: time="2024-10-09T01:25:57.967456660Z" level=info msg="Daemon has completed initialization" Oct 9 01:25:58.045308 dockerd[1472]: time="2024-10-09T01:25:58.044404576Z" level=info msg="API listen on /run/docker.sock" Oct 9 01:25:58.045617 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 9 01:25:58.050573 etcd-wrapper[1447]: Error response from daemon: No such container: etcd-member Oct 9 01:25:58.084516 etcd-wrapper[1657]: Error response from daemon: No such container: etcd-member Oct 9 01:25:58.149294 etcd-wrapper[1675]: Unable to find image 'quay.io/coreos/etcd:v3.5.0' locally Oct 9 01:25:58.329631 systemd[1]: Started sshd@1-172.24.4.107:22-172.24.4.1:47372.service - OpenSSH per-connection server daemon (172.24.4.1:47372). Oct 9 01:25:59.452423 etcd-wrapper[1675]: v3.5.0: Pulling from coreos/etcd Oct 9 01:25:59.749165 etcd-wrapper[1675]: 1813d21adc01: Pulling fs layer Oct 9 01:25:59.749165 etcd-wrapper[1675]: 6e96907ab677: Pulling fs layer Oct 9 01:25:59.749165 etcd-wrapper[1675]: 444ed0ea8673: Pulling fs layer Oct 9 01:25:59.749165 etcd-wrapper[1675]: 0fd2df5633f0: Pulling fs layer Oct 9 01:25:59.749165 etcd-wrapper[1675]: 8cc22b9456bb: Pulling fs layer Oct 9 01:25:59.749165 etcd-wrapper[1675]: 7ac70aecd290: Pulling fs layer Oct 9 01:25:59.749165 etcd-wrapper[1675]: 4b376c64dfe4: Pulling fs layer Oct 9 01:25:59.749165 etcd-wrapper[1675]: 0fd2df5633f0: Waiting Oct 9 01:25:59.749165 etcd-wrapper[1675]: 4b376c64dfe4: Waiting Oct 9 01:25:59.749165 etcd-wrapper[1675]: 7ac70aecd290: Waiting Oct 9 01:26:00.197528 sshd[1685]: Accepted publickey for core from 172.24.4.1 port 47372 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:00.200679 sshd[1685]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:00.207923 etcd-wrapper[1675]: 444ed0ea8673: Verifying Checksum Oct 9 01:26:00.207923 etcd-wrapper[1675]: 444ed0ea8673: Download complete Oct 9 01:26:00.210186 systemd-logind[1361]: New session 2 of user core. Oct 9 01:26:00.216309 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 9 01:26:00.223881 etcd-wrapper[1675]: 6e96907ab677: Verifying Checksum Oct 9 01:26:00.224069 etcd-wrapper[1675]: 6e96907ab677: Download complete Oct 9 01:26:00.353644 etcd-wrapper[1675]: 1813d21adc01: Verifying Checksum Oct 9 01:26:00.353644 etcd-wrapper[1675]: 1813d21adc01: Download complete Oct 9 01:26:00.546263 etcd-wrapper[1675]: 8cc22b9456bb: Verifying Checksum Oct 9 01:26:00.547050 etcd-wrapper[1675]: 8cc22b9456bb: Download complete Oct 9 01:26:00.628698 etcd-wrapper[1675]: 0fd2df5633f0: Verifying Checksum Oct 9 01:26:00.628698 etcd-wrapper[1675]: 0fd2df5633f0: Download complete Oct 9 01:26:00.647213 etcd-wrapper[1675]: 7ac70aecd290: Verifying Checksum Oct 9 01:26:00.647213 etcd-wrapper[1675]: 7ac70aecd290: Download complete Oct 9 01:26:00.772744 sshd[1685]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:00.780648 systemd[1]: sshd@1-172.24.4.107:22-172.24.4.1:47372.service: Deactivated successfully. Oct 9 01:26:00.784571 systemd[1]: session-2.scope: Deactivated successfully. Oct 9 01:26:00.785796 systemd-logind[1361]: Session 2 logged out. Waiting for processes to exit. Oct 9 01:26:00.793464 systemd[1]: Started sshd@2-172.24.4.107:22-172.24.4.1:47380.service - OpenSSH per-connection server daemon (172.24.4.1:47380). Oct 9 01:26:00.797791 systemd-logind[1361]: Removed session 2. Oct 9 01:26:00.853066 etcd-wrapper[1675]: 4b376c64dfe4: Verifying Checksum Oct 9 01:26:00.853942 etcd-wrapper[1675]: 4b376c64dfe4: Download complete Oct 9 01:26:01.444369 login[1463]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 9 01:26:01.450567 login[1464]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 9 01:26:01.454472 systemd-logind[1361]: New session 3 of user core. Oct 9 01:26:01.459254 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 9 01:26:01.466182 systemd-logind[1361]: New session 4 of user core. Oct 9 01:26:01.469976 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 9 01:26:01.501247 etcd-wrapper[1675]: 1813d21adc01: Pull complete Oct 9 01:26:01.769927 systemd[1]: var-lib-docker-overlay2-8b8c4993bc8d71bc4e8075acec752212f8a24c0f1485b08c60854ff75cc81427-merged.mount: Deactivated successfully. Oct 9 01:26:01.984993 sshd[1699]: Accepted publickey for core from 172.24.4.1 port 47380 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:01.988204 sshd[1699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:01.997319 systemd-logind[1361]: New session 5 of user core. Oct 9 01:26:02.013469 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 9 01:26:02.588199 etcd-wrapper[1675]: 6e96907ab677: Pull complete Oct 9 01:26:02.600564 sshd[1699]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:02.609451 systemd[1]: sshd@2-172.24.4.107:22-172.24.4.1:47380.service: Deactivated successfully. Oct 9 01:26:02.613067 systemd[1]: session-5.scope: Deactivated successfully. Oct 9 01:26:02.615522 systemd-logind[1361]: Session 5 logged out. Waiting for processes to exit. Oct 9 01:26:02.618276 systemd-logind[1361]: Removed session 5. Oct 9 01:26:03.125589 systemd[1]: var-lib-docker-overlay2-7d393c4beaa5e757130df8bfc4c1add47203aef900776d9cd183c916ff553058-merged.mount: Deactivated successfully. Oct 9 01:26:03.581708 etcd-wrapper[1675]: 444ed0ea8673: Pull complete Oct 9 01:26:03.987981 systemd[1]: var-lib-docker-overlay2-0ca540e7d58bb6cfd3c2029035c1ebee5b2f4b702ffb82191c0a25dae3df9c9b-merged.mount: Deactivated successfully. Oct 9 01:26:04.509791 etcd-wrapper[1675]: 0fd2df5633f0: Pull complete Oct 9 01:26:04.566644 systemd[1]: var-lib-docker-overlay2-2c4f8bfabce04262a76fcd25bad2d221ac5e25c719fb93df06985c3bcb9697cb-merged.mount: Deactivated successfully. Oct 9 01:26:04.583838 etcd-wrapper[1675]: 8cc22b9456bb: Pull complete Oct 9 01:26:04.618992 systemd[1]: var-lib-docker-overlay2-b291c821d469c5e98f752038be924f6ab7bec60ff5c8d8749b9c5ac4471e042d-merged.mount: Deactivated successfully. Oct 9 01:26:04.643244 etcd-wrapper[1675]: 7ac70aecd290: Pull complete Oct 9 01:26:04.701192 etcd-wrapper[1675]: 4b376c64dfe4: Pull complete Oct 9 01:26:04.733147 etcd-wrapper[1675]: Digest: sha256:28759af54acd6924b2191dc1a1d096e2fa2e219717a21b9d8edf89717db3631b Oct 9 01:26:04.751602 etcd-wrapper[1675]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0 Oct 9 01:26:05.040351 systemd[1]: var-lib-docker-overlay2-d860f8ed4093ab9a3616becf23dfa7b507986feaee80d0bb0b8482239a00b71c-merged.mount: Deactivated successfully. Oct 9 01:26:05.347052 containerd[1375]: time="2024-10-09T01:26:05.346586917Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:26:05.347052 containerd[1375]: time="2024-10-09T01:26:05.346682707Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:26:05.347052 containerd[1375]: time="2024-10-09T01:26:05.346722982Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:05.347939 containerd[1375]: time="2024-10-09T01:26:05.346947213Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:05.430433 systemd[1]: Started docker-610ce23d2786b85b5aa95b5073249efb0182f104ba3008e1d25fef0a24b0e025.scope - libcontainer container 610ce23d2786b85b5aa95b5073249efb0182f104ba3008e1d25fef0a24b0e025. Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"info","ts":1728437165.5268042,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"info","ts":1728437165.5268867,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"74d701e3111143cfb89affb596787429"} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"warn","ts":1728437165.5269177,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"warn","ts":1728437165.5269253,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"warn","ts":1728437165.5269322,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"warn","ts":1728437165.5269375,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:05.526Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.24.4.107:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.24.4.107:2380","--advertise-client-urls=http://172.24.4.107:2379","--discovery=https://discovery.etcd.io/9439d70a93aa7988c83f567df2aa5ea4","--enable-v2=true"]} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:05.527Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.24.4.107:2380"]} Oct 9 01:26:05.527719 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:05.527Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Oct 9 01:26:05.529271 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:05.527Z","caller":"embed/etcd.go:307","msg":"starting an etcd server","etcd-version":"3.5.0","git-sha":"946a5a6f2","go-version":"go1.16.3","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"74d701e3111143cfb89affb596787429","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://172.24.4.107:2380"],"listen-peer-urls":["http://172.24.4.107:2380"],"advertise-client-urls":["http://172.24.4.107:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"74d701e3111143cfb89affb596787429=http://172.24.4.107:2380","initial-cluster-state":"new","initial-cluster-token":"https://discovery.etcd.io/9439d70a93aa7988c83f567df2aa5ea4","quota-size-bytes":2147483648,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"https://discovery.etcd.io/9439d70a93aa7988c83f567df2aa5ea4","discovery-proxy":"","downgrade-check-interval":"5s"} Oct 9 01:26:05.736704 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:05.735Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"207.748402ms"} Oct 9 01:26:06.874258 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:06.873Z","caller":"v2discovery/discovery.go:336","msg":"found self from discovery server","discovery-url":"https://discovery.etcd.io","self":"762ef2008432947e"} Oct 9 01:26:06.874258 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:06.873Z","caller":"v2discovery/discovery.go:352","msg":"found peers from discovery server; waiting for more","discovery-url":"https://discovery.etcd.io","found-peers":1,"needed-peers":2} Oct 9 01:26:07.569974 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.569Z","caller":"v2discovery/discovery.go:371","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"7f1b43484c2b49f"} Oct 9 01:26:07.569974 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.569Z","caller":"v2discovery/discovery.go:352","msg":"found peers from discovery server; waiting for more","discovery-url":"https://discovery.etcd.io","found-peers":2,"needed-peers":1} Oct 9 01:26:07.698442 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.697Z","caller":"v2discovery/discovery.go:371","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"5e1f14aae1bebcd0"} Oct 9 01:26:07.699454 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.698Z","caller":"v2discovery/discovery.go:378","msg":"found all needed peers from discovery server","discovery-url":"https://discovery.etcd.io","found-peers":3} Oct 9 01:26:07.708402 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.707Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"762ef2008432947e","cluster-id":"d11d67a465f255f4"} Oct 9 01:26:07.708402 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=()"} Oct 9 01:26:07.709338 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e became follower at term 0"} Oct 9 01:26:07.709338 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 762ef2008432947e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Oct 9 01:26:07.709338 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e became follower at term 1"} Oct 9 01:26:07.709338 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455)"} Oct 9 01:26:07.709338 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455 6782162288007625936)"} Oct 9 01:26:07.709891 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455 6782162288007625936 8516010029436015742)"} Oct 9 01:26:07.711409 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:07.711Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Oct 9 01:26:07.713714 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.713Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Oct 9 01:26:07.715328 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.715Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Oct 9 01:26:07.716608 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.716Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.716820 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.716Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.721421 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.719Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.721421 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.719Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.723046 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.721Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.723046 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.721Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f","remote-peer-urls":["http://172.24.4.97:2380"]} Oct 9 01:26:07.723046 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.721Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:07.723046 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.722Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.723046 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.722Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:07.723046 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.721Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.724489 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.723Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:07.725259 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.724Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:07.726857 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.726Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:07.726857 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.726Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0","remote-peer-urls":["http://172.24.4.65:2380"]} Oct 9 01:26:07.728216 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.726Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"762ef2008432947e","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Oct 9 01:26:07.729174 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.728Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} Oct 9 01:26:07.731582 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.730Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:07.732032 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.731Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:07.732032 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:07.731Z","caller":"embed/etcd.go:700","msg":"Flag `enable-v2` is deprecated and will get removed in etcd 3.6."} Oct 9 01:26:07.733025 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.731Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"762ef2008432947e","initial-advertise-peer-urls":["http://172.24.4.107:2380"],"listen-peer-urls":["http://172.24.4.107:2380"],"advertise-client-urls":["http://172.24.4.107:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Oct 9 01:26:07.733025 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.24.4.107:2380"} Oct 9 01:26:07.733025 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.24.4.107:2380"} Oct 9 01:26:07.733025 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455 6782162288007625936 8516010029436015742)"} Oct 9 01:26:07.733025 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","added-peer-id":"7f1b43484c2b49f","added-peer-peer-urls":["http://172.24.4.97:2380"]} Oct 9 01:26:07.733025 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455 6782162288007625936 8516010029436015742)"} Oct 9 01:26:07.733025 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","added-peer-id":"5e1f14aae1bebcd0","added-peer-peer-urls":["http://172.24.4.65:2380"]} Oct 9 01:26:07.733555 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455 6782162288007625936 8516010029436015742)"} Oct 9 01:26:07.733555 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.732Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","added-peer-id":"762ef2008432947e","added-peer-peer-urls":["http://172.24.4.107:2380"]} Oct 9 01:26:07.959410 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.958Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"7f1b43484c2b49f","stream-type":"stream Message"} Oct 9 01:26:07.959410 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.958Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.961194 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.958Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.961194 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.959Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"7f1b43484c2b49f","stream-type":"stream MsgApp v2"} Oct 9 01:26:07.961194 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.959Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.963190 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.962Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:07.964742 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:07.963Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:08.054883 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.054Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"5e1f14aae1bebcd0","stream-type":"stream MsgApp v2"} Oct 9 01:26:08.054883 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.054Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:08.054883 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.054Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:08.055928 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.055Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"5e1f14aae1bebcd0","stream-type":"stream Message"} Oct 9 01:26:08.055928 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.055Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:08.064243 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.063Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:08.065843 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.065Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:08.082521 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.081Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"762ef2008432947e","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} Oct 9 01:26:08.528800 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [logterm: 1, index: 3, vote: 0] cast MsgPreVote for 7f1b43484c2b49f [logterm: 1, index: 3] at term 1"} Oct 9 01:26:08.530751 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.530Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [term: 1] received a MsgVote message with higher term from 7f1b43484c2b49f [term: 2]"} Oct 9 01:26:08.530751 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.530Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e became follower at term 2"} Oct 9 01:26:08.530751 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.530Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [logterm: 1, index: 3, vote: 0] cast MsgVote for 7f1b43484c2b49f [logterm: 1, index: 3] at term 2"} Oct 9 01:26:08.533280 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 762ef2008432947e elected leader 7f1b43484c2b49f at term 2"} Oct 9 01:26:08.538437 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.537Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"762ef2008432947e","local-member-attributes":"{Name:74d701e3111143cfb89affb596787429 ClientURLs:[http://172.24.4.107:2379]}","request-path":"/0/members/762ef2008432947e/attributes","cluster-id":"d11d67a465f255f4","publish-timeout":"7s"} Oct 9 01:26:08.539226 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.538Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Oct 9 01:26:08.539797 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.539Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Oct 9 01:26:08.540900 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.540Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Oct 9 01:26:08.540900 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.539Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Oct 9 01:26:08.541351 systemd[1]: Started etcd-member.service - etcd (System Application Container). Oct 9 01:26:08.544514 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.544Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","cluster-version":"3.5"} Oct 9 01:26:08.544782 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:08.544Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Oct 9 01:26:08.550413 systemd[1]: Starting flanneld.service - flannel - Network fabric for containers (System Application Container)... Oct 9 01:26:08.770499 etcdctl[1808]: { "Network": "10.254.0.0/16", "Backend": {"Type": "vxlan"} } Oct 9 01:26:08.807806 flannel-wrapper[1816]: Error response from daemon: No such container: flannel Oct 9 01:26:08.831652 flannel-wrapper[1824]: Error response from daemon: No such container: flannel Oct 9 01:26:08.860903 flannel-wrapper[1840]: Unable to find image 'quay.io/coreos/flannel:v0.14.0' locally Oct 9 01:26:10.147702 flannel-wrapper[1840]: v0.14.0: Pulling from coreos/flannel Oct 9 01:26:10.450393 flannel-wrapper[1840]: 801bfaa63ef2: Pulling fs layer Oct 9 01:26:10.450393 flannel-wrapper[1840]: e4264a7179f6: Pulling fs layer Oct 9 01:26:10.451621 flannel-wrapper[1840]: bc75ea45ad2e: Pulling fs layer Oct 9 01:26:10.451621 flannel-wrapper[1840]: 78648579d12a: Pulling fs layer Oct 9 01:26:10.451621 flannel-wrapper[1840]: 3393447261e4: Pulling fs layer Oct 9 01:26:10.451621 flannel-wrapper[1840]: 071b96dd834b: Pulling fs layer Oct 9 01:26:10.451621 flannel-wrapper[1840]: 4de2f0468a91: Pulling fs layer Oct 9 01:26:10.451621 flannel-wrapper[1840]: 78648579d12a: Waiting Oct 9 01:26:10.451621 flannel-wrapper[1840]: 3393447261e4: Waiting Oct 9 01:26:10.451621 flannel-wrapper[1840]: 071b96dd834b: Waiting Oct 9 01:26:10.451621 flannel-wrapper[1840]: 4de2f0468a91: Waiting Oct 9 01:26:10.770268 flannel-wrapper[1840]: bc75ea45ad2e: Verifying Checksum Oct 9 01:26:10.770268 flannel-wrapper[1840]: bc75ea45ad2e: Download complete Oct 9 01:26:10.847948 flannel-wrapper[1840]: e4264a7179f6: Verifying Checksum Oct 9 01:26:10.847948 flannel-wrapper[1840]: e4264a7179f6: Download complete Oct 9 01:26:10.857155 flannel-wrapper[1840]: 801bfaa63ef2: Verifying Checksum Oct 9 01:26:10.857155 flannel-wrapper[1840]: 801bfaa63ef2: Download complete Oct 9 01:26:11.009987 flannel-wrapper[1840]: 801bfaa63ef2: Pull complete Oct 9 01:26:11.177511 flannel-wrapper[1840]: 071b96dd834b: Verifying Checksum Oct 9 01:26:11.177511 flannel-wrapper[1840]: 071b96dd834b: Download complete Oct 9 01:26:11.188572 flannel-wrapper[1840]: 3393447261e4: Verifying Checksum Oct 9 01:26:11.188572 flannel-wrapper[1840]: 3393447261e4: Download complete Oct 9 01:26:11.289065 flannel-wrapper[1840]: 78648579d12a: Verifying Checksum Oct 9 01:26:11.289266 flannel-wrapper[1840]: 78648579d12a: Download complete Oct 9 01:26:11.465370 systemd[1]: var-lib-docker-overlay2-8899a36258be1bb64f689f61fee0b17b4008a461a965f6b9a1189011a9ed943e-merged.mount: Deactivated successfully. Oct 9 01:26:11.475989 flannel-wrapper[1840]: 4de2f0468a91: Verifying Checksum Oct 9 01:26:11.475989 flannel-wrapper[1840]: 4de2f0468a91: Download complete Oct 9 01:26:12.623082 systemd[1]: Started sshd@3-172.24.4.107:22-172.24.4.1:33758.service - OpenSSH per-connection server daemon (172.24.4.1:33758). Oct 9 01:26:13.103520 flannel-wrapper[1840]: e4264a7179f6: Pull complete Oct 9 01:26:13.997738 sshd[1861]: Accepted publickey for core from 172.24.4.1 port 33758 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:13.999650 sshd[1861]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:14.010462 systemd-logind[1361]: New session 6 of user core. Oct 9 01:26:14.017713 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 9 01:26:14.075735 systemd[1]: var-lib-docker-overlay2-ffaf9e1834a0c0e01ec03cd8da3aad0af1a5267ef97d872ba804af744599e2ab-merged.mount: Deactivated successfully. Oct 9 01:26:14.401001 flannel-wrapper[1840]: bc75ea45ad2e: Pull complete Oct 9 01:26:14.551258 sshd[1861]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:14.559382 systemd[1]: sshd@3-172.24.4.107:22-172.24.4.1:33758.service: Deactivated successfully. Oct 9 01:26:14.561778 systemd[1]: session-6.scope: Deactivated successfully. Oct 9 01:26:14.563265 systemd-logind[1361]: Session 6 logged out. Waiting for processes to exit. Oct 9 01:26:14.567715 systemd[1]: Started sshd@4-172.24.4.107:22-172.24.4.1:53370.service - OpenSSH per-connection server daemon (172.24.4.1:53370). Oct 9 01:26:14.569415 systemd-logind[1361]: Removed session 6. Oct 9 01:26:14.944543 systemd[1]: var-lib-docker-overlay2-ad6d85a835d9f17c9eeab2254d87880458e846a7e859ee5b4d16a63e9e00f1db-merged.mount: Deactivated successfully. Oct 9 01:26:15.785041 sshd[1878]: Accepted publickey for core from 172.24.4.1 port 53370 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:15.787718 sshd[1878]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:15.798553 systemd-logind[1361]: New session 7 of user core. Oct 9 01:26:15.811497 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 9 01:26:16.329992 flannel-wrapper[1840]: 78648579d12a: Pull complete Oct 9 01:26:16.375056 systemd[1]: var-lib-docker-overlay2-4e8360da4dfb2ccf7a234bcf39f499ac220e86e9f964d4c0b6334b3d682b7c0b-merged.mount: Deactivated successfully. Oct 9 01:26:16.389376 flannel-wrapper[1840]: 3393447261e4: Pull complete Oct 9 01:26:16.418699 systemd[1]: var-lib-docker-overlay2-e97a55a22bc052bfa7329e2b1e4056c6a82d536e6d11a0bd6ee4bb16978a7d29-merged.mount: Deactivated successfully. Oct 9 01:26:16.650607 sshd[1878]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:16.657165 systemd-logind[1361]: Session 7 logged out. Waiting for processes to exit. Oct 9 01:26:16.657604 systemd[1]: sshd@4-172.24.4.107:22-172.24.4.1:53370.service: Deactivated successfully. Oct 9 01:26:16.660833 systemd[1]: session-7.scope: Deactivated successfully. Oct 9 01:26:16.664688 systemd-logind[1361]: Removed session 7. Oct 9 01:26:16.788374 flannel-wrapper[1840]: 071b96dd834b: Pull complete Oct 9 01:26:17.356227 systemd[1]: var-lib-docker-overlay2-d30a81d08b77a990ad717ef08cb6a203f9d6a10ed6e2de45213f5a912d3ef58b-merged.mount: Deactivated successfully. Oct 9 01:26:17.917789 flannel-wrapper[1840]: 4de2f0468a91: Pull complete Oct 9 01:26:17.939425 flannel-wrapper[1840]: Digest: sha256:4a330b2f2e74046e493b2edc30d61fdebbdddaaedcb32d62736f25be8d3c64d5 Oct 9 01:26:17.945881 flannel-wrapper[1840]: Status: Downloaded newer image for quay.io/coreos/flannel:v0.14.0 Oct 9 01:26:17.987609 systemd[1]: var-lib-docker-overlay2-e6c2b01805194ecb36fdf8c8f8a4784c84bf411d7db525f2045eca6f7c0c7706\x2dinit-merged.mount: Deactivated successfully. Oct 9 01:26:18.134580 containerd[1375]: time="2024-10-09T01:26:18.131644960Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:26:18.134580 containerd[1375]: time="2024-10-09T01:26:18.131931778Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:26:18.134580 containerd[1375]: time="2024-10-09T01:26:18.132008933Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:18.134580 containerd[1375]: time="2024-10-09T01:26:18.132349942Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:18.161289 systemd[1]: Started docker-cf780805f7714e6cad26a75d6d41593a2308bea9eaf2cd2810306602f938074b.scope - libcontainer container cf780805f7714e6cad26a75d6d41593a2308bea9eaf2cd2810306602f938074b. Oct 9 01:26:18.206915 flannel-wrapper[1840]: I1009 01:26:18.205895 1 main.go:520] Determining IP address of default interface Oct 9 01:26:18.206915 flannel-wrapper[1840]: I1009 01:26:18.206608 1 main.go:533] Using interface with name eth0 and address 172.24.4.107 Oct 9 01:26:18.206915 flannel-wrapper[1840]: I1009 01:26:18.206628 1 main.go:550] Defaulting external address to interface address (172.24.4.107) Oct 9 01:26:18.206915 flannel-wrapper[1840]: I1009 01:26:18.206706 1 main.go:254] Created subnet manager: Etcd Local Manager with Previous Subnet: None Oct 9 01:26:18.206915 flannel-wrapper[1840]: I1009 01:26:18.206715 1 main.go:257] Installing signal handlers Oct 9 01:26:18.210999 flannel-wrapper[1840]: I1009 01:26:18.210778 1 main.go:392] Found network config - Backend type: vxlan Oct 9 01:26:18.210999 flannel-wrapper[1840]: I1009 01:26:18.210819 1 vxlan.go:123] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false Oct 9 01:26:18.241722 systemd-timesyncd[1270]: Network configuration changed, trying to establish connection. Oct 9 01:26:18.249578 flannel-wrapper[1840]: I1009 01:26:18.248879 1 local_manager.go:234] Picking subnet in range 10.254.1.0 ... 10.254.255.0 Oct 9 01:26:18.251538 flannel-wrapper[1840]: I1009 01:26:18.251287 1 local_manager.go:220] Allocated lease (10.254.95.0/24) to current node (172.24.4.107) Oct 9 01:26:18.253903 systemd-networkd[1304]: flannel.1: Link UP Oct 9 01:26:18.253913 systemd-networkd[1304]: flannel.1: Gained carrier Oct 9 01:26:18.255399 flannel-wrapper[1840]: I1009 01:26:18.253539 1 main.go:357] Current network or subnet (10.254.0.0/16, 10.254.95.0/24) is not equal to previous one (0.0.0.0/0, 0.0.0.0/0), trying to recycle old iptables rules Oct 9 01:26:18.270447 flannel-wrapper[1840]: I1009 01:26:18.270160 1 iptables.go:172] Deleting iptables rule: -s 0.0.0.0/0 -d 0.0.0.0/0 -j RETURN Oct 9 01:26:18.271735 flannel-wrapper[1840]: I1009 01:26:18.271543 1 iptables.go:172] Deleting iptables rule: -s 0.0.0.0/0 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully Oct 9 01:26:18.272864 flannel-wrapper[1840]: I1009 01:26:18.272751 1 iptables.go:172] Deleting iptables rule: ! -s 0.0.0.0/0 -d 0.0.0.0/0 -j RETURN Oct 9 01:26:18.274049 flannel-wrapper[1840]: I1009 01:26:18.273775 1 iptables.go:172] Deleting iptables rule: ! -s 0.0.0.0/0 -d 0.0.0.0/0 -j MASQUERADE --random-fully Oct 9 01:26:18.274881 flannel-wrapper[1840]: I1009 01:26:18.274603 1 main.go:307] Setting up masking rules Oct 9 01:26:18.275756 flannel-wrapper[1840]: I1009 01:26:18.275400 1 main.go:315] Changing default FORWARD chain policy to ACCEPT Oct 9 01:26:18.276028 flannel-wrapper[1840]: I1009 01:26:18.275873 1 main.go:323] Wrote subnet file to /run/flannel/subnet.env Oct 9 01:26:18.276028 flannel-wrapper[1840]: I1009 01:26:18.275885 1 main.go:327] Running backend. Oct 9 01:26:18.276772 systemd[1]: Started flanneld.service - flannel - Network fabric for containers (System Application Container). Oct 9 01:26:18.277910 flannel-wrapper[1840]: I1009 01:26:18.276586 1 vxlan_network.go:59] watching for new subnet leases Oct 9 01:26:18.277315 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 9 01:26:18.282148 flannel-wrapper[1840]: I1009 01:26:18.281624 1 iptables.go:148] Some iptables rules are missing; deleting and recreating rules Oct 9 01:26:18.282148 flannel-wrapper[1840]: I1009 01:26:18.281640 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 -j ACCEPT Oct 9 01:26:18.282148 flannel-wrapper[1840]: I1009 01:26:18.281708 1 iptables.go:148] Some iptables rules are missing; deleting and recreating rules Oct 9 01:26:18.282148 flannel-wrapper[1840]: I1009 01:26:18.281714 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 -d 10.254.0.0/16 -j RETURN Oct 9 01:26:18.283989 flannel-wrapper[1840]: I1009 01:26:18.283777 1 iptables.go:172] Deleting iptables rule: -d 10.254.0.0/16 -j ACCEPT Oct 9 01:26:18.284506 systemd[1]: Starting flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... Oct 9 01:26:18.287972 flannel-wrapper[1840]: I1009 01:26:18.286902 1 main.go:439] Waiting for 22h59m59.962306505s to renew lease Oct 9 01:26:18.289712 flannel-wrapper[1840]: I1009 01:26:18.289529 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully Oct 9 01:26:18.290734 flannel-wrapper[1840]: I1009 01:26:18.290625 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 -j ACCEPT Oct 9 01:26:18.291974 flannel-wrapper[1840]: I1009 01:26:18.291782 1 iptables.go:172] Deleting iptables rule: ! -s 10.254.0.0/16 -d 10.254.95.0/24 -j RETURN Oct 9 01:26:18.293547 flannel-wrapper[1840]: I1009 01:26:18.293437 1 iptables.go:172] Deleting iptables rule: ! -s 10.254.0.0/16 -d 10.254.0.0/16 -j MASQUERADE --random-fully Oct 9 01:26:18.294518 sh[1984]: + for try in 1 2 3 4 5 6 Oct 9 01:26:18.294832 sh[1984]: + /usr/lib/coreos/flannel-wrapper -d /run/flannel/flannel_docker_opts.env -i Oct 9 01:26:18.295966 flannel-wrapper[1840]: I1009 01:26:18.295714 1 iptables.go:160] Adding iptables rule: -d 10.254.0.0/16 -j ACCEPT Oct 9 01:26:18.298797 flannel-wrapper[1840]: I1009 01:26:18.298603 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 -d 10.254.0.0/16 -j RETURN Oct 9 01:26:18.300839 flannel-wrapper[1840]: I1009 01:26:18.300719 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully Oct 9 01:26:18.303292 flannel-wrapper[1840]: I1009 01:26:18.303041 1 iptables.go:160] Adding iptables rule: ! -s 10.254.0.0/16 -d 10.254.95.0/24 -j RETURN Oct 9 01:26:18.305706 flannel-wrapper[1840]: I1009 01:26:18.305440 1 iptables.go:160] Adding iptables rule: ! -s 10.254.0.0/16 -d 10.254.0.0/16 -j MASQUERADE --random-fully Oct 9 01:26:18.315457 sh[2004]: Error response from daemon: No such container: flannel-docker-opts Oct 9 01:26:18.332166 sh[2017]: Error response from daemon: No such container: flannel-docker-opts Oct 9 01:26:19.313337 systemd-resolved[1246]: Clock change detected. Flushing caches. Oct 9 01:26:19.313539 systemd-timesyncd[1270]: Contacted time server 51.75.17.219:123 (2.flatcar.pool.ntp.org). Oct 9 01:26:19.313585 systemd-timesyncd[1270]: Initial clock synchronization to Wed 2024-10-09 01:26:19.313267 UTC. Oct 9 01:26:19.395185 containerd[1375]: time="2024-10-09T01:26:19.394436214Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:26:19.395185 containerd[1375]: time="2024-10-09T01:26:19.394545509Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:26:19.395185 containerd[1375]: time="2024-10-09T01:26:19.394573291Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:19.395185 containerd[1375]: time="2024-10-09T01:26:19.394694449Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:19.421789 systemd[1]: Started docker-0f41e316ce44353cb64e4816d021be02432bb4b1e95977eef5cc028d68471292.scope - libcontainer container 0f41e316ce44353cb64e4816d021be02432bb4b1e95977eef5cc028d68471292. Oct 9 01:26:19.465710 systemd[1]: docker-0f41e316ce44353cb64e4816d021be02432bb4b1e95977eef5cc028d68471292.scope: Deactivated successfully. Oct 9 01:26:19.480966 dockerd[1472]: time="2024-10-09T01:26:19.480817362Z" level=info msg="ignoring event" container=0f41e316ce44353cb64e4816d021be02432bb4b1e95977eef5cc028d68471292 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 9 01:26:19.482836 containerd[1375]: time="2024-10-09T01:26:19.482062537Z" level=info msg="shim disconnected" id=0f41e316ce44353cb64e4816d021be02432bb4b1e95977eef5cc028d68471292 namespace=moby Oct 9 01:26:19.482836 containerd[1375]: time="2024-10-09T01:26:19.482131667Z" level=warning msg="cleaning up after shim disconnected" id=0f41e316ce44353cb64e4816d021be02432bb4b1e95977eef5cc028d68471292 namespace=moby Oct 9 01:26:19.482836 containerd[1375]: time="2024-10-09T01:26:19.482142447Z" level=info msg="cleaning up dead shim" namespace=moby Oct 9 01:26:19.525701 dockerd[1472]: time="2024-10-09T01:26:19.525634396Z" level=info msg="Processing signal 'terminated'" Oct 9 01:26:19.525833 systemd[1]: Stopping docker.service - Docker Application Container Engine... Oct 9 01:26:19.535553 flannel-wrapper[1840]: I1009 01:26:19.534930 1 main.go:376] shutdownHandler sent cancel signal... Oct 9 01:26:19.537618 flannel-wrapper[1840]: I1009 01:26:19.535695 1 watch.go:40] context canceled, close receiver chan Oct 9 01:26:19.537618 flannel-wrapper[1840]: I1009 01:26:19.535711 1 vxlan_network.go:74] evts chan closed Oct 9 01:26:19.537618 flannel-wrapper[1840]: I1009 01:26:19.535719 1 watch.go:174] context canceled, close receiver chan Oct 9 01:26:19.537618 flannel-wrapper[1840]: I1009 01:26:19.535757 1 main.go:432] Stopped monitoring lease Oct 9 01:26:19.537618 flannel-wrapper[1840]: I1009 01:26:19.535765 1 main.go:345] Waiting for all goroutines to exit Oct 9 01:26:19.537618 flannel-wrapper[1840]: I1009 01:26:19.535773 1 main.go:348] Exiting cleanly... Oct 9 01:26:19.539024 systemd[1]: docker-cf780805f7714e6cad26a75d6d41593a2308bea9eaf2cd2810306602f938074b.scope: Deactivated successfully. Oct 9 01:26:19.541209 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.540Z","caller":"osutil/interrupt_unix.go:64","msg":"received signal; shutting down","signal":"terminated"} Oct 9 01:26:19.541209 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.540Z","caller":"embed/etcd.go:367","msg":"closing etcd server","name":"74d701e3111143cfb89affb596787429","data-dir":"/var/lib/etcd","advertise-peer-urls":["http://172.24.4.107:2380"],"advertise-client-urls":["http://172.24.4.107:2379"]} Oct 9 01:26:19.542884 etcd-wrapper[1675]: WARNING: 2024/10/09 01:26:19 [core] grpc: addrConn.createTransport failed to connect to {0.0.0.0:2379 0.0.0.0:2379 0 }. Err: connection error: desc = "transport: Error while dialing dial tcp 0.0.0.0:2379: connect: connection refused". Reconnecting... Oct 9 01:26:19.542884 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.542Z","caller":"etcdserver/server.go:1429","msg":"skipped leadership transfer; local server is not leader","local-member-id":"762ef2008432947e","current-leader-member-id":"7f1b43484c2b49f"} Oct 9 01:26:19.543590 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.542Z","caller":"rafthttp/peer.go:330","msg":"stopping remote peer","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.545695 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.544Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.545695 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.544Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.555803 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.555Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.555803 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.555Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.555803 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.555Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.557841 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.555Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f","error":"context canceled"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.555Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"7f1b43484c2b49f","error":"failed to read 7f1b43484c2b49f on stream MsgApp v2 (context canceled)"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.556Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.556Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f","error":"context canceled"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.556Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.556Z","caller":"rafthttp/peer.go:335","msg":"stopped remote peer","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.556Z","caller":"rafthttp/peer.go:330","msg":"stopping remote peer","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.557Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.558170 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.557Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.559230 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.558Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.559230 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.558Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.559595 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.558Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.560097 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.559Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0","error":"context canceled"} Oct 9 01:26:19.560097 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.559Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"5e1f14aae1bebcd0","error":"failed to read 5e1f14aae1bebcd0 on stream MsgApp v2 (context canceled)"} Oct 9 01:26:19.560097 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.559Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.561112 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.559Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0","error":"context canceled"} Oct 9 01:26:19.561112 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.559Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.561112 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.559Z","caller":"rafthttp/peer.go:335","msg":"stopped remote peer","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:19.561112 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.560Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"762ef2008432947e","remote-peer-id-stream-handler":"762ef2008432947e","remote-peer-id-from":"7f1b43484c2b49f","cluster-id":"d11d67a465f255f4"} Oct 9 01:26:19.562226 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.560Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"762ef2008432947e","remote-peer-id-stream-handler":"762ef2008432947e","remote-peer-id-from":"7f1b43484c2b49f","cluster-id":"d11d67a465f255f4"} Oct 9 01:26:19.562226 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.561Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"762ef2008432947e","remote-peer-id-stream-handler":"762ef2008432947e","remote-peer-id-from":"5e1f14aae1bebcd0","cluster-id":"d11d67a465f255f4"} Oct 9 01:26:19.562226 etcd-wrapper[1675]: {"level":"warn","ts":"2024-10-09T01:26:19.561Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"762ef2008432947e","remote-peer-id-stream-handler":"762ef2008432947e","remote-peer-id-from":"5e1f14aae1bebcd0","cluster-id":"d11d67a465f255f4"} Oct 9 01:26:19.563515 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:19.562Z","caller":"embed/etcd.go:562","msg":"stopping serving peer traffic","address":"172.24.4.107:2380"} Oct 9 01:26:19.576511 dockerd[1472]: time="2024-10-09T01:26:19.576444972Z" level=info msg="ignoring event" container=cf780805f7714e6cad26a75d6d41593a2308bea9eaf2cd2810306602f938074b module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 9 01:26:19.577255 containerd[1375]: time="2024-10-09T01:26:19.577185971Z" level=info msg="shim disconnected" id=cf780805f7714e6cad26a75d6d41593a2308bea9eaf2cd2810306602f938074b namespace=moby Oct 9 01:26:19.577322 containerd[1375]: time="2024-10-09T01:26:19.577254600Z" level=warning msg="cleaning up after shim disconnected" id=cf780805f7714e6cad26a75d6d41593a2308bea9eaf2cd2810306602f938074b namespace=moby Oct 9 01:26:19.577322 containerd[1375]: time="2024-10-09T01:26:19.577265601Z" level=info msg="cleaning up dead shim" namespace=moby Oct 9 01:26:20.100198 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:20.099Z","caller":"embed/etcd.go:567","msg":"stopped serving peer traffic","address":"172.24.4.107:2380"} Oct 9 01:26:20.100198 etcd-wrapper[1675]: {"level":"info","ts":"2024-10-09T01:26:20.099Z","caller":"embed/etcd.go:369","msg":"closed etcd server","name":"74d701e3111143cfb89affb596787429","data-dir":"/var/lib/etcd","advertise-peer-urls":["http://172.24.4.107:2380"],"advertise-client-urls":["http://172.24.4.107:2379"]} Oct 9 01:26:20.103450 systemd[1]: docker-610ce23d2786b85b5aa95b5073249efb0182f104ba3008e1d25fef0a24b0e025.scope: Deactivated successfully. Oct 9 01:26:20.136220 containerd[1375]: time="2024-10-09T01:26:20.135894199Z" level=info msg="shim disconnected" id=610ce23d2786b85b5aa95b5073249efb0182f104ba3008e1d25fef0a24b0e025 namespace=moby Oct 9 01:26:20.136220 containerd[1375]: time="2024-10-09T01:26:20.135986602Z" level=warning msg="cleaning up after shim disconnected" id=610ce23d2786b85b5aa95b5073249efb0182f104ba3008e1d25fef0a24b0e025 namespace=moby Oct 9 01:26:20.136220 containerd[1375]: time="2024-10-09T01:26:20.136011058Z" level=info msg="cleaning up dead shim" namespace=moby Oct 9 01:26:20.136999 dockerd[1472]: time="2024-10-09T01:26:20.136002993Z" level=info msg="ignoring event" container=610ce23d2786b85b5aa95b5073249efb0182f104ba3008e1d25fef0a24b0e025 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 9 01:26:20.236737 systemd-networkd[1304]: flannel.1: Gained IPv6LL Oct 9 01:26:20.302276 systemd[1]: var-lib-docker-overlay2-cda1dcfb5588e97b5355731930fa88286ad54095e5a622d5870e32a2ab3499de-merged.mount: Deactivated successfully. Oct 9 01:26:20.302525 systemd[1]: var-lib-docker-overlay2-e6c2b01805194ecb36fdf8c8f8a4784c84bf411d7db525f2045eca6f7c0c7706-merged.mount: Deactivated successfully. Oct 9 01:26:20.479549 systemd[1]: var-lib-docker-overlay2-d860f8ed4093ab9a3616becf23dfa7b507986feaee80d0bb0b8482239a00b71c-merged.mount: Deactivated successfully. Oct 9 01:26:20.565727 dockerd[1472]: time="2024-10-09T01:26:20.565290551Z" level=info msg="stopping event stream following graceful shutdown" error="" module=libcontainerd namespace=moby Oct 9 01:26:20.566832 dockerd[1472]: time="2024-10-09T01:26:20.566755619Z" level=info msg="Daemon shutdown complete" Oct 9 01:26:20.602198 systemd[1]: docker.service: Deactivated successfully. Oct 9 01:26:20.603186 systemd[1]: Stopped docker.service - Docker Application Container Engine. Oct 9 01:26:20.603581 systemd[1]: docker.service: Consumed 6.329s CPU time. Oct 9 01:26:20.605209 systemd[1]: docker.socket: Deactivated successfully. Oct 9 01:26:20.606983 systemd[1]: Closed docker.socket - Docker Socket for the API. Oct 9 01:26:20.607084 systemd[1]: Stopping docker.socket - Docker Socket for the API... Oct 9 01:26:20.608941 docker[2160]: Cannot connect to the Docker daemon at unix:///var/run/docker.sock. Is the docker daemon running? Oct 9 01:26:20.612333 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 9 01:26:20.617855 systemd[1]: flanneld.service: Control process exited, code=exited, status=1/FAILURE Oct 9 01:26:20.619772 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 9 01:26:20.629217 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 9 01:26:20.636531 (dockerd)[2182]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS Oct 9 01:26:20.675025 dockerd[2182]: time="2024-10-09T01:26:20.674955087Z" level=info msg="Starting up" Oct 9 01:26:20.703389 systemd[1]: var-lib-docker-overlay2-check\x2doverlayfs\x2dsupport34809019-merged.mount: Deactivated successfully. Oct 9 01:26:20.712621 dockerd[2182]: time="2024-10-09T01:26:20.712579652Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Oct 9 01:26:20.735032 dockerd[2182]: time="2024-10-09T01:26:20.734863375Z" level=info msg="Loading containers: start." Oct 9 01:26:21.162384 dockerd[2182]: time="2024-10-09T01:26:21.162240371Z" level=info msg="Loading containers: done." Oct 9 01:26:21.180328 dockerd[2182]: time="2024-10-09T01:26:21.180264395Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Oct 9 01:26:21.180552 dockerd[2182]: time="2024-10-09T01:26:21.180395501Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Oct 9 01:26:21.180552 dockerd[2182]: time="2024-10-09T01:26:21.180448861Z" level=info msg="Daemon has completed initialization" Oct 9 01:26:21.207950 dockerd[2182]: time="2024-10-09T01:26:21.207775171Z" level=info msg="API listen on /run/docker.sock" Oct 9 01:26:21.208126 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 9 01:26:21.209334 docker[2170]: etcd-member Oct 9 01:26:21.210932 sh[1997]: Restarted Docker to apply Flannel options Oct 9 01:26:21.211057 sh[1984]: + break Oct 9 01:26:21.211057 sh[1984]: + '[' 1 '!=' fail ']' Oct 9 01:26:21.211855 systemd[1]: Finished flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). Oct 9 01:26:21.212102 systemd[1]: Startup finished in 1.221s (kernel) + 12.015s (initrd) + 28.512s (userspace) = 41.749s. Oct 9 01:26:21.222107 docker[2180]: flannel Oct 9 01:26:21.223921 systemd[1]: flanneld.service: Failed with result 'exit-code'. Oct 9 01:26:21.246653 docker[2374]: etcd-member Oct 9 01:26:21.248496 systemd[1]: etcd-member.service: Deactivated successfully. Oct 9 01:26:27.615126 systemd[1]: Started sshd@5-172.24.4.107:22-172.24.4.1:42410.service - OpenSSH per-connection server daemon (172.24.4.1:42410). Oct 9 01:26:28.954338 sshd[2388]: Accepted publickey for core from 172.24.4.1 port 42410 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:28.957113 sshd[2388]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:28.967951 systemd-logind[1361]: New session 8 of user core. Oct 9 01:26:28.975761 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 9 01:26:29.602939 sshd[2388]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:29.617293 systemd[1]: sshd@5-172.24.4.107:22-172.24.4.1:42410.service: Deactivated successfully. Oct 9 01:26:29.620855 systemd[1]: session-8.scope: Deactivated successfully. Oct 9 01:26:29.626830 systemd-logind[1361]: Session 8 logged out. Waiting for processes to exit. Oct 9 01:26:29.633662 systemd[1]: Started sshd@6-172.24.4.107:22-172.24.4.1:42424.service - OpenSSH per-connection server daemon (172.24.4.1:42424). Oct 9 01:26:29.637442 systemd-logind[1361]: Removed session 8. Oct 9 01:26:30.897974 sshd[2395]: Accepted publickey for core from 172.24.4.1 port 42424 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:30.901740 sshd[2395]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:30.913221 systemd-logind[1361]: New session 9 of user core. Oct 9 01:26:30.926806 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 9 01:26:31.475237 systemd[1]: flanneld.service: Scheduled restart job, restart counter is at 1. Oct 9 01:26:31.477947 systemd[1]: etcd-member.service: Scheduled restart job, restart counter is at 1. Oct 9 01:26:31.488295 systemd[1]: Stopping flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... Oct 9 01:26:31.533170 docker[2399]: flannel-docker-opts Oct 9 01:26:31.547242 sshd[2395]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:31.563143 systemd[1]: sshd@6-172.24.4.107:22-172.24.4.1:42424.service: Deactivated successfully. Oct 9 01:26:31.567902 systemd[1]: session-9.scope: Deactivated successfully. Oct 9 01:26:31.572806 systemd-logind[1361]: Session 9 logged out. Waiting for processes to exit. Oct 9 01:26:31.580094 systemd[1]: Started sshd@7-172.24.4.107:22-172.24.4.1:42440.service - OpenSSH per-connection server daemon (172.24.4.1:42440). Oct 9 01:26:31.588030 systemd-logind[1361]: Removed session 9. Oct 9 01:26:31.613164 docker[2407]: flannel-docker-opts Oct 9 01:26:31.616004 systemd[1]: flannel-docker-opts.service: Deactivated successfully. Oct 9 01:26:31.616429 systemd[1]: Stopped flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). Oct 9 01:26:31.617418 systemd[1]: Stopped flanneld.service - flannel - Network fabric for containers (System Application Container). Oct 9 01:26:31.633144 (-wrapper)[2419]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Oct 9 01:26:31.634290 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Oct 9 01:26:31.672603 etcd-wrapper[2423]: Error response from daemon: No such container: etcd-member Oct 9 01:26:31.704064 etcd-wrapper[2431]: Error response from daemon: No such container: etcd-member Oct 9 01:26:31.766632 systemd[1]: var-lib-docker-overlay2-1494e20472b2f229ec86734c77dd18966fa99099462bd44a7e77ef73474d0254\x2dinit-merged.mount: Deactivated successfully. Oct 9 01:26:31.863053 containerd[1375]: time="2024-10-09T01:26:31.862913440Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:26:31.863053 containerd[1375]: time="2024-10-09T01:26:31.862977440Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:26:31.863053 containerd[1375]: time="2024-10-09T01:26:31.863012486Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:31.865111 containerd[1375]: time="2024-10-09T01:26:31.863090112Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:31.891835 systemd[1]: Started docker-9b24ac7c49ef84d6e4847bea3586eff0fb0e54e235122fd99e3cfa4dae503c34.scope - libcontainer container 9b24ac7c49ef84d6e4847bea3586eff0fb0e54e235122fd99e3cfa4dae503c34. Oct 9 01:26:31.956555 etcd-wrapper[2449]: {"level":"info","ts":1728437191.9561737,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"info","ts":1728437191.9562838,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"74d701e3111143cfb89affb596787429"} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"warn","ts":1728437191.9563596,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"warn","ts":1728437191.9563699,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"warn","ts":1728437191.956377,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"warn","ts":1728437191.9564843,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.956Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.24.4.107:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.24.4.107:2380","--advertise-client-urls=http://172.24.4.107:2379","--discovery=https://discovery.etcd.io/9439d70a93aa7988c83f567df2aa5ea4","--enable-v2=true"]} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.956Z","caller":"etcdmain/etcd.go:115","msg":"server has been already initialized","data-dir":"/var/lib/etcd","dir-type":"member"} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.956Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.24.4.107:2380"]} Oct 9 01:26:31.957251 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.956Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Oct 9 01:26:31.957790 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.956Z","caller":"embed/etcd.go:307","msg":"starting an etcd server","etcd-version":"3.5.0","git-sha":"946a5a6f2","go-version":"go1.16.3","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":true,"name":"74d701e3111143cfb89affb596787429","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://172.24.4.107:2380"],"listen-peer-urls":["http://172.24.4.107:2380"],"advertise-client-urls":["http://172.24.4.107:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"","initial-cluster-state":"new","initial-cluster-token":"","quota-size-bytes":2147483648,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"https://discovery.etcd.io/9439d70a93aa7988c83f567df2aa5ea4","discovery-proxy":"","downgrade-check-interval":"5s"} Oct 9 01:26:31.958081 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.957Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"205.225µs"} Oct 9 01:26:31.958081 etcd-wrapper[2449]: {"level":"warn","ts":"2024-10-09T01:26:31.957Z","caller":"etcdserver/server.go:480","msg":"discovery token is ignored since cluster already initialized; valid logs are found","wal-dir":"/var/lib/etcd/member/wal"} Oct 9 01:26:31.958081 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.957Z","caller":"etcdserver/server.go:526","msg":"No snapshot found. Recovering WAL from scratch!"} Oct 9 01:26:31.959727 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.959Z","caller":"etcdserver/raft.go:483","msg":"restarting local member","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","commit-index":30} Oct 9 01:26:31.960300 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.960Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=()"} Oct 9 01:26:31.960563 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.960Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e became follower at term 2"} Oct 9 01:26:31.960814 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.960Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 762ef2008432947e [peers: [], term: 2, commit: 30, applied: 0, lastindex: 30, lastterm: 2]"} Oct 9 01:26:31.961252 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.961Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Oct 9 01:26:31.961510 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.961Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","recovered-remote-peer-id":"5e1f14aae1bebcd0","recovered-remote-peer-urls":["http://172.24.4.65:2380"]} Oct 9 01:26:31.961745 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.961Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","recovered-remote-peer-id":"762ef2008432947e","recovered-remote-peer-urls":["http://172.24.4.107:2380"]} Oct 9 01:26:31.962044 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.961Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","recovered-remote-peer-id":"7f1b43484c2b49f","recovered-remote-peer-urls":["http://172.24.4.97:2380"]} Oct 9 01:26:31.962389 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.961Z","caller":"membership/cluster.go:285","msg":"set cluster version from store","cluster-version":"3.5"} Oct 9 01:26:31.963180 etcd-wrapper[2449]: {"level":"warn","ts":"2024-10-09T01:26:31.963Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Oct 9 01:26:31.964584 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.964Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Oct 9 01:26:31.965325 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.965Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Oct 9 01:26:31.965961 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.965Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.965961 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.965Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.966629 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.966Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.969451 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.968Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.969451 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.968Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.969451 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.968Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.969451 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.968Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f","remote-peer-urls":["http://172.24.4.97:2380"]} Oct 9 01:26:31.969451 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.968Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.969451 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.968Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.969451 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.969Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.970385 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.970Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.972137 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.971Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.972807 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.972Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.973017 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.972Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.973017 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.972Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:31.973248 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.973Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.973511 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.973Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.973694 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.973Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.973884 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.973Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0","remote-peer-urls":["http://172.24.4.65:2380"]} Oct 9 01:26:31.974151 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.974Z","caller":"etcdserver/server.go:834","msg":"starting etcd server","local-member-id":"762ef2008432947e","local-server-version":"3.5.0","cluster-id":"d11d67a465f255f4","cluster-version":"3.5"} Oct 9 01:26:31.974554 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.974Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} Oct 9 01:26:31.974869 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455)"} Oct 9 01:26:31.975287 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.974Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","added-peer-id":"7f1b43484c2b49f","added-peer-peer-urls":["http://172.24.4.97:2380"]} Oct 9 01:26:31.975287 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455 6782162288007625936)"} Oct 9 01:26:31.975287 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.974Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","added-peer-id":"5e1f14aae1bebcd0","added-peer-peer-urls":["http://172.24.4.65:2380"]} Oct 9 01:26:31.975287 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e switched to configuration voters=(572436765311415455 6782162288007625936 8516010029436015742)"} Oct 9 01:26:31.975287 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.975Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","added-peer-id":"762ef2008432947e","added-peer-peer-urls":["http://172.24.4.107:2380"]} Oct 9 01:26:31.975287 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.975Z","caller":"membership/cluster.go:523","msg":"updated cluster version","cluster-id":"d11d67a465f255f4","local-member-id":"762ef2008432947e","from":"3.5","to":"3.5"} Oct 9 01:26:31.977392 etcd-wrapper[2449]: {"level":"warn","ts":"2024-10-09T01:26:31.977Z","caller":"embed/etcd.go:700","msg":"Flag `enable-v2` is deprecated and will get removed in etcd 3.6."} Oct 9 01:26:31.977780 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.977Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"762ef2008432947e","initial-advertise-peer-urls":["http://172.24.4.107:2380"],"listen-peer-urls":["http://172.24.4.107:2380"],"advertise-client-urls":["http://172.24.4.107:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Oct 9 01:26:31.978040 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.977Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.24.4.107:2380"} Oct 9 01:26:31.978327 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.978Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.978604 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.978Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.978813 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.978Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:31.979002 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:31.978Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.24.4.107:2380"} Oct 9 01:26:32.025273 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.024Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"762ef2008432947e","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} Oct 9 01:26:32.029016 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.027Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"5e1f14aae1bebcd0","stream-type":"stream MsgApp v2"} Oct 9 01:26:32.029403 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.028Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:32.029403 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.028Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"5e1f14aae1bebcd0","stream-type":"stream Message"} Oct 9 01:26:32.029403 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.029Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"5e1f14aae1bebcd0"} Oct 9 01:26:32.057855 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.057Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"7f1b43484c2b49f","stream-type":"stream Message"} Oct 9 01:26:32.057855 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.057Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:32.059794 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.058Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"762ef2008432947e","to":"7f1b43484c2b49f","stream-type":"stream MsgApp v2"} Oct 9 01:26:32.059794 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.058Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"762ef2008432947e","remote-peer-id":"7f1b43484c2b49f"} Oct 9 01:26:32.263720 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e is starting a new election at term 2"} Oct 9 01:26:32.263720 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e became pre-candidate at term 2"} Oct 9 01:26:32.263720 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e received MsgPreVoteResp from 762ef2008432947e at term 2"} Oct 9 01:26:32.263720 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [logterm: 2, index: 30] sent MsgPreVote request to 7f1b43484c2b49f at term 2"} Oct 9 01:26:32.263720 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [logterm: 2, index: 30] sent MsgPreVote request to 5e1f14aae1bebcd0 at term 2"} Oct 9 01:26:32.264995 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e received MsgPreVoteResp from 5e1f14aae1bebcd0 at term 2"} Oct 9 01:26:32.265546 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e has received 2 MsgPreVoteResp votes and 0 vote rejections"} Oct 9 01:26:32.265989 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e became candidate at term 3"} Oct 9 01:26:32.265989 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e received MsgVoteResp from 762ef2008432947e at term 3"} Oct 9 01:26:32.265989 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [logterm: 2, index: 30] sent MsgVote request to 7f1b43484c2b49f at term 3"} Oct 9 01:26:32.265989 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [logterm: 2, index: 30] sent MsgVote request to 5e1f14aae1bebcd0 at term 3"} Oct 9 01:26:32.265989 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e [term: 3] ignored a MsgPreVoteResp message with lower term from 7f1b43484c2b49f [term: 2]"} Oct 9 01:26:32.276806 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e received MsgVoteResp from 5e1f14aae1bebcd0 at term 3"} Oct 9 01:26:32.276806 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e has received 2 MsgVoteResp votes and 0 vote rejections"} Oct 9 01:26:32.276806 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"762ef2008432947e became leader at term 3"} Oct 9 01:26:32.276806 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 762ef2008432947e elected leader 762ef2008432947e at term 3"} Oct 9 01:26:32.285615 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.283Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"762ef2008432947e","local-member-attributes":"{Name:74d701e3111143cfb89affb596787429 ClientURLs:[http://172.24.4.107:2379]}","request-path":"/0/members/762ef2008432947e/attributes","cluster-id":"d11d67a465f255f4","publish-timeout":"7s"} Oct 9 01:26:32.285615 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.283Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Oct 9 01:26:32.285615 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.284Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Oct 9 01:26:32.285615 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.284Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Oct 9 01:26:32.286302 systemd[1]: Started etcd-member.service - etcd (System Application Container). Oct 9 01:26:32.290206 etcd-wrapper[2449]: {"level":"info","ts":"2024-10-09T01:26:32.286Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Oct 9 01:26:32.296971 systemd[1]: Starting flanneld.service - flannel - Network fabric for containers (System Application Container)... Oct 9 01:26:32.381110 etcdctl[2507]: { "Network": "10.254.0.0/16", "Backend": {"Type": "vxlan"} } Oct 9 01:26:32.439885 flannel-wrapper[2517]: Error response from daemon: No such container: flannel Oct 9 01:26:32.470493 flannel-wrapper[2525]: Error response from daemon: No such container: flannel Oct 9 01:26:32.652287 containerd[1375]: time="2024-10-09T01:26:32.650420229Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:26:32.652287 containerd[1375]: time="2024-10-09T01:26:32.650636044Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:26:32.652287 containerd[1375]: time="2024-10-09T01:26:32.650706316Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:32.652287 containerd[1375]: time="2024-10-09T01:26:32.650907052Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:32.694820 systemd[1]: Started docker-41bdf1136dd35d29f6b266a9cc38772c979f1b351dd6bdc5523a8b9233cb4a8f.scope - libcontainer container 41bdf1136dd35d29f6b266a9cc38772c979f1b351dd6bdc5523a8b9233cb4a8f. Oct 9 01:26:32.784753 flannel-wrapper[2541]: I1009 01:26:32.784267 1 main.go:520] Determining IP address of default interface Oct 9 01:26:32.786175 flannel-wrapper[2541]: I1009 01:26:32.785977 1 main.go:533] Using interface with name eth0 and address 172.24.4.107 Oct 9 01:26:32.786175 flannel-wrapper[2541]: I1009 01:26:32.786017 1 main.go:550] Defaulting external address to interface address (172.24.4.107) Oct 9 01:26:32.786507 flannel-wrapper[2541]: I1009 01:26:32.786320 1 main.go:254] Created subnet manager: Etcd Local Manager with Previous Subnet: 10.254.95.0/24 Oct 9 01:26:32.786507 flannel-wrapper[2541]: I1009 01:26:32.786334 1 main.go:257] Installing signal handlers Oct 9 01:26:32.789543 flannel-wrapper[2541]: I1009 01:26:32.788942 1 main.go:392] Found network config - Backend type: vxlan Oct 9 01:26:32.789543 flannel-wrapper[2541]: I1009 01:26:32.788990 1 vxlan.go:123] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false Oct 9 01:26:32.794784 flannel-wrapper[2541]: I1009 01:26:32.793858 1 local_manager.go:147] Found lease (10.254.95.0/24) for current IP (172.24.4.107), reusing Oct 9 01:26:32.797439 flannel-wrapper[2541]: I1009 01:26:32.796767 1 main.go:307] Setting up masking rules Oct 9 01:26:32.812950 flannel-wrapper[2541]: I1009 01:26:32.812551 1 main.go:315] Changing default FORWARD chain policy to ACCEPT Oct 9 01:26:32.813702 flannel-wrapper[2541]: I1009 01:26:32.813114 1 main.go:323] Wrote subnet file to /run/flannel/subnet.env Oct 9 01:26:32.813702 flannel-wrapper[2541]: I1009 01:26:32.813125 1 main.go:327] Running backend. Oct 9 01:26:32.813953 systemd[1]: Started flanneld.service - flannel - Network fabric for containers (System Application Container). Oct 9 01:26:32.818945 flannel-wrapper[2541]: I1009 01:26:32.817053 1 vxlan_network.go:59] watching for new subnet leases Oct 9 01:26:32.820871 systemd[1]: Starting flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... Oct 9 01:26:32.825038 flannel-wrapper[2541]: I1009 01:26:32.824405 1 main.go:439] Waiting for 22h59m59.970210881s to renew lease Oct 9 01:26:32.829194 sh[2625]: + for try in 1 2 3 4 5 6 Oct 9 01:26:32.829194 sh[2625]: + /usr/lib/coreos/flannel-wrapper -d /run/flannel/flannel_docker_opts.env -i Oct 9 01:26:32.849819 sh[2632]: Error response from daemon: No such container: flannel-docker-opts Oct 9 01:26:32.872310 sh[2640]: Error response from daemon: No such container: flannel-docker-opts Oct 9 01:26:33.011205 containerd[1375]: time="2024-10-09T01:26:33.009995663Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:26:33.011205 containerd[1375]: time="2024-10-09T01:26:33.010120857Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:26:33.011739 containerd[1375]: time="2024-10-09T01:26:33.010171983Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:33.011739 containerd[1375]: time="2024-10-09T01:26:33.010327765Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:26:33.043891 systemd[1]: Started docker-1de7cd69d361d9d8572114692b16b16c555f20a58a6e5a1a9c2c40a3811e7641.scope - libcontainer container 1de7cd69d361d9d8572114692b16b16c555f20a58a6e5a1a9c2c40a3811e7641. Oct 9 01:26:33.073528 sshd[2415]: Accepted publickey for core from 172.24.4.1 port 42440 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:33.076334 sshd[2415]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:33.083040 systemd-logind[1361]: New session 10 of user core. Oct 9 01:26:33.089752 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 9 01:26:33.100494 systemd[1]: docker-1de7cd69d361d9d8572114692b16b16c555f20a58a6e5a1a9c2c40a3811e7641.scope: Deactivated successfully. Oct 9 01:26:33.121285 dockerd[2182]: time="2024-10-09T01:26:33.121177504Z" level=info msg="ignoring event" container=1de7cd69d361d9d8572114692b16b16c555f20a58a6e5a1a9c2c40a3811e7641 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 9 01:26:33.121842 containerd[1375]: time="2024-10-09T01:26:33.121760868Z" level=info msg="shim disconnected" id=1de7cd69d361d9d8572114692b16b16c555f20a58a6e5a1a9c2c40a3811e7641 namespace=moby Oct 9 01:26:33.121842 containerd[1375]: time="2024-10-09T01:26:33.121836420Z" level=warning msg="cleaning up after shim disconnected" id=1de7cd69d361d9d8572114692b16b16c555f20a58a6e5a1a9c2c40a3811e7641 namespace=moby Oct 9 01:26:33.121943 containerd[1375]: time="2024-10-09T01:26:33.121847250Z" level=info msg="cleaning up dead shim" namespace=moby Oct 9 01:26:33.135681 containerd[1375]: time="2024-10-09T01:26:33.135532256Z" level=warning msg="cleanup warnings time=\"2024-10-09T01:26:33Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=moby Oct 9 01:26:33.157137 sh[2625]: + break Oct 9 01:26:33.157137 sh[2625]: + '[' 1 '!=' fail ']' Oct 9 01:26:33.157880 systemd[1]: Finished flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). Oct 9 01:26:33.703886 sshd[2415]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:33.716672 systemd[1]: sshd@7-172.24.4.107:22-172.24.4.1:42440.service: Deactivated successfully. Oct 9 01:26:33.721768 systemd[1]: session-10.scope: Deactivated successfully. Oct 9 01:26:33.724172 systemd-logind[1361]: Session 10 logged out. Waiting for processes to exit. Oct 9 01:26:33.738049 systemd[1]: Started sshd@8-172.24.4.107:22-172.24.4.1:42448.service - OpenSSH per-connection server daemon (172.24.4.1:42448). Oct 9 01:26:33.741844 systemd-logind[1361]: Removed session 10. Oct 9 01:26:33.756117 systemd[1]: var-lib-docker-overlay2-9a4fba9a8222d73caf4de9f7b8592b64579ce71ab86b2679767a08fdcbb24425-merged.mount: Deactivated successfully. Oct 9 01:26:34.926760 sshd[2737]: Accepted publickey for core from 172.24.4.1 port 42448 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:34.929148 sshd[2737]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:34.937983 systemd-logind[1361]: New session 11 of user core. Oct 9 01:26:34.949701 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 9 01:26:35.655114 sudo[2740]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 9 01:26:35.656142 sudo[2740]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:26:35.684730 sudo[2740]: pam_unix(sudo:session): session closed for user root Oct 9 01:26:35.877809 sshd[2737]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:35.887666 systemd[1]: sshd@8-172.24.4.107:22-172.24.4.1:42448.service: Deactivated successfully. Oct 9 01:26:35.890797 systemd[1]: session-11.scope: Deactivated successfully. Oct 9 01:26:35.892599 systemd-logind[1361]: Session 11 logged out. Waiting for processes to exit. Oct 9 01:26:35.903049 systemd[1]: Started sshd@9-172.24.4.107:22-172.24.4.1:52706.service - OpenSSH per-connection server daemon (172.24.4.1:52706). Oct 9 01:26:35.905277 systemd-logind[1361]: Removed session 11. Oct 9 01:26:37.064609 sshd[2745]: Accepted publickey for core from 172.24.4.1 port 52706 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:37.067404 sshd[2745]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:37.077102 systemd-logind[1361]: New session 12 of user core. Oct 9 01:26:37.087853 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 9 01:26:37.480262 sudo[2749]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 9 01:26:37.480989 sudo[2749]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:26:37.488994 sudo[2749]: pam_unix(sudo:session): session closed for user root Oct 9 01:26:37.501102 sudo[2748]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 9 01:26:37.501831 sudo[2748]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:26:37.526140 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 9 01:26:37.605550 augenrules[2771]: No rules Oct 9 01:26:37.607955 systemd[1]: audit-rules.service: Deactivated successfully. Oct 9 01:26:37.608574 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 9 01:26:37.611444 sudo[2748]: pam_unix(sudo:session): session closed for user root Oct 9 01:26:37.846678 sshd[2745]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:37.852764 systemd[1]: sshd@9-172.24.4.107:22-172.24.4.1:52706.service: Deactivated successfully. Oct 9 01:26:37.856419 systemd[1]: session-12.scope: Deactivated successfully. Oct 9 01:26:37.859790 systemd-logind[1361]: Session 12 logged out. Waiting for processes to exit. Oct 9 01:26:37.861905 systemd-logind[1361]: Removed session 12. Oct 9 01:26:38.335054 systemd[1]: Started sshd@10-172.24.4.107:22-172.24.4.1:52708.service - OpenSSH per-connection server daemon (172.24.4.1:52708). Oct 9 01:26:39.535017 sshd[2786]: Accepted publickey for core from 172.24.4.1 port 52708 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:39.538258 sshd[2786]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:39.548822 systemd-logind[1361]: New session 13 of user core. Oct 9 01:26:39.559875 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 9 01:26:40.349766 sshd[2786]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:40.364878 systemd[1]: sshd@10-172.24.4.107:22-172.24.4.1:52708.service: Deactivated successfully. Oct 9 01:26:40.368679 systemd[1]: session-13.scope: Deactivated successfully. Oct 9 01:26:40.372447 systemd-logind[1361]: Session 13 logged out. Waiting for processes to exit. Oct 9 01:26:40.380115 systemd[1]: Started sshd@11-172.24.4.107:22-172.24.4.1:52722.service - OpenSSH per-connection server daemon (172.24.4.1:52722). Oct 9 01:26:40.382646 systemd-logind[1361]: Removed session 13. Oct 9 01:26:41.464087 update_engine[1364]: I20241009 01:26:41.463843 1364 update_attempter.cc:509] Updating boot flags... Oct 9 01:26:41.521279 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2807) Oct 9 01:26:41.594614 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2806) Oct 9 01:26:41.626636 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2806) Oct 9 01:26:41.797442 sshd[2798]: Accepted publickey for core from 172.24.4.1 port 52722 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:41.801304 sshd[2798]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:41.812305 systemd-logind[1361]: New session 14 of user core. Oct 9 01:26:41.817772 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 9 01:26:42.558118 sshd[2798]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:42.565338 systemd-logind[1361]: Session 14 logged out. Waiting for processes to exit. Oct 9 01:26:42.566036 systemd[1]: sshd@11-172.24.4.107:22-172.24.4.1:52722.service: Deactivated successfully. Oct 9 01:26:42.569321 systemd[1]: session-14.scope: Deactivated successfully. Oct 9 01:26:42.573075 systemd-logind[1361]: Removed session 14. Oct 9 01:26:44.708323 systemd[1]: Started sshd@12-172.24.4.107:22-172.24.4.1:45708.service - OpenSSH per-connection server daemon (172.24.4.1:45708). Oct 9 01:26:46.226442 sshd[2829]: Accepted publickey for core from 172.24.4.1 port 45708 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:26:46.228591 sshd[2829]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:26:46.236743 systemd-logind[1361]: New session 15 of user core. Oct 9 01:26:46.241701 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 9 01:26:56.178867 sshd[2829]: pam_unix(sshd:session): session closed for user core Oct 9 01:26:56.184722 systemd[1]: sshd@12-172.24.4.107:22-172.24.4.1:45708.service: Deactivated successfully. Oct 9 01:26:56.188883 systemd[1]: session-15.scope: Deactivated successfully. Oct 9 01:26:56.191824 systemd-logind[1361]: Session 15 logged out. Waiting for processes to exit. Oct 9 01:26:56.194211 systemd-logind[1361]: Removed session 15.